builder: fx-team_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-029 starttime: 1463055693.74 results: success (0) buildid: 20160512023005 builduid: ec6582345bb941c38462adc55d81c658 revision: c3ff04109a8a6e439be36ae44133b2ba68fc79f6 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-12 05:21:33.745243) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-12 05:21:33.745713) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-12 05:21:33.746049) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-12 05:21:33.762834) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 05:21:33.763191) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=0274524eba3bdaad075c2247000001d4-1463055161.483907-940509389 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-12 05:21:33-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 88.7M=0s 2016-05-12 05:21:33 (88.7 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.124574 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 05:21:33.908813) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 05:21:33.909093) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=0274524eba3bdaad075c2247000001d4-1463055161.483907-940509389 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020173 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 05:21:33.959155) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 05:21:33.959509) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev c3ff04109a8a6e439be36ae44133b2ba68fc79f6 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev c3ff04109a8a6e439be36ae44133b2ba68fc79f6 --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=0274524eba3bdaad075c2247000001d4-1463055161.483907-940509389 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-12 05:21:34,067 truncating revision to first 12 chars 2016-05-12 05:21:34,067 Setting DEBUG logging. 2016-05-12 05:21:34,068 attempt 1/10 2016-05-12 05:21:34,068 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/c3ff04109a8a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-12 05:21:34,627 unpacking tar archive at: fx-team-c3ff04109a8a/testing/mozharness/ program finished with exit code 0 elapsedTime=0.850318 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 05:21:34.830710) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-12 05:21:34.830997) ========= script_repo_revision: c3ff04109a8a6e439be36ae44133b2ba68fc79f6 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-12 05:21:34.831408) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-12 05:21:34.831694) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-12 05:21:34.841666) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 42 secs) (at 2016-05-12 05:21:34.842065) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=0274524eba3bdaad075c2247000001d4-1463055161.483907-940509389 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:21:35 INFO - MultiFileLogger online at 20160512 05:21:35 in /builds/slave/test 05:21:35 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 05:21:35 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:21:35 INFO - {'append_to_log': False, 05:21:35 INFO - 'base_work_dir': '/builds/slave/test', 05:21:35 INFO - 'blob_upload_branch': 'Fx-Team', 05:21:35 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:21:35 INFO - 'branch': 'Fx-Team', 05:21:35 INFO - 'buildbot_json_path': 'buildprops.json', 05:21:35 INFO - 'config_files': ('talos/linux_config.py',), 05:21:35 INFO - 'default_actions': ('clobber', 05:21:35 INFO - 'read-buildbot-config', 05:21:35 INFO - 'download-and-extract', 05:21:35 INFO - 'populate-webroot', 05:21:35 INFO - 'create-virtualenv', 05:21:35 INFO - 'install', 05:21:35 INFO - 'run-tests'), 05:21:35 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:21:35 INFO - 'download_minidump_stackwalk': True, 05:21:35 INFO - 'download_symbols': 'ondemand', 05:21:35 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:21:35 INFO - 'tooltool.py': '/tools/tooltool.py', 05:21:35 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:21:35 INFO - '/tools/misc-python/virtualenv.py')}, 05:21:35 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:21:35 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:21:35 INFO - 'installer_path': 'installer.exe', 05:21:35 INFO - 'log_level': 'info', 05:21:35 INFO - 'log_name': 'talos', 05:21:35 INFO - 'log_to_console': True, 05:21:35 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:21:35 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:21:35 INFO - 'opt_config_files': (), 05:21:35 INFO - 'pip_index': False, 05:21:35 INFO - 'sps_profile': False, 05:21:35 INFO - 'sps_profile_interval': 0, 05:21:35 INFO - 'suite': 'dromaeojs-e10s', 05:21:35 INFO - 'system_bits': '32', 05:21:35 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:21:35 INFO - 'title': 'talos-linux64-ix-029', 05:21:35 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:21:35 INFO - 'use_talos_json': True, 05:21:35 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:21:35 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:21:35 INFO - 'work_dir': 'build'} 05:21:35 INFO - ##### 05:21:35 INFO - ##### Running clobber step. 05:21:35 INFO - ##### 05:21:35 INFO - Running pre-action listener: _resource_record_pre_action 05:21:35 INFO - Running main action method: clobber 05:21:35 INFO - rmtree: /builds/slave/test/build 05:21:35 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:21:35 INFO - Running post-action listener: _resource_record_post_action 05:21:35 INFO - ##### 05:21:35 INFO - ##### Finished clobber step (success) 05:21:35 INFO - ##### 05:21:35 INFO - ##### 05:21:35 INFO - ##### Running read-buildbot-config step. 05:21:35 INFO - ##### 05:21:35 INFO - Running pre-action listener: _resource_record_pre_action 05:21:35 INFO - Running main action method: read_buildbot_config 05:21:35 INFO - Using buildbot properties: 05:21:35 INFO - { 05:21:35 INFO - "project": "", 05:21:35 INFO - "product": "firefox", 05:21:35 INFO - "script_repo_revision": "production", 05:21:35 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs-e10s", 05:21:35 INFO - "repository": "", 05:21:35 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs-e10s", 05:21:35 INFO - "buildid": "20160512023005", 05:21:35 INFO - "pgo_build": "True", 05:21:35 INFO - "basedir": "/builds/slave/test", 05:21:35 INFO - "buildnumber": 457, 05:21:35 INFO - "slavename": "talos-linux64-ix-029", 05:21:35 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 05:21:35 INFO - "platform": "ubuntu64_hw", 05:21:35 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 05:21:35 INFO - "branch": "fx-team", 05:21:35 INFO - "repo_path": "integration/fx-team", 05:21:35 INFO - "revision": "c3ff04109a8a6e439be36ae44133b2ba68fc79f6", 05:21:35 INFO - "stage_platform": "linux64-pgo", 05:21:35 INFO - "builduid": "ec6582345bb941c38462adc55d81c658", 05:21:35 INFO - "slavebuilddir": "test" 05:21:35 INFO - } 05:21:35 INFO - Found installer url https://queue.taskcluster.net/v1/task/L4mV7LDAR2CnVRmLYvfCmw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 05:21:35 INFO - Running post-action listener: _resource_record_post_action 05:21:35 INFO - ##### 05:21:35 INFO - ##### Finished read-buildbot-config step (success) 05:21:35 INFO - ##### 05:21:35 INFO - ##### 05:21:35 INFO - ##### Running download-and-extract step. 05:21:35 INFO - ##### 05:21:35 INFO - Running pre-action listener: _resource_record_pre_action 05:21:35 INFO - Running main action method: download_and_extract 05:21:35 INFO - mkdir: /builds/slave/test/build/tests 05:21:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:21:35 INFO - https://queue.taskcluster.net/v1/task/L4mV7LDAR2CnVRmLYvfCmw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 05:21:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L4mV7LDAR2CnVRmLYvfCmw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 05:21:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L4mV7LDAR2CnVRmLYvfCmw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 05:21:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L4mV7LDAR2CnVRmLYvfCmw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 05:21:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L4mV7LDAR2CnVRmLYvfCmw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 05:21:47 INFO - Downloaded 1361 bytes. 05:21:47 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 05:21:47 INFO - Using the following test package requirements: 05:21:47 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 05:21:47 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 05:21:47 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 05:21:47 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 05:21:47 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 05:21:47 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 05:21:47 INFO - u'jsshell-linux-x86_64.zip'], 05:21:47 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 05:21:47 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 05:21:47 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 05:21:47 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 05:21:47 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 05:21:47 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 05:21:47 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 05:21:47 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 05:21:47 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 05:21:47 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 05:21:47 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 05:21:47 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 05:21:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:21:47 INFO - https://queue.taskcluster.net/v1/task/L4mV7LDAR2CnVRmLYvfCmw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:21:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L4mV7LDAR2CnVRmLYvfCmw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 05:21:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L4mV7LDAR2CnVRmLYvfCmw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 05:21:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L4mV7LDAR2CnVRmLYvfCmw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 05:21:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L4mV7LDAR2CnVRmLYvfCmw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 05:21:53 INFO - Downloaded 23902275 bytes. 05:21:53 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:21:53 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 05:21:54 INFO - Return code: 0 05:21:54 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 05:21:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:21:54 INFO - https://queue.taskcluster.net/v1/task/L4mV7LDAR2CnVRmLYvfCmw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:21:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L4mV7LDAR2CnVRmLYvfCmw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 05:21:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L4mV7LDAR2CnVRmLYvfCmw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 05:21:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L4mV7LDAR2CnVRmLYvfCmw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 05:21:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L4mV7LDAR2CnVRmLYvfCmw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 05:21:55 INFO - Downloaded 23902275 bytes. 05:21:55 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:21:55 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 05:21:56 INFO - Return code: 0 05:21:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:21:56 INFO - https://queue.taskcluster.net/v1/task/L4mV7LDAR2CnVRmLYvfCmw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 05:21:56 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L4mV7LDAR2CnVRmLYvfCmw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 05:21:56 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L4mV7LDAR2CnVRmLYvfCmw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 05:21:56 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L4mV7LDAR2CnVRmLYvfCmw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 05:21:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L4mV7LDAR2CnVRmLYvfCmw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 05:21:57 INFO - Downloaded 10840152 bytes. 05:21:58 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:21:58 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 05:21:58 INFO - Return code: 0 05:21:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:21:58 INFO - https://queue.taskcluster.net/v1/task/L4mV7LDAR2CnVRmLYvfCmw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 05:21:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L4mV7LDAR2CnVRmLYvfCmw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 05:21:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L4mV7LDAR2CnVRmLYvfCmw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 05:21:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L4mV7LDAR2CnVRmLYvfCmw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 05:21:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L4mV7LDAR2CnVRmLYvfCmw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 05:21:59 INFO - Downloaded 58340576 bytes. 05:21:59 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/L4mV7LDAR2CnVRmLYvfCmw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 05:21:59 INFO - mkdir: /builds/slave/test/properties 05:21:59 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:21:59 INFO - Writing to file /builds/slave/test/properties/build_url 05:21:59 INFO - Contents: 05:21:59 INFO - build_url:https://queue.taskcluster.net/v1/task/L4mV7LDAR2CnVRmLYvfCmw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 05:22:00 INFO - Running post-action listener: _resource_record_post_action 05:22:00 INFO - Running post-action listener: set_extra_try_arguments 05:22:00 INFO - ##### 05:22:00 INFO - ##### Finished download-and-extract step (success) 05:22:00 INFO - ##### 05:22:00 INFO - ##### 05:22:00 INFO - ##### Running populate-webroot step. 05:22:00 INFO - ##### 05:22:00 INFO - Running pre-action listener: _resource_record_pre_action 05:22:00 INFO - Running main action method: populate_webroot 05:22:00 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:22:00 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:22:00 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:22:00 INFO - '--tppagecycles', 05:22:00 INFO - '7'], 05:22:00 INFO - 'tests': ['tsvgm']}}, 05:22:00 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:22:00 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:22:00 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:22:00 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:22:00 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:22:00 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:22:00 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:22:00 INFO - 'talos_options': ['--disable-e10s'], 05:22:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:22:00 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:22:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:22:00 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:22:00 INFO - 'talos_options': ['--disable-e10s'], 05:22:00 INFO - 'tests': ['damp', 'tps']}, 05:22:00 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:22:00 INFO - 'tests': ['damp', 'tps']}, 05:22:00 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:22:00 INFO - 'tests': ['dromaeo_dom']}, 05:22:00 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:22:00 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:22:00 INFO - 'tests': ['a11yr', 05:22:00 INFO - 'ts_paint', 05:22:00 INFO - 'tpaint', 05:22:00 INFO - 'sessionrestore', 05:22:00 INFO - 'sessionrestore_no_auto_restore', 05:22:00 INFO - 'tabpaint']}, 05:22:00 INFO - 'other-e10s': {'tests': ['a11yr', 05:22:00 INFO - 'ts_paint', 05:22:00 INFO - 'tpaint', 05:22:00 INFO - 'sessionrestore', 05:22:00 INFO - 'sessionrestore_no_auto_restore', 05:22:00 INFO - 'tabpaint']}, 05:22:00 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:22:00 INFO - 'ts_paint', 05:22:00 INFO - 'tpaint', 05:22:00 INFO - 'sessionrestore', 05:22:00 INFO - 'sessionrestore_no_auto_restore', 05:22:00 INFO - 'tabpaint']}, 05:22:00 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:22:00 INFO - 'ts_paint', 05:22:00 INFO - 'tpaint', 05:22:00 INFO - 'sessionrestore', 05:22:00 INFO - 'sessionrestore_no_auto_restore', 05:22:00 INFO - 'tabpaint']}, 05:22:00 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:22:00 INFO - 'tests': ['a11yr', 05:22:00 INFO - 'ts_paint', 05:22:00 INFO - 'tpaint', 05:22:00 INFO - 'sessionrestore', 05:22:00 INFO - 'sessionrestore_no_auto_restore', 05:22:00 INFO - 'tabpaint']}, 05:22:00 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:22:00 INFO - 'tests': ['a11yr', 05:22:00 INFO - 'ts_paint', 05:22:00 INFO - 'tpaint', 05:22:00 INFO - 'sessionrestore', 05:22:00 INFO - 'sessionrestore_no_auto_restore', 05:22:00 INFO - 'tabpaint']}, 05:22:00 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:22:00 INFO - 'tests': ['tsvgx', 05:22:00 INFO - 'tsvgr_opacity', 05:22:00 INFO - 'tart', 05:22:00 INFO - 'tscrollx', 05:22:00 INFO - 'cart']}, 05:22:00 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:22:00 INFO - 'tsvgr_opacity', 05:22:00 INFO - 'tart', 05:22:00 INFO - 'tscrollx', 05:22:00 INFO - 'cart']}, 05:22:00 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:22:00 INFO - 'talos_options': ['--disable-e10s'], 05:22:00 INFO - 'tests': ['tp5o']}, 05:22:00 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:22:00 INFO - 'tests': ['tp5o']}, 05:22:00 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:22:00 INFO - 'talos_options': ['--disable-e10s', 05:22:00 INFO - '--xperf_path', 05:22:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:22:00 INFO - 'tests': ['tp5n']}, 05:22:00 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:22:00 INFO - 'talos_options': ['--xperf_path', 05:22:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:22:00 INFO - 'tests': ['tp5n']}}, 05:22:00 INFO - 'talos.zip': {'path': '', 05:22:00 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:22:00 INFO - Running post-action listener: _resource_record_post_action 05:22:00 INFO - ##### 05:22:00 INFO - ##### Finished populate-webroot step (success) 05:22:00 INFO - ##### 05:22:00 INFO - ##### 05:22:00 INFO - ##### Running create-virtualenv step. 05:22:00 INFO - ##### 05:22:00 INFO - Running pre-action listener: _resource_record_pre_action 05:22:00 INFO - Running main action method: create_virtualenv 05:22:00 INFO - Creating virtualenv /builds/slave/test/build/venv 05:22:00 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 05:22:00 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:22:00 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:22:00 INFO - Using real prefix '/usr' 05:22:00 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:22:00 INFO - Installing distribute.............................................................................................................................................................................................done. 05:22:02 INFO - Installing pip.................done. 05:22:02 INFO - Return code: 0 05:22:02 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:22:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:22:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:22:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:22:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:22:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:22:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:22:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f3315d8ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10318a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10fc770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x118b9c0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x118c8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x118cd50>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '0274524eba3bdaad075c2247000001d4-1463055161.483907-940509389', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:22:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:22:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:22:02 INFO - Using env: {'DISPLAY': ':0', 05:22:02 INFO - 'HOME': '/home/cltbld', 05:22:02 INFO - 'LANG': 'en_US.UTF-8', 05:22:02 INFO - 'LANGUAGE': 'en_US:en', 05:22:02 INFO - 'LOGNAME': 'cltbld', 05:22:02 INFO - 'MAIL': '/var/mail/cltbld', 05:22:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:22:02 INFO - 'MOZ_NO_REMOTE': '1', 05:22:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:22:02 INFO - 'NO_EM_RESTART': '1', 05:22:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:22:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:22:02 INFO - 'PWD': '/builds/slave/test', 05:22:02 INFO - 'SHELL': '/bin/bash', 05:22:02 INFO - 'SHLVL': '1', 05:22:02 INFO - 'TERM': 'linux', 05:22:02 INFO - 'TMOUT': '86400', 05:22:02 INFO - 'USER': 'cltbld', 05:22:02 INFO - 'XDG_SESSION_COOKIE': '0274524eba3bdaad075c2247000001d4-1463055161.483907-940509389', 05:22:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:22:02 INFO - '_': '/tools/buildbot/bin/python'} 05:22:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:22:03 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 05:22:03 INFO - Cleaning up... 05:22:03 INFO - Return code: 0 05:22:03 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 05:22:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:22:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:22:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:22:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:22:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:22:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:22:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f3315d8ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10318a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10fc770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x118b9c0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x118c8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x118cd50>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '0274524eba3bdaad075c2247000001d4-1463055161.483907-940509389', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:22:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 05:22:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 05:22:03 INFO - Using env: {'DISPLAY': ':0', 05:22:03 INFO - 'HOME': '/home/cltbld', 05:22:03 INFO - 'LANG': 'en_US.UTF-8', 05:22:03 INFO - 'LANGUAGE': 'en_US:en', 05:22:03 INFO - 'LOGNAME': 'cltbld', 05:22:03 INFO - 'MAIL': '/var/mail/cltbld', 05:22:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:22:03 INFO - 'MOZ_NO_REMOTE': '1', 05:22:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:22:03 INFO - 'NO_EM_RESTART': '1', 05:22:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:22:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:22:03 INFO - 'PWD': '/builds/slave/test', 05:22:03 INFO - 'SHELL': '/bin/bash', 05:22:03 INFO - 'SHLVL': '1', 05:22:03 INFO - 'TERM': 'linux', 05:22:03 INFO - 'TMOUT': '86400', 05:22:03 INFO - 'USER': 'cltbld', 05:22:03 INFO - 'XDG_SESSION_COOKIE': '0274524eba3bdaad075c2247000001d4-1463055161.483907-940509389', 05:22:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:22:03 INFO - '_': '/tools/buildbot/bin/python'} 05:22:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:22:03 INFO - Downloading/unpacking psutil>=0.7.1 05:22:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:22:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:22:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:22:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:22:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:22:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:22:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:22:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:22:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:22:05 INFO - Installing collected packages: psutil 05:22:05 INFO - Running setup.py install for psutil 05:22:05 INFO - building 'psutil._psutil_linux' extension 05:22:05 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 05:22:05 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 05:22:05 INFO - building 'psutil._psutil_posix' extension 05:22:05 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 05:22:05 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 05:22:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:22:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:22:05 INFO - Successfully installed psutil 05:22:05 INFO - Cleaning up... 05:22:05 INFO - Return code: 0 05:22:05 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 05:22:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:22:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:22:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:22:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:22:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:22:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:22:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f3315d8ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10318a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10fc770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x118b9c0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x118c8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x118cd50>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '0274524eba3bdaad075c2247000001d4-1463055161.483907-940509389', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:22:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 05:22:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 05:22:05 INFO - Using env: {'DISPLAY': ':0', 05:22:05 INFO - 'HOME': '/home/cltbld', 05:22:05 INFO - 'LANG': 'en_US.UTF-8', 05:22:05 INFO - 'LANGUAGE': 'en_US:en', 05:22:05 INFO - 'LOGNAME': 'cltbld', 05:22:05 INFO - 'MAIL': '/var/mail/cltbld', 05:22:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:22:05 INFO - 'MOZ_NO_REMOTE': '1', 05:22:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:22:05 INFO - 'NO_EM_RESTART': '1', 05:22:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:22:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:22:05 INFO - 'PWD': '/builds/slave/test', 05:22:05 INFO - 'SHELL': '/bin/bash', 05:22:05 INFO - 'SHLVL': '1', 05:22:05 INFO - 'TERM': 'linux', 05:22:05 INFO - 'TMOUT': '86400', 05:22:05 INFO - 'USER': 'cltbld', 05:22:05 INFO - 'XDG_SESSION_COOKIE': '0274524eba3bdaad075c2247000001d4-1463055161.483907-940509389', 05:22:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:22:05 INFO - '_': '/tools/buildbot/bin/python'} 05:22:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:22:06 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 05:22:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:22:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:22:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:22:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:22:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:22:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:22:07 INFO - Downloading mozsystemmonitor-0.0.tar.gz 05:22:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:22:08 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 05:22:08 INFO - Installing collected packages: mozsystemmonitor 05:22:08 INFO - Running setup.py install for mozsystemmonitor 05:22:08 INFO - Successfully installed mozsystemmonitor 05:22:08 INFO - Cleaning up... 05:22:08 INFO - Return code: 0 05:22:08 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:22:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:22:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:22:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:22:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:22:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:22:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:22:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f3315d8ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10318a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10fc770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x118b9c0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x118c8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x118cd50>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '0274524eba3bdaad075c2247000001d4-1463055161.483907-940509389', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:22:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:22:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:22:08 INFO - Using env: {'DISPLAY': ':0', 05:22:08 INFO - 'HOME': '/home/cltbld', 05:22:08 INFO - 'LANG': 'en_US.UTF-8', 05:22:08 INFO - 'LANGUAGE': 'en_US:en', 05:22:08 INFO - 'LOGNAME': 'cltbld', 05:22:08 INFO - 'MAIL': '/var/mail/cltbld', 05:22:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:22:08 INFO - 'MOZ_NO_REMOTE': '1', 05:22:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:22:08 INFO - 'NO_EM_RESTART': '1', 05:22:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:22:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:22:08 INFO - 'PWD': '/builds/slave/test', 05:22:08 INFO - 'SHELL': '/bin/bash', 05:22:08 INFO - 'SHLVL': '1', 05:22:08 INFO - 'TERM': 'linux', 05:22:08 INFO - 'TMOUT': '86400', 05:22:08 INFO - 'USER': 'cltbld', 05:22:08 INFO - 'XDG_SESSION_COOKIE': '0274524eba3bdaad075c2247000001d4-1463055161.483907-940509389', 05:22:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:22:08 INFO - '_': '/tools/buildbot/bin/python'} 05:22:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:22:08 INFO - Downloading/unpacking blobuploader==1.2.4 05:22:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:22:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:22:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:22:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:22:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:22:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:22:10 INFO - Downloading blobuploader-1.2.4.tar.gz 05:22:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:22:10 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:22:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:22:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:22:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:22:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:22:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:22:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:22:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:22:10 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:22:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:22:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:22:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:22:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:22:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:22:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:22:10 INFO - Downloading docopt-0.6.1.tar.gz 05:22:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:22:11 INFO - Installing collected packages: blobuploader, requests, docopt 05:22:11 INFO - Running setup.py install for blobuploader 05:22:11 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:22:11 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:22:11 INFO - Running setup.py install for requests 05:22:11 INFO - Running setup.py install for docopt 05:22:11 INFO - Successfully installed blobuploader requests docopt 05:22:11 INFO - Cleaning up... 05:22:11 INFO - Return code: 0 05:22:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:22:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:22:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:22:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:22:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:22:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:22:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:22:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f3315d8ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10318a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10fc770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x118b9c0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x118c8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x118cd50>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '0274524eba3bdaad075c2247000001d4-1463055161.483907-940509389', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:22:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:22:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:22:11 INFO - Using env: {'DISPLAY': ':0', 05:22:11 INFO - 'HOME': '/home/cltbld', 05:22:11 INFO - 'LANG': 'en_US.UTF-8', 05:22:11 INFO - 'LANGUAGE': 'en_US:en', 05:22:11 INFO - 'LOGNAME': 'cltbld', 05:22:11 INFO - 'MAIL': '/var/mail/cltbld', 05:22:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:22:11 INFO - 'MOZ_NO_REMOTE': '1', 05:22:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:22:11 INFO - 'NO_EM_RESTART': '1', 05:22:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:22:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:22:11 INFO - 'PWD': '/builds/slave/test', 05:22:11 INFO - 'SHELL': '/bin/bash', 05:22:11 INFO - 'SHLVL': '1', 05:22:11 INFO - 'TERM': 'linux', 05:22:11 INFO - 'TMOUT': '86400', 05:22:11 INFO - 'USER': 'cltbld', 05:22:11 INFO - 'XDG_SESSION_COOKIE': '0274524eba3bdaad075c2247000001d4-1463055161.483907-940509389', 05:22:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:22:11 INFO - '_': '/tools/buildbot/bin/python'} 05:22:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:22:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:22:11 INFO - Running setup.py (path:/tmp/pip-atTpCI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:22:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:22:11 INFO - Running setup.py (path:/tmp/pip-sMEFmI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:22:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:22:12 INFO - Running setup.py (path:/tmp/pip-NA82aT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:22:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:22:12 INFO - Running setup.py (path:/tmp/pip-WMaQud-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:22:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:22:12 INFO - Running setup.py (path:/tmp/pip-at62Cd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:22:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:22:12 INFO - Running setup.py (path:/tmp/pip-n0yatb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:22:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:22:12 INFO - Running setup.py (path:/tmp/pip-lztcQB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:22:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:22:12 INFO - Running setup.py (path:/tmp/pip-1_nRHK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:22:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:22:12 INFO - Running setup.py (path:/tmp/pip-4YMpV7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:22:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:22:12 INFO - Running setup.py (path:/tmp/pip-CqgD1a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:22:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:22:12 INFO - Running setup.py (path:/tmp/pip-MW7Q0i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:22:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:22:12 INFO - Running setup.py (path:/tmp/pip-51rsVw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:22:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:22:12 INFO - Running setup.py (path:/tmp/pip-ik02S9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:22:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:22:12 INFO - Running setup.py (path:/tmp/pip-aLj68k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:22:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:22:12 INFO - Running setup.py (path:/tmp/pip-DDgiKn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:22:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:22:12 INFO - Running setup.py (path:/tmp/pip-FUnq6x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:22:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:22:12 INFO - Running setup.py (path:/tmp/pip-4IEqIr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:22:13 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:22:13 INFO - Running setup.py install for manifestparser 05:22:13 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:22:13 INFO - Running setup.py install for mozcrash 05:22:13 INFO - Running setup.py install for mozdebug 05:22:13 INFO - Running setup.py install for mozdevice 05:22:13 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:22:13 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:22:13 INFO - Running setup.py install for mozfile 05:22:13 INFO - Running setup.py install for mozhttpd 05:22:13 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:22:13 INFO - Running setup.py install for mozinfo 05:22:13 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:22:13 INFO - Running setup.py install for mozInstall 05:22:13 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:22:13 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:22:13 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:22:13 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:22:13 INFO - Running setup.py install for mozleak 05:22:13 INFO - Running setup.py install for mozlog 05:22:14 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:22:14 INFO - Running setup.py install for moznetwork 05:22:14 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:22:14 INFO - Running setup.py install for mozprocess 05:22:14 INFO - Running setup.py install for mozprofile 05:22:14 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:22:14 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:22:14 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:22:14 INFO - Running setup.py install for mozrunner 05:22:14 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:22:14 INFO - Running setup.py install for mozscreenshot 05:22:14 INFO - Running setup.py install for moztest 05:22:14 INFO - Running setup.py install for mozversion 05:22:14 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:22:14 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:22:14 INFO - Cleaning up... 05:22:14 INFO - Return code: 0 05:22:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:22:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:22:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:22:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:22:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:22:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:22:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:22:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f3315d8ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10318a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10fc770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x118b9c0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x118c8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x118cd50>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '0274524eba3bdaad075c2247000001d4-1463055161.483907-940509389', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:22:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:22:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:22:14 INFO - Using env: {'DISPLAY': ':0', 05:22:14 INFO - 'HOME': '/home/cltbld', 05:22:14 INFO - 'LANG': 'en_US.UTF-8', 05:22:14 INFO - 'LANGUAGE': 'en_US:en', 05:22:14 INFO - 'LOGNAME': 'cltbld', 05:22:14 INFO - 'MAIL': '/var/mail/cltbld', 05:22:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:22:14 INFO - 'MOZ_NO_REMOTE': '1', 05:22:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:22:14 INFO - 'NO_EM_RESTART': '1', 05:22:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:22:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:22:14 INFO - 'PWD': '/builds/slave/test', 05:22:14 INFO - 'SHELL': '/bin/bash', 05:22:14 INFO - 'SHLVL': '1', 05:22:14 INFO - 'TERM': 'linux', 05:22:14 INFO - 'TMOUT': '86400', 05:22:14 INFO - 'USER': 'cltbld', 05:22:14 INFO - 'XDG_SESSION_COOKIE': '0274524eba3bdaad075c2247000001d4-1463055161.483907-940509389', 05:22:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:22:14 INFO - '_': '/tools/buildbot/bin/python'} 05:22:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:22:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:22:15 INFO - Running setup.py (path:/tmp/pip-1e8pla-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:22:15 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 05:22:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:22:15 INFO - Running setup.py (path:/tmp/pip-etkp4d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:22:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:22:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:22:15 INFO - Running setup.py (path:/tmp/pip-EBL00f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:22:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:22:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:22:15 INFO - Running setup.py (path:/tmp/pip-Vpj87J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:22:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:22:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:22:15 INFO - Running setup.py (path:/tmp/pip-7ac8J_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:22:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 05:22:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:22:15 INFO - Running setup.py (path:/tmp/pip-F4k91b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:22:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 05:22:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:22:15 INFO - Running setup.py (path:/tmp/pip-sEpOJP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:22:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 05:22:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:22:15 INFO - Running setup.py (path:/tmp/pip-Vudgpi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:22:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 05:22:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:22:15 INFO - Running setup.py (path:/tmp/pip-5q2a4w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:22:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 05:22:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:22:15 INFO - Running setup.py (path:/tmp/pip-bZQIQh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:22:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:22:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:22:15 INFO - Running setup.py (path:/tmp/pip-9L5zhn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:22:15 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 05:22:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:22:15 INFO - Running setup.py (path:/tmp/pip-gbuVhU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:22:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 05:22:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:22:15 INFO - Running setup.py (path:/tmp/pip-TvUckN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:22:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 05:22:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:22:16 INFO - Running setup.py (path:/tmp/pip-PClDf3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:22:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 05:22:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:22:16 INFO - Running setup.py (path:/tmp/pip-qnSvJt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:22:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 05:22:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:22:16 INFO - Running setup.py (path:/tmp/pip-8MLI09-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:22:16 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 05:22:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:22:16 INFO - Running setup.py (path:/tmp/pip-VvBW7y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:22:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 05:22:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:22:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:22:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:22:16 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:22:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:22:16 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:22:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:22:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:22:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:22:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:22:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:22:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:22:18 INFO - Downloading blessings-1.6.tar.gz 05:22:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:22:18 INFO - Installing collected packages: blessings 05:22:18 INFO - Running setup.py install for blessings 05:22:18 INFO - Successfully installed blessings 05:22:18 INFO - Cleaning up... 05:22:18 INFO - Return code: 0 05:22:18 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:22:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:22:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:22:18 INFO - Reading from file tmpfile_stdout 05:22:18 INFO - Current package versions: 05:22:18 INFO - argparse == 1.2.1 05:22:18 INFO - blessings == 1.6 05:22:18 INFO - blobuploader == 1.2.4 05:22:18 INFO - docopt == 0.6.1 05:22:18 INFO - manifestparser == 1.1 05:22:18 INFO - mozInstall == 1.12 05:22:18 INFO - mozcrash == 0.17 05:22:18 INFO - mozdebug == 0.1 05:22:18 INFO - mozdevice == 0.48 05:22:18 INFO - mozfile == 1.2 05:22:18 INFO - mozhttpd == 0.7 05:22:18 INFO - mozinfo == 0.9 05:22:18 INFO - mozleak == 0.1 05:22:18 INFO - mozlog == 3.1 05:22:18 INFO - moznetwork == 0.27 05:22:18 INFO - mozprocess == 0.22 05:22:18 INFO - mozprofile == 0.28 05:22:18 INFO - mozrunner == 6.11 05:22:18 INFO - mozscreenshot == 0.1 05:22:18 INFO - mozsystemmonitor == 0.0 05:22:18 INFO - moztest == 0.7 05:22:18 INFO - mozversion == 1.4 05:22:18 INFO - psutil == 3.1.1 05:22:18 INFO - requests == 1.2.3 05:22:18 INFO - wsgiref == 0.1.2 05:22:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:22:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:22:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:22:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:22:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:22:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:22:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:22:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f3315d8ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10318a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10fc770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x118b9c0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x118c8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x118cd50>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '0274524eba3bdaad075c2247000001d4-1463055161.483907-940509389', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:22:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 05:22:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:22:18 INFO - Using env: {'DISPLAY': ':0', 05:22:18 INFO - 'HOME': '/home/cltbld', 05:22:18 INFO - 'LANG': 'en_US.UTF-8', 05:22:18 INFO - 'LANGUAGE': 'en_US:en', 05:22:18 INFO - 'LOGNAME': 'cltbld', 05:22:18 INFO - 'MAIL': '/var/mail/cltbld', 05:22:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:22:18 INFO - 'MOZ_NO_REMOTE': '1', 05:22:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:22:18 INFO - 'NO_EM_RESTART': '1', 05:22:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:22:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:22:18 INFO - 'PWD': '/builds/slave/test', 05:22:18 INFO - 'SHELL': '/bin/bash', 05:22:18 INFO - 'SHLVL': '1', 05:22:18 INFO - 'TERM': 'linux', 05:22:18 INFO - 'TMOUT': '86400', 05:22:18 INFO - 'USER': 'cltbld', 05:22:18 INFO - 'XDG_SESSION_COOKIE': '0274524eba3bdaad075c2247000001d4-1463055161.483907-940509389', 05:22:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:22:18 INFO - '_': '/tools/buildbot/bin/python'} 05:22:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:22:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:22:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 05:22:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 05:22:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:22:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 05:22:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 05:22:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 05:22:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 05:22:18 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 05:22:18 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:22:18 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:22:18 INFO - Cleaning up... 05:22:18 INFO - Return code: 0 05:22:18 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:22:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:22:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:22:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:22:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:22:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:22:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:22:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f3315d8ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10318a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10fc770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x118b9c0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x118c8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x118cd50>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '0274524eba3bdaad075c2247000001d4-1463055161.483907-940509389', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:22:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:22:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:22:18 INFO - Using env: {'DISPLAY': ':0', 05:22:18 INFO - 'HOME': '/home/cltbld', 05:22:18 INFO - 'LANG': 'en_US.UTF-8', 05:22:18 INFO - 'LANGUAGE': 'en_US:en', 05:22:18 INFO - 'LOGNAME': 'cltbld', 05:22:18 INFO - 'MAIL': '/var/mail/cltbld', 05:22:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:22:18 INFO - 'MOZ_NO_REMOTE': '1', 05:22:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:22:18 INFO - 'NO_EM_RESTART': '1', 05:22:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:22:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:22:18 INFO - 'PWD': '/builds/slave/test', 05:22:18 INFO - 'SHELL': '/bin/bash', 05:22:18 INFO - 'SHLVL': '1', 05:22:18 INFO - 'TERM': 'linux', 05:22:18 INFO - 'TMOUT': '86400', 05:22:18 INFO - 'USER': 'cltbld', 05:22:18 INFO - 'XDG_SESSION_COOKIE': '0274524eba3bdaad075c2247000001d4-1463055161.483907-940509389', 05:22:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:22:18 INFO - '_': '/tools/buildbot/bin/python'} 05:22:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:22:19 INFO - Downloading/unpacking jsonschema 05:22:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:22:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:22:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:22:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:22:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:22:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:22:20 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:22:20 INFO - Downloading/unpacking functools32 (from jsonschema) 05:22:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:22:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:22:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:22:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:22:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:22:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:22:21 INFO - Downloading functools32-3.2.3-2.tar.gz 05:22:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:22:21 INFO - warning: no files found matching '*.txt' 05:22:21 INFO - no previously-included directories found matching 'build' 05:22:21 INFO - no previously-included directories found matching 'dist' 05:22:21 INFO - no previously-included directories found matching '.git*' 05:22:21 INFO - Installing collected packages: jsonschema, functools32 05:22:21 INFO - Running setup.py install for functools32 05:22:21 INFO - warning: no files found matching '*.txt' 05:22:21 INFO - no previously-included directories found matching 'build' 05:22:21 INFO - no previously-included directories found matching 'dist' 05:22:21 INFO - no previously-included directories found matching '.git*' 05:22:21 INFO - Successfully installed jsonschema functools32 05:22:21 INFO - Cleaning up... 05:22:21 INFO - Return code: 0 05:22:21 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 05:22:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:22:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:22:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:22:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:22:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:22:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:22:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f3315d8ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10318a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10fc770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x118b9c0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x118c8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x118cd50>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '0274524eba3bdaad075c2247000001d4-1463055161.483907-940509389', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:22:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 05:22:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 05:22:21 INFO - Using env: {'DISPLAY': ':0', 05:22:21 INFO - 'HOME': '/home/cltbld', 05:22:21 INFO - 'LANG': 'en_US.UTF-8', 05:22:21 INFO - 'LANGUAGE': 'en_US:en', 05:22:21 INFO - 'LOGNAME': 'cltbld', 05:22:21 INFO - 'MAIL': '/var/mail/cltbld', 05:22:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:22:21 INFO - 'MOZ_NO_REMOTE': '1', 05:22:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:22:21 INFO - 'NO_EM_RESTART': '1', 05:22:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:22:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:22:21 INFO - 'PWD': '/builds/slave/test', 05:22:21 INFO - 'SHELL': '/bin/bash', 05:22:21 INFO - 'SHLVL': '1', 05:22:21 INFO - 'TERM': 'linux', 05:22:21 INFO - 'TMOUT': '86400', 05:22:21 INFO - 'USER': 'cltbld', 05:22:21 INFO - 'XDG_SESSION_COOKIE': '0274524eba3bdaad075c2247000001d4-1463055161.483907-940509389', 05:22:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:22:21 INFO - '_': '/tools/buildbot/bin/python'} 05:22:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:22:21 INFO - Downloading/unpacking flake8 05:22:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:22:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:22:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:22:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:22:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:22:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:22:23 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:22:23 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:22:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:22:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:22:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:22:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:22:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:22:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:22:23 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:22:23 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:22:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:22:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:22:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:22:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:22:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:22:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:22:23 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:22:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:22:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:22:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:22:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:22:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:22:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:22:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 05:22:24 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:22:24 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:22:24 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:22:24 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:22:24 INFO - no previously-included directories found matching 'docs/_build' 05:22:24 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:22:24 INFO - Running setup.py install for pep8 05:22:24 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:22:24 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:22:24 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:22:24 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:22:24 INFO - no previously-included directories found matching 'docs/_build' 05:22:24 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 05:22:24 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:22:24 INFO - Cleaning up... 05:22:24 INFO - Return code: 0 05:22:24 INFO - Running post-action listener: _resource_record_post_action 05:22:24 INFO - Running post-action listener: _start_resource_monitoring 05:22:24 INFO - Starting resource monitoring. 05:22:24 INFO - ##### 05:22:24 INFO - ##### Finished create-virtualenv step (success) 05:22:24 INFO - ##### 05:22:24 INFO - ##### 05:22:24 INFO - ##### Running install step. 05:22:24 INFO - ##### 05:22:24 INFO - Running pre-action listener: _resource_record_pre_action 05:22:24 INFO - Running main action method: install 05:22:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:22:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:22:24 INFO - Reading from file tmpfile_stdout 05:22:24 INFO - Detecting whether we're running mozinstall >=1.0... 05:22:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:22:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:22:24 INFO - Reading from file tmpfile_stdout 05:22:24 INFO - Output received: 05:22:24 INFO - Usage: mozinstall [options] installer 05:22:24 INFO - Options: 05:22:24 INFO - -h, --help show this help message and exit 05:22:24 INFO - -d DEST, --destination=DEST 05:22:24 INFO - Directory to install application into. [default: 05:22:24 INFO - "/builds/slave/test"] 05:22:24 INFO - --app=APP Application being installed. [default: firefox] 05:22:24 INFO - mkdir: /builds/slave/test/build/application 05:22:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 05:22:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 05:22:41 INFO - Reading from file tmpfile_stdout 05:22:41 INFO - Output received: 05:22:41 INFO - /builds/slave/test/build/application/firefox/firefox 05:22:41 INFO - Running post-action listener: _resource_record_post_action 05:22:41 INFO - ##### 05:22:41 INFO - ##### Finished install step (success) 05:22:41 INFO - ##### 05:22:41 INFO - ##### 05:22:41 INFO - ##### Running run-tests step. 05:22:41 INFO - ##### 05:22:41 INFO - Running pre-action listener: _resource_record_pre_action 05:22:41 INFO - Running main action method: run_tests 05:22:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:22:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:22:41 INFO - Python 2.7.3 05:22:41 INFO - Return code: 0 05:22:41 INFO - grabbing minidump binary from tooltool 05:22:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:22:41 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x118b9c0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x118c8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x118cd50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:22:41 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 05:22:41 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 05:22:41 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 05:22:41 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:22:41 INFO - Return code: 0 05:22:41 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 05:22:41 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:22:41 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:22:41 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 05:22:41 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:22:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 05:22:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 05:22:42 INFO - Return code: 0 05:22:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/L4mV7LDAR2CnVRmLYvfCmw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-029', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 05:22:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/L4mV7LDAR2CnVRmLYvfCmw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-029 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 05:22:42 INFO - Using env: {'DISPLAY': ':0', 05:22:42 INFO - 'HOME': '/home/cltbld', 05:22:42 INFO - 'LANG': 'en_US.UTF-8', 05:22:42 INFO - 'LANGUAGE': 'en_US:en', 05:22:42 INFO - 'LOGNAME': 'cltbld', 05:22:42 INFO - 'MAIL': '/var/mail/cltbld', 05:22:42 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:22:42 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 05:22:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:22:42 INFO - 'MOZ_NO_REMOTE': '1', 05:22:42 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:22:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:22:42 INFO - 'NO_EM_RESTART': '1', 05:22:42 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:22:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:22:42 INFO - 'PWD': '/builds/slave/test', 05:22:42 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:22:42 INFO - 'SHELL': '/bin/bash', 05:22:42 INFO - 'SHLVL': '1', 05:22:42 INFO - 'TERM': 'linux', 05:22:42 INFO - 'TMOUT': '86400', 05:22:42 INFO - 'USER': 'cltbld', 05:22:42 INFO - 'XDG_SESSION_COOKIE': '0274524eba3bdaad075c2247000001d4-1463055161.483907-940509389', 05:22:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:22:42 INFO - '_': '/tools/buildbot/bin/python'} 05:22:42 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/L4mV7LDAR2CnVRmLYvfCmw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-029', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 05:22:42 INFO - mozversion application_buildid: 20160512023005 05:22:42 INFO - mozversion application_changeset: c3ff04109a8a6e439be36ae44133b2ba68fc79f6 05:22:42 INFO - mozversion application_display_name: Nightly 05:22:42 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:22:42 INFO - mozversion application_name: Firefox 05:22:42 INFO - mozversion application_remotingname: firefox 05:22:42 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 05:22:42 INFO - mozversion application_vendor: Mozilla 05:22:42 INFO - mozversion application_version: 49.0a1 05:22:42 INFO - mozversion platform_buildid: 20160512023005 05:22:42 INFO - mozversion platform_changeset: c3ff04109a8a6e439be36ae44133b2ba68fc79f6 05:22:42 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 05:22:42 INFO - mozversion platform_version: 49.0a1 05:22:42 INFO - using testdate: 1463055762 05:22:42 INFO - actual date: 1463055762 05:22:42 INFO - starting webserver on 'localhost:47633' 05:22:42 INFO - SUITE-START | Running 2 tests 05:22:42 INFO - TEST-START | dromaeo_css 05:22:42 INFO - operating with platform_type : linux_ 05:22:42 INFO - Initialising browser for dromaeo_css test... 05:22:42 INFO - TEST-INFO | started process 8542 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpVLjxaE/profile http://localhost:47633/getInfo.html) 05:22:45 INFO - PROCESS | 8542 | 05:22:45 INFO - PROCESS | 8542 | (process:8608): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 05:22:46 INFO - PROCESS | 8542 | __metrics Screen width/height:1600/1200 05:22:46 INFO - PROCESS | 8542 | colorDepth:24 05:22:46 INFO - PROCESS | 8542 | Browser inner width/height: 1024/697 05:22:46 INFO - PROCESS | 8542 | __metrics 05:22:47 ERROR - PROCESS | 8542 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 05:22:47 INFO - TEST-INFO | 8542: exit 0 05:22:47 INFO - Browser initialized. 05:22:47 INFO - Running cycle 1/1 for dromaeo_css test... 05:22:47 INFO - TEST-INFO | started process 8659 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpVLjxaE/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:22:49 INFO - PROCESS | 8659 | 05:22:49 INFO - PROCESS | 8659 | (process:8719): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 05:22:59 INFO - PROCESS | 8659 | RSS: Main: 163500032 05:22:59 INFO - PROCESS | 8659 | 05:23:49 INFO - PROCESS | 8659 | 1463055829273 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 05:25:57 INFO - PROCESS | 8659 | Cycle 1(1): loaded http://localhost:47633/tests/dromaeo/cssquery-dojo.html (next: http://localhost:47633/tests/dromaeo/cssquery-ext.html) 05:25:58 INFO - PROCESS | 8659 | RSS: Main: 165093376 05:25:58 INFO - PROCESS | 8659 | 05:29:00 INFO - PROCESS | 8659 | Cycle 1(1): loaded http://localhost:47633/tests/dromaeo/cssquery-ext.html (next: http://localhost:47633/tests/dromaeo/cssquery-jquery.html) 05:29:00 INFO - PROCESS | 8659 | RSS: Main: 166014976 05:29:00 INFO - PROCESS | 8659 | 05:32:02 INFO - PROCESS | 8659 | Cycle 1(1): loaded http://localhost:47633/tests/dromaeo/cssquery-jquery.html (next: http://localhost:47633/tests/dromaeo/cssquery-mootools.html) 05:32:03 INFO - PROCESS | 8659 | RSS: Main: 167743488 05:32:03 INFO - PROCESS | 8659 | 05:35:05 INFO - PROCESS | 8659 | Cycle 1(1): loaded http://localhost:47633/tests/dromaeo/cssquery-mootools.html (next: http://localhost:47633/tests/dromaeo/cssquery-prototype.html) 05:35:05 INFO - PROCESS | 8659 | RSS: Main: 165437440 05:35:05 INFO - PROCESS | 8659 | 05:38:08 INFO - PROCESS | 8659 | Cycle 1(1): loaded http://localhost:47633/tests/dromaeo/cssquery-prototype.html (next: http://localhost:47633/tests/dromaeo/cssquery-yui.html) 05:38:08 INFO - PROCESS | 8659 | RSS: Main: 166514688 05:38:08 INFO - PROCESS | 8659 | 05:41:11 INFO - PROCESS | 8659 | Cycle 1(1): loaded http://localhost:47633/tests/dromaeo/cssquery-yui.html (next: http://localhost:47633/tests/dromaeo/cssquery-dojo.html) 05:41:11 INFO - PROCESS | 8659 | RSS: Main: 168529920 05:41:11 INFO - PROCESS | 8659 | 05:41:11 INFO - PROCESS | 8659 | __start_tp_report 05:41:11 INFO - PROCESS | 8659 | _x_x_mozilla_page_load 05:41:11 INFO - PROCESS | 8659 | _x_x_mozilla_page_load_details 05:41:11 INFO - PROCESS | 8659 | |i|pagename|runs| 05:41:11 INFO - PROCESS | 8659 | |0;dojo.html;3144.4340018796615;3172.6351469581004;3200.6319273283566;3220.1466611347996;3220.82285474299;5392.811251606199;5418.458154184585;5436.619436639432;5461.863453413666;5493.697846618445;5256.526912577867;5399.082156033474;5419.783208671648;5430.484104010123;5469.288992430978;4813.085513752385;4847.321375007502;4970.055689419017;4982.302477653131;5037.244413337992;953.5925887012411;4833.419989601235;4977.328060711794;4998.300237966688;5029.446760856303;4737.697133288339;4805.351277577517;4816.229403295473;4827.396575428072;4853.417589889218;4702.576768090888;4800.279958006327;4836.927446088312;4841.709497430165;4894.241392584119;4780.7092085137165;4799.880002999918;4811.566958973709;4834.347363106006;4835.661503694742;5251.474852514756;5298.894022119574;5299.6025298102795;5299.920501192485;5391.379991301012;5704.087346024616;5715.856828634248;5783.913241301384;5793.536517078624;5803.419658034205;5795.478406943395;5826.679532625704;5850.531957443394;5855.941440585582;5917.112433135021;5426.050441172819;5456.536194423479;5456.563474922076;5458.072127738294;5462.0441422751255;5827.88344233113;5935.821925342247;5965.463108320272;6003.339632640406;6074.726637301332;5833.562482813806;5858.297004359504;5858.50202732764;5862.208601838697;5868.295804503401;5956.642601443928;5958.374370691102;5969.253843269591;5970.701464926737;5981.700914954322;5692.857678557986;5769.423057694197;5789.681567513827;5832.737526811304;5863.882722345615;5591.832245032656;5727.684977326204;5733.569982251264;5779.711014449261;5856.443637854397;4339.067100573392;4364.41080454141;4372.693911426233;4396.516383197845;4410.316400957857;3911.5892831252877;3930.941035884464;3933.468981687493;3950.6246906543834;3965.147493288957;5557.861053473695;5588.748506317225;5592.804251851166;5600.635958662674;5602.1036634138345;5832.912506312408;5893.702510243887;5903.144044113666;5930.080837470199;5936.881262374728;5402.594805389612;5598.832035038955;5605.215269862222;5621.831345059655;5646.661200327993;5062.898742025212;5087.923681144785;5099.286099946011;5117;5119.667221630657;5914.585978981516;6138.815835524942;6140.4473597376455;6177.166082578884;6181.443670069625;5816.738246778989;5825.737841797045;5849.678267695318;5877.764889404376;5912.142739302691;6016.87966240664;6020.578559500968;6031.306399764083;6035.637861728311;6046.09308603713;5589.664620122806;5644.407337229696;5769.653820770767;5774.538036957118;5777.335606405317;5764.394738552392;5826.067829147316;5832.766689332379;5833.766649333979;5882.794102206402;5637.154426836008;5658.292713410824;5671.716414179357;5707.143928410772;5714.257146570919;5550.250716153268;5623.662580245198;5657.12314591239;5667.688277144796;5675.233843431084;5410.972945135407;5461.1262198048125;5470.097433923358;5553.278073850373;5689.288838895138;5661.122526008476;5695.487406133468;5705.600607957569;5709.8287051388525;5768.769249229984;5566.610337276514;5567.832965011056;5606.607537472338;5608.242887210175;5639.125935480007 05:41:11 INFO - PROCESS | 8659 | |1;ext.html;583.7810820942146;611.9877602447951;638.6072565372295;640.6924676155448;659.4164164714226;43169.35245971309;44138.55861441389;44364.11271774563;44424.3336349955;48181.518184818226;44784.3282350765;44935.101297974106;45113.77443112788;45306.54693453065;45325.093498130016;8880.33397495189;9031.864522032174;9034.638614455447;9058.139476749731;9067.45595264283;8887.777805554848;8908.599113039929;8918.197362237397;8954.507502087383;8990.910090899106;8921.643134274622;8932.910670893272;8949.95525022374;8985.326100542452;9021.233195178418;8913.777155571097;8940.061293564213;8977.865332020025;8994.100589940987;9012.77468063297;8835.514046727427;8938.284937205008;8978.326625503114;8991.775205619912;9013.324000699908;41413.37879931804;41518.58481415208;42008.15983680309;42087.789561052;42165;3657.140571965586;3691.169486865461;3700.0564855961825;3804.524434445694;3942;31410.68589314123;31594.368112637618;31753;32090.679093209004;33851.15372115692;4330.415393921811;4368.956310436887;4432.113577284556;4496.9325460118125;4569.390350528561;218556;220774;221826;222860;223734;34773.47839782405;35662.643373566454;35757.46363804545;56431;57460.71269643625;36411.81794091013;36670.8166459166;36782.816085919396;36919;36939.26121477556;14548.345324460424;14557.344919478648;14585.41658333676;14588.927055364868;14591.562253132422;11890.346030968207;12017.098717596215;12034.097442691658;12076.818847717292;12132;8412.284955778705;8442;8487.70293039753;8513.36149788756;8564.48613083217;3198.792078514889;3202.407554602406;3224.27453822888;3241;3259.3970115528705;1041.6874339599992;1071.8713754349371;1124.9662510124547;1139.202558209257;1152.4410660829492;32113.678863211535;32531.349373012406;32711.182220444203;32908.17729556732;36335.81832090823;1158;1184.4788293150987;1187.709011292239;1196.1865931166717;1216.6410908781754;16549.338026478807;16556.58608534772;16627.334906603843;17013.06428146439;17050.23273952675;452334;453027;455221;457718;460249;56865;56912.43087569154;57051.42948570544;57054.7147264261;57178;1388.645895296713;1402.9859701402856;1413.5829930170623;1434.985650143506;1435.2751860310477;30333.544996825505;31209.53185702171;31248.843755781076;31618.683813162494;32145.678543214268;50837.74581127219;55046.72476637591;56541.71729141493;56662.7166864163;62841.68579157075;435047;435469;438051;438538;439476;43690.12619747651;44366.33450498181;44633.10733785371;44665;44973.10053798971;49107.26339104845;49244.50755492399;49358;50505.24242136297;50574.747126264134;40879.795601023;41396.79301603593;42284.788576056926;42982.35526467043;43187;41257.38113928233;41569.58430415778;41732;41998;42269.154616906875;36961.26077478382;39837.203255935296;41180.58819411887;41415.37876931909;41595.58404416038;35456.64543354533;36586;36929;37273;37363.62636373602;28376.85811570929;28783.712162878102;28904;29075.85462072676;29224.415511690073 05:41:11 INFO - PROCESS | 8659 | |2;jquery.html;393.1959143551437;393.89955561331874;397.3562828218286;405.50933370621544;424.9978750106249;13980.93009534951;17890.46328610142;18695.158717857696;19735.605287894232;19741.309054183108;18630.62738745224;18648.34730784423;18998.525036874085;19419.223231070777;19537.511562210915;5593.468620481058;5654.632448890829;5722;5777.335606405275;5825;5349.411564727877;5687.374388817227;5749.683767392792;5752.309722833266;5794.5943783935145;5675.9432405675825;5682.374938756733;5709.65742055478;5709.71451427429;5716.342620598622;5321.680699158062;5638.577106716992;5643.492085712285;5685.772569097231;5720.2849643794525;5439.238506609078;5486.588505862076;5780.5953583248765;5787.33445653751;5789.247397838254;22038.33884983453;22328.66028038307;22447.551048979094;22520.774792252196;22650.207242746426;2380.190735150057;2403.542061986073;2417.5164967006667;2479.4917042006527;2967.6883927187764;14240.216788076652;15387.923060384739;15458.845411545853;15955.282012309473;16173.029618222827;2034.3896830950655;2145.753238377575;2422.9515409691708;2450.754924507553;2482.131254061082;93754.53122734342;96057.51971240099;96075.51962240213;96850;98755;17009.404670836462;17767.733483997752;18042.458726238234;18183.727244091348;18276.543086422946;32741.345173096404;32943.67056329454;33101;33282.16794580106;33358.66641333604;9767.16979056774;10030.44832534203;10064.14454771338;10104.04011618895;10202.183825293958;8128.349732021424;8160.102388737234;8189.918100819034;8274.338052955749;8312.958435207785;8736.78158046054;8938.10618938114;8984.595693193822;8988.685396011108;9105.499197544066;3410.2156504003724;3481.216726236627;3527;3615.475756015363;3640.7087433005295;921.2721949659827;1027.8715160604925;1031.159604921986;1065.4938904020528;1069.337011053152;17985.190666420043;18686.626267474847;18744.437666870017;18799.718004229948;19003.539884866237;988.016923161459;1025.041586116983;1032.0814475117152;1039.3763741754888;1047.9056884880397;10181.694549163536;10384.896151038543;10583.31208471447;10613.893861061291;10640.148788096934;488037;488504;505207;512951;518248;51479.22781158286;64863;92957.5352123235;93655;94721.5263923676;1023.1354505442889;1032.8812186598636;1044.8119338518989;1047.874255089379;1059.6979860739652;16597.41909033178;16914.830851691324;17030.233639486007;17107.48677539676;17206.053667048433;39285.80357098196;39342.606573933896;39441.60558394379;39998.80000599979;40666.79666601648;56567.43432565621;56981.43018569761;57190.42809571851;57536.71231643815;58193.70903145457;7052.612106333996;7613.695452181851;8336.208060234268;8616.439931404439;8678.869816952625;7495.700171993278;10253.692389228336;12281.631551053482;13032.152910060815;13488.393022314216;11339.659810205707;11519.596814111466;11580.88419115798;11999.940000299943;12058;11476.426178690865;11957.641270761891;11990.220635658654;12260.938695306466;12284.385780711107;12061.75876482483;12070.698232544257;12088;12120.515179392725;12175;8306.377021723516;8310.293625041939;8476.32189424857;8516.233538981522;8562;11022.779544409228;11657.125715571197;11829.112816538622;11835.11236657237;11929.224600400947 05:41:11 INFO - PROCESS | 8659 | |3;mootools.html;1494.484402881006;1532.6551525906668;1667.749837524372;1807.8282563156513;1836.7520384748063;7269.20038795732;8125.959370203155;8143.87784183237;8691.695790647329;8692.000419951699;8221.917780822205;8455.788605284872;8486.151384861527;8572.614232359543;8583.570821458934;2264.2527965771296;2294.701688780456;2296.701428814252;2300.114455934469;2301.470661747799;2308.4228942764307;2321.245595181564;2341.5433990371885;2352.9176478823247;2355.528894221154;2258.7289525256992;2269.965950510744;2275.9089636414524;2317.050009496107;2338.3452633262627;2257.8532395394245;2266.603344414721;2269.387265438339;2270.750217476076;2306.815454763615;2216.268631351666;2218.43429925368;2228.320362289501;2231.9330420087263;2234.128689810975;8397.496150230945;8411.705590304371;8412.663493460253;8455.661773529051;8463.788405289917;5055.443901170868;5195.506426889439;5206.583473322163;5248.239005344204;5263.157894736862;3180.141361832292;3243.5783348164823;3392.6607339265875;3395.6604339565847;3400.2828571571445;1013.6198925402974;1014.8782146142437;1059.8569193158903;1081.194510089988;1081.4808891732014;11764.764704705944;11774.587889423914;11804.291742495478;11812.40937953099;11824.113191510585;4462.799174037176;4566.13243483737;4627.652926030494;4634.420697412824;4644.95355046452;9920.454375009444;9969.102780749767;9980.451075190935;9996.100350968447;10025.598976041023;5216.58267338612;5217.295665085241;5218.712970786643;5223.582113430916;5281.313429254173;4181.435506206623;4619.815207391734;4809.038192361541;4819.566239038503;4858.101251268456;3892.552356479041;3919.098607320332;3994.0613955720382;4029.7582145071387;4034.0519977805193;1284.3706583773883;1291.9935400323127;1297.7858653322096;1300.4018151650157;1313.6387493439381;946.9195118414874;959.0649117110761;960.4093482508207;970.69908328417;975.8243516167158;5382.2464854920345;5400.21696853954;5489.670619762827;5596.160575913564;5672.744726487318;920.1396694091046;926.0878035135386;951.5289931483821;994.572333896417;1027.9280450368403;3722.516072910504;3849.557300910431;3852.5184351956004;3906.121122747359;3956.188981258835;9823.26325525574;10324.638637647646;10351.844722329171;10602.575896964036;10674.679759607225;10478.842817357745;10502.737431564272;10535.262531622866;10590.947045264878;10666.46667666629;7823.726169584037;7826.960865195638;7920.9999999998845;7962.7213047543055;7964.482308649919;5566.860828479321;5745.913811292918;5847.8245652630485;5855.443732845373;5877.971355012941;9586.376885502421;9595.376300540442;9791.363561368487;9870.161036311994;9904.950475247577;9635.236066277184;9749.415035097916;9980.051895069957;10001.649942251986;10110;6524.249711283263;7418.221086786026;7529.924700752922;7584.886226706714;7645.770626881203;8483.533405662747;8506.447080939719;8516.574171291535;8531.146885311418;8544.615492302986;7520.586367749826;7757.7672669819995;7766.611669416619;7833.295003449717;7862.646180921986;7468.626568671436;7606.8098297543;7649.082110146818;7698.769036928901;7729.497582657109;6639.6680165992475;6721.865562688621;6725.19297684281;6725.428338591275;6734.494912881455;4817.445993710769;4922.360093187863;4931.3835770528685;4940.851774446773;4998.550130488274;4879.585235254901;4891.584215341736;4924.803007879645;4932.186189278729;4935.851924442272 05:41:11 INFO - PROCESS | 8659 | |4;prototype.html;344.8086312096786;345.574942820331;346.81272113058947;352.5047308531514;353.918598722294;5586.86032849179;5643.492085712285;5678.205051292812;5684.573656975723;5695.686737229451;5553.972230138854;5557.249771280876;5559.332880054389;5561.63849349792;5582.162675598663;3851.61483851614;4090.4682391289166;4197.7901104944785;4250.9149817003645;4251.681123915703;4200.180964711883;4244.893877653052;4275.850345237917;4277.443932288814;4288.935665965013;4265.552117027714;4265.616094551489;4270.377256642471;4271.8718438446895;4280.59334363235;4146.668266538685;4197.853075142355;4229.936550951738;4246.830126794925;4251.723637963538;3722.9255414891823;3733.925321493582;3747.38168202249;3754.436834474823;3775.093977445393;5203.16749320107;5309.389420216686;5317.8404647860625;5336.4930331618825;5355.518003379676;4743.383360163191;4893.21708526634;4912.238603016538;4914.533119353656;4951.207806750903;5720.48515633595;5767.942320576824;5777.422257774231;5790.189373487715;5796.88406231873;4137.193247316774;4189.455370801807;4198.91602167958;4201.6008479194425;4219.2194444027955;5889.587728858982;6007.7596896124105;6015.669138197398;6076.513878889678;6079.787207447718;4225.345071513921;4225.72532785368;4235.2800023996;4246.532881383046;4254.617249393959;4274.5939135782055;4307.440032795716;4321.459817522809;4325.178216138924;4327.394164816928;4707.8701111732935;4896.485868983706;4918.9508104919205;4933.618586795703;4955.900881982412;5859.414058594191;5867.325257595431;5889.411058894162;5895.616784908967;5915.438033386821;1485.3687182947203;1502.5942995391183;1504.3982407037272;1506.8493150684844;1508.58513908676;943.2406912435418;972.988092383927;983.685220729374;995.9253056020827;1016.3038318751679;4863.124637565202;4937.185364414831;4978.278149668277;5025.874353141201;5033.697978121324;5259.605529585297;5295.179247216687;5306.575473962073;5337.599680024014;5397.460253974649;4941.728204948762;5025.14572522672;5028.32117664118;5241.9213711794355;5675.77296908119;3227.80633162011;3232.5636039134415;3233.708966193054;3242.4974129010043;3250.2199472126517;6168.321484636686;6250.968745156245;6274.525486510666;6278.775638750491;6282.528810339151;5914.733836977346;6161.96919015411;6192.628442293477;6196.411340922605;6216.533759968021;6133.294671112879;6196.81409557723;6243.594166379171;6268.905966410507;6285.748570057238;6020.909686354796;6038.969805150946;6047.727852246571;6081.840946463942;6109.877802444015;5808.5062769665055;5946.43508866657;6029.51763858899;6047.909281360695;6063.727132279147;6198.597091189058;6241.719122639583;6256.280527739186;6265.091561723616;6271;4119.464469618931;4150.481189851269;4179.9791001046015;4220.15596880619;4269.935950960676;5960.3443621202505;6020.247469066367;6044.760824031151;6054.39456054391;6063.969680151571;5875.412458754261;5879.323877753942;5910.970445147746;5914.521369657586;5946;5622.437756224344;5934.554908381975;5957.255343082114;5973.940260597512;6003.519718422603;5888;5888.17565540816;5917.053271476543;5943.108533719804;5946.881062378815;5792.391798861059;5876.764929402947;5916.822495325147;6019.337872834072;6060.454559089703;5768.644368573466;5859.179714840011;5867.794627188028;5880.764769409176;5902.2622172228475 05:41:11 INFO - PROCESS | 8659 | |5;yui.html;668.8060462465885;676.9898451523228;712.9786106416808;724.6666533394638;735.2316828913785;13643.386047627855;14425.350859211334;16096.275667594942;16994.660106797855;17031.48905532833;15611.375544978187;15642.139682317467;15681.451149209779;15742.842571574281;15821.920890395531;704.2077662629541;822.2969361196181;828.668532586964;829.5478963964654;829.7096016394244;819.8278361544053;819.8563004608568;824.9381296402764;829.4318391901567;831.584207896052;865.3293697384514;868.4789126524066;869.1004810021628;869.1786261982429;871.9258862996653;856.0754385263931;859.2438653984516;860.7761981884693;861.8491763941286;863.7883718488947;626.627156841683;631.034517188702;633.6134957675814;633.9397757213058;634.1344065350809;14262.215578143197;14268.072575282573;14274.500392486318;14289.142651440947;14370.281485925661;13265.602031939057;13339.533116340881;13436.395362208725;13769.105008174536;14037.578872633838;10111.797764044752;10155.89844101557;10197.49012549377;10226.335288206243;10295.536700848479;3739.3082279778328;3767.2088861339153;3786.0345611869075;3825.5218097737784;3934.8229329680235;54270.72864635691;54280.17793186598;54348.92383071246;54488.45511544913;55262.723686381716;10893.945530272456;11096.753672743202;11130.944345278383;11230.66308010761;11420.543178272776;19112.617747645247;19231.807681923;19245.230190792492;19408.8059119407;19642.11610477532;8067.556284404297;8268.958655206685;8305.621298820655;8384;8448.112948140357;4341.565843415633;4347.9565204347555;4772.379590653262;4891.24185751202;4961.082199792978;3423.4180189367867;3514.789112653249;3526.083218363193;3528.6118526962014;3536.257385948928;630.8864404407251;649.954503184782;666.0309250041179;673.2022553274386;673.2056173714965;548.1531034551627;591.3701907468533;606.7209083821491;656.875193713193;679.8917764044576;8524.232819046316;8553.401261911606;8579.742607721779;8826.779330516789;8848.380613357132;666.4734859460972;680.2075581947075;682.8975653651992;683.6991723641581;686.4576984182539;4424.513303536608;4487.730736155841;4509.774511274423;4583.954160458353;4615.215413379734;64397;65419;66074.66962665156;66684;67162.32837671658;54086.729566352704;55729.164062539094;56263.71868140715;56568.71715641396;57661.711691441276;52041.21938170931;52189.47810521922;52632;52883.73558132262;53316.46683533193;8308.87536686938;8365.163483651586;8457.323414126731;8521.701740439055;8888.022317544936;32809;32987.835060824546;33202.667973319956;33228.00315990524;34310.82844585761;41922;43580.34629480624;44310.33534496921;44605.77697111494;44669.55330446654;11554.94222528882;11585.6524304271;11672.824907626562;11697.707557311136;12252.571160009356;12311.56909508163;12879.742405151657;12973.54592589255;13739;13844.930775346058;11613.477393517482;12125.818112728493;12211.572594959134;12608.117431779687;13127.277999710108;12142.39288035577;12201.20692155007;12363.134580579273;12604.747905041664;12949.417276222404;11972;12096.879031209928;12440.440180192094;12535.623931282076;12848.743025139633;10779;11163.482016828557;11192.328460292409;11289.71775705614;11583.884161158281;10866.348019118877;10914.290571112851;10963.232573719763;11068.391238481961;11188.216824822184 05:41:11 INFO - PROCESS | 8659 | __end_tp_report 05:41:11 INFO - PROCESS | 8659 | __start_cc_report 05:41:11 INFO - PROCESS | 8659 | _x_x_mozilla_cycle_collect,366 05:41:11 INFO - PROCESS | 8659 | __end_cc_report 05:41:11 INFO - PROCESS | 8659 | __startTimestamp1463056871596__endTimestamp 05:41:11 INFO - PROCESS | 8659 | 05:41:11 INFO - PROCESS | 8659 | ------- Summary: start ------- 05:41:11 INFO - PROCESS | 8659 | Number of tests: 6 05:41:11 INFO - PROCESS | 8659 | 05:41:11 INFO - PROCESS | 8659 | [#0] dojo.html Cycles:165 Average:5375.15 Median:5608.24 stddev:720.62 (12.8%) stddev-sans-first:701.26 05:41:11 INFO - PROCESS | 8659 | Values: 3144.4 3172.6 3200.6 3220.1 3220.8 5392.8 5418.5 5436.6 5461.9 5493.7 5256.5 5399.1 5419.8 5430.5 5469.3 4813.1 4847.3 4970.1 4982.3 5037.2 953.6 4833.4 4977.3 4998.3 5029.4 4737.7 4805.4 4816.2 4827.4 4853.4 4702.6 4800.3 4836.9 4841.7 4894.2 4780.7 4799.9 4811.6 4834.3 4835.7 5251.5 5298.9 5299.6 5299.9 5391.4 5704.1 5715.9 5783.9 5793.5 5803.4 5795.5 5826.7 5850.5 5855.9 5917.1 5426.1 5456.5 5456.6 5458.1 5462.0 5827.9 5935.8 5965.5 6003.3 6074.7 5833.6 5858.3 5858.5 5862.2 5868.3 5956.6 5958.4 5969.3 5970.7 5981.7 5692.9 5769.4 5789.7 5832.7 5863.9 5591.8 5727.7 5733.6 5779.7 5856.4 4339.1 4364.4 4372.7 4396.5 4410.3 3911.6 3930.9 3933.5 3950.6 3965.1 5557.9 5588.7 5592.8 5600.6 5602.1 5832.9 5893.7 5903.1 5930.1 5936.9 5402.6 5598.8 5605.2 5621.8 5646.7 5062.9 5087.9 5099.3 5117.0 5119.7 5914.6 6138.8 6140.4 6177.2 6181.4 5816.7 5825.7 5849.7 5877.8 5912.1 6016.9 6020.6 6031.3 6035.6 6046.1 5589.7 5644.4 5769.7 5774.5 5777.3 5764.4 5826.1 5832.8 5833.8 5882.8 5637.2 5658.3 5671.7 5707.1 5714.3 5550.3 5623.7 5657.1 5667.7 5675.2 5411.0 5461.1 5470.1 5553.3 5689.3 5661.1 5695.5 5705.6 5709.8 5768.8 5566.6 5567.8 5606.6 5608.2 5639.1 05:41:11 INFO - PROCESS | 8659 | 05:41:11 INFO - PROCESS | 8659 | [#1] ext.html Cycles:180 Average:53712.72 Median:42003.08 stddev:102464.38 (243.9%) stddev-sans-first:102674.17 05:41:11 INFO - PROCESS | 8659 | Values: 583.8 612.0 638.6 640.7 659.4 43169.4 44138.6 44364.1 44424.3 48181.5 44784.3 44935.1 45113.8 45306.5 45325.1 8880.3 9031.9 9034.6 9058.1 9067.5 8887.8 8908.6 8918.2 8954.5 8990.9 8921.6 8932.9 8950.0 8985.3 9021.2 8913.8 8940.1 8977.9 8994.1 9012.8 8835.5 8938.3 8978.3 8991.8 9013.3 41413.4 41518.6 42008.2 42087.8 42165.0 3657.1 3691.2 3700.1 3804.5 3942.0 31410.7 31594.4 31753.0 32090.7 33851.2 4330.4 4369.0 4432.1 4496.9 4569.4 218556.0 220774.0 221826.0 222860.0 223734.0 34773.5 35662.6 35757.5 56431.0 57460.7 36411.8 36670.8 36782.8 36919.0 36939.3 14548.3 14557.3 14585.4 14588.9 14591.6 11890.3 12017.1 12034.1 12076.8 12132.0 8412.3 8442.0 8487.7 8513.4 8564.5 3198.8 3202.4 3224.3 3241.0 3259.4 1041.7 1071.9 1125.0 1139.2 1152.4 32113.7 32531.3 32711.2 32908.2 36335.8 1158.0 1184.5 1187.7 1196.2 1216.6 16549.3 16556.6 16627.3 17013.1 17050.2 452334.0 453027.0 455221.0 457718.0 460249.0 56865.0 56912.4 57051.4 57054.7 57178.0 1388.6 1403.0 1413.6 1435.0 1435.3 30333.5 31209.5 31248.8 31618.7 32145.7 50837.7 55046.7 56541.7 56662.7 62841.7 435047.0 435469.0 438051.0 438538.0 439476.0 43690.1 44366.3 44633.1 44665.0 44973.1 49107.3 49244.5 49358.0 50505.2 50574.7 40879.8 41396.8 42284.8 42982.4 43187.0 41257.4 41569.6 41732.0 41998.0 42269.2 36961.3 39837.2 41180.6 41415.4 41595.6 35456.6 36586.0 36929.0 37273.0 37363.6 28376.9 28783.7 28904.0 29075.9 29224.4 05:41:11 INFO - PROCESS | 8659 | 05:41:11 INFO - PROCESS | 8659 | [#2] jquery.html Cycles:180 Average:30279.30 Median:17854.52 stddev:82792.12 (463.7%) stddev-sans-first:82993.96 05:41:11 INFO - PROCESS | 8659 | Values: 393.2 393.9 397.4 405.5 425.0 13980.9 17890.5 18695.2 19735.6 19741.3 18630.6 18648.3 18998.5 19419.2 19537.5 5593.5 5654.6 5722.0 5777.3 5825.0 5349.4 5687.4 5749.7 5752.3 5794.6 5675.9 5682.4 5709.7 5709.7 5716.3 5321.7 5638.6 5643.5 5685.8 5720.3 5439.2 5486.6 5780.6 5787.3 5789.2 22038.3 22328.7 22447.6 22520.8 22650.2 2380.2 2403.5 2417.5 2479.5 2967.7 14240.2 15387.9 15458.8 15955.3 16173.0 2034.4 2145.8 2423.0 2450.8 2482.1 93754.5 96057.5 96075.5 96850.0 98755.0 17009.4 17767.7 18042.5 18183.7 18276.5 32741.3 32943.7 33101.0 33282.2 33358.7 9767.2 10030.4 10064.1 10104.0 10202.2 8128.3 8160.1 8189.9 8274.3 8313.0 8736.8 8938.1 8984.6 8988.7 9105.5 3410.2 3481.2 3527.0 3615.5 3640.7 921.3 1027.9 1031.2 1065.5 1069.3 17985.2 18686.6 18744.4 18799.7 19003.5 988.0 1025.0 1032.1 1039.4 1047.9 10181.7 10384.9 10583.3 10613.9 10640.1 488037.0 488504.0 505207.0 512951.0 518248.0 51479.2 64863.0 92957.5 93655.0 94721.5 1023.1 1032.9 1044.8 1047.9 1059.7 16597.4 16914.8 17030.2 17107.5 17206.1 39285.8 39342.6 39441.6 39998.8 40666.8 56567.4 56981.4 57190.4 57536.7 58193.7 7052.6 7613.7 8336.2 8616.4 8678.9 7495.7 10253.7 12281.6 13032.2 13488.4 11339.7 11519.6 11580.9 11999.9 12058.0 11476.4 11957.6 11990.2 12260.9 12284.4 12061.8 12070.7 12088.0 12120.5 12175.0 8306.4 8310.3 8476.3 8516.2 8562.0 11022.8 11657.1 11829.1 11835.1 11929.2 05:41:11 INFO - PROCESS | 8659 | 05:41:11 INFO - PROCESS | 8659 | [#3] mootools.html Cycles:180 Average:5626.64 Median:4938.35 stddev:3160.23 (64.0%) stddev-sans-first:3153.84 05:41:11 INFO - PROCESS | 8659 | Values: 1494.5 1532.7 1667.7 1807.8 1836.8 7269.2 8126.0 8143.9 8691.7 8692.0 8221.9 8455.8 8486.2 8572.6 8583.6 2264.3 2294.7 2296.7 2300.1 2301.5 2308.4 2321.2 2341.5 2352.9 2355.5 2258.7 2270.0 2275.9 2317.1 2338.3 2257.9 2266.6 2269.4 2270.8 2306.8 2216.3 2218.4 2228.3 2231.9 2234.1 8397.5 8411.7 8412.7 8455.7 8463.8 5055.4 5195.5 5206.6 5248.2 5263.2 3180.1 3243.6 3392.7 3395.7 3400.3 1013.6 1014.9 1059.9 1081.2 1081.5 11764.8 11774.6 11804.3 11812.4 11824.1 4462.8 4566.1 4627.7 4634.4 4645.0 9920.5 9969.1 9980.5 9996.1 10025.6 5216.6 5217.3 5218.7 5223.6 5281.3 4181.4 4619.8 4809.0 4819.6 4858.1 3892.6 3919.1 3994.1 4029.8 4034.1 1284.4 1292.0 1297.8 1300.4 1313.6 946.9 959.1 960.4 970.7 975.8 5382.2 5400.2 5489.7 5596.2 5672.7 920.1 926.1 951.5 994.6 1027.9 3722.5 3849.6 3852.5 3906.1 3956.2 9823.3 10324.6 10351.8 10602.6 10674.7 10478.8 10502.7 10535.3 10590.9 10666.5 7823.7 7827.0 7921.0 7962.7 7964.5 5566.9 5745.9 5847.8 5855.4 5878.0 9586.4 9595.4 9791.4 9870.2 9905.0 9635.2 9749.4 9980.1 10001.6 10110.0 6524.2 7418.2 7529.9 7584.9 7645.8 8483.5 8506.4 8516.6 8531.1 8544.6 7520.6 7757.8 7766.6 7833.3 7862.6 7468.6 7606.8 7649.1 7698.8 7729.5 6639.7 6721.9 6725.2 6725.4 6734.5 4817.4 4922.4 4931.4 4940.9 4998.6 4879.6 4891.6 4924.8 4932.2 4935.9 05:41:11 INFO - PROCESS | 8659 | 05:41:11 INFO - PROCESS | 8659 | [#4] prototype.html Cycles:180 Average:4874.47 Median:5555.61 stddev:1453.98 (26.2%) stddev-sans-first:1417.76 05:41:11 INFO - PROCESS | 8659 | Values: 344.8 345.6 346.8 352.5 353.9 5586.9 5643.5 5678.2 5684.6 5695.7 5554.0 5557.2 5559.3 5561.6 5582.2 3851.6 4090.5 4197.8 4250.9 4251.7 4200.2 4244.9 4275.9 4277.4 4288.9 4265.6 4265.6 4270.4 4271.9 4280.6 4146.7 4197.9 4229.9 4246.8 4251.7 3722.9 3733.9 3747.4 3754.4 3775.1 5203.2 5309.4 5317.8 5336.5 5355.5 4743.4 4893.2 4912.2 4914.5 4951.2 5720.5 5767.9 5777.4 5790.2 5796.9 4137.2 4189.5 4198.9 4201.6 4219.2 5889.6 6007.8 6015.7 6076.5 6079.8 4225.3 4225.7 4235.3 4246.5 4254.6 4274.6 4307.4 4321.5 4325.2 4327.4 4707.9 4896.5 4919.0 4933.6 4955.9 5859.4 5867.3 5889.4 5895.6 5915.4 1485.4 1502.6 1504.4 1506.8 1508.6 943.2 973.0 983.7 995.9 1016.3 4863.1 4937.2 4978.3 5025.9 5033.7 5259.6 5295.2 5306.6 5337.6 5397.5 4941.7 5025.1 5028.3 5241.9 5675.8 3227.8 3232.6 3233.7 3242.5 3250.2 6168.3 6251.0 6274.5 6278.8 6282.5 5914.7 6162.0 6192.6 6196.4 6216.5 6133.3 6196.8 6243.6 6268.9 6285.7 6020.9 6039.0 6047.7 6081.8 6109.9 5808.5 5946.4 6029.5 6047.9 6063.7 6198.6 6241.7 6256.3 6265.1 6271.0 4119.5 4150.5 4180.0 4220.2 4269.9 5960.3 6020.2 6044.8 6054.4 6064.0 5875.4 5879.3 5911.0 5914.5 5946.0 5622.4 5934.6 5957.3 5973.9 6003.5 5888.0 5888.2 5917.1 5943.1 5946.9 5792.4 5876.8 5916.8 6019.3 6060.5 5768.6 5859.2 5867.8 5880.8 5902.3 05:41:11 INFO - PROCESS | 8659 | 05:41:11 INFO - PROCESS | 8659 | [#5] yui.html Cycles:180 Average:14973.66 Median:44458.06 stddev:17519.62 (39.4%) stddev-sans-first:17535.84 05:41:11 INFO - PROCESS | 8659 | Values: 668.8 677.0 713.0 724.7 735.2 13643.4 14425.4 16096.3 16994.7 17031.5 15611.4 15642.1 15681.5 15742.8 15821.9 704.2 822.3 828.7 829.5 829.7 819.8 819.9 824.9 829.4 831.6 865.3 868.5 869.1 869.2 871.9 856.1 859.2 860.8 861.8 863.8 626.6 631.0 633.6 633.9 634.1 14262.2 14268.1 14274.5 14289.1 14370.3 13265.6 13339.5 13436.4 13769.1 14037.6 10111.8 10155.9 10197.5 10226.3 10295.5 3739.3 3767.2 3786.0 3825.5 3934.8 54270.7 54280.2 54348.9 54488.5 55262.7 10893.9 11096.8 11130.9 11230.7 11420.5 19112.6 19231.8 19245.2 19408.8 19642.1 8067.6 8269.0 8305.6 8384.0 8448.1 4341.6 4348.0 4772.4 4891.2 4961.1 3423.4 3514.8 3526.1 3528.6 3536.3 630.9 650.0 666.0 673.2 673.2 548.2 591.4 606.7 656.9 679.9 8524.2 8553.4 8579.7 8826.8 8848.4 666.5 680.2 682.9 683.7 686.5 4424.5 4487.7 4509.8 4584.0 4615.2 64397.0 65419.0 66074.7 66684.0 67162.3 54086.7 55729.2 56263.7 56568.7 57661.7 52041.2 52189.5 52632.0 52883.7 53316.5 8308.9 8365.2 8457.3 8521.7 8888.0 32809.0 32987.8 33202.7 33228.0 34310.8 41922.0 43580.3 44310.3 44605.8 44669.6 11554.9 11585.7 11672.8 11697.7 12252.6 12311.6 12879.7 12973.5 13739.0 13844.9 11613.5 12125.8 12211.6 12608.1 13127.3 12142.4 12201.2 12363.1 12604.7 12949.4 11972.0 12096.9 12440.4 12535.6 12848.7 10779.0 11163.5 11192.3 11289.7 11583.9 10866.3 10914.3 10963.2 11068.4 11188.2 05:41:11 INFO - PROCESS | 8659 | -------- Summary: end -------- 05:41:11 INFO - PROCESS | 8659 | 05:41:12 INFO - TEST-INFO | 8659: exit 0 05:41:12 INFO - TEST-OK | dromaeo_css | took 1109739ms 05:41:12 INFO - TEST-START | kraken 05:41:12 INFO - operating with platform_type : linux_ 05:41:12 INFO - Initialising browser for kraken test... 05:41:12 INFO - TEST-INFO | started process 8892 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpwmZ5Z0/profile http://localhost:47633/getInfo.html) 05:41:15 INFO - PROCESS | 8892 | 05:41:15 INFO - PROCESS | 8892 | (process:8961): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 05:41:16 INFO - PROCESS | 8892 | __metrics Screen width/height:1600/1200 05:41:16 INFO - PROCESS | 8892 | colorDepth:24 05:41:16 INFO - PROCESS | 8892 | Browser inner width/height: 1024/697 05:41:16 INFO - PROCESS | 8892 | __metrics 05:41:16 ERROR - PROCESS | 8892 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 05:41:17 INFO - TEST-INFO | 8892: exit 0 05:41:17 INFO - Browser initialized. 05:41:17 INFO - Running cycle 1/1 for kraken test... 05:41:17 INFO - TEST-INFO | started process 9012 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpwmZ5Z0/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:41:19 INFO - PROCESS | 9012 | 05:41:19 INFO - PROCESS | 9012 | (process:9072): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 05:41:29 INFO - PROCESS | 9012 | RSS: Main: 163160064 05:41:29 INFO - PROCESS | 9012 | 05:41:31 INFO - PROCESS | 9012 | Cycle 1(1): loaded http://localhost:47633/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:47633/tests/kraken/driver.html?testName=audio-beat-detection) 05:41:32 INFO - PROCESS | 9012 | RSS: Main: 167440384 05:41:32 INFO - PROCESS | 9012 | 05:41:35 INFO - PROCESS | 9012 | Cycle 1(1): loaded http://localhost:47633/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:47633/tests/kraken/driver.html?testName=audio-dft) 05:41:36 INFO - PROCESS | 9012 | RSS: Main: 167718912 05:41:36 INFO - PROCESS | 9012 | 05:41:38 INFO - PROCESS | 9012 | Cycle 1(1): loaded http://localhost:47633/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:47633/tests/kraken/driver.html?testName=audio-fft) 05:41:39 INFO - PROCESS | 9012 | RSS: Main: 162897920 05:41:39 INFO - PROCESS | 9012 | 05:41:41 INFO - PROCESS | 9012 | Cycle 1(1): loaded http://localhost:47633/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:47633/tests/kraken/driver.html?testName=audio-oscillator) 05:41:42 INFO - PROCESS | 9012 | RSS: Main: 163205120 05:41:42 INFO - PROCESS | 9012 | 05:41:44 INFO - PROCESS | 9012 | Cycle 1(1): loaded http://localhost:47633/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:47633/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:41:45 INFO - PROCESS | 9012 | RSS: Main: 163168256 05:41:45 INFO - PROCESS | 9012 | 05:41:48 INFO - PROCESS | 9012 | Cycle 1(1): loaded http://localhost:47633/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:47633/tests/kraken/driver.html?testName=imaging-darkroom) 05:41:49 INFO - PROCESS | 9012 | RSS: Main: 163209216 05:41:49 INFO - PROCESS | 9012 | 05:41:52 INFO - PROCESS | 9012 | Cycle 1(1): loaded http://localhost:47633/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:47633/tests/kraken/driver.html?testName=imaging-desaturate) 05:41:53 INFO - PROCESS | 9012 | RSS: Main: 161824768 05:41:53 INFO - PROCESS | 9012 | 05:41:56 INFO - PROCESS | 9012 | Cycle 1(1): loaded http://localhost:47633/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:47633/tests/kraken/driver.html?testName=json-parse-financial) 05:41:56 INFO - PROCESS | 9012 | RSS: Main: 162639872 05:41:56 INFO - PROCESS | 9012 | 05:41:58 INFO - PROCESS | 9012 | Cycle 1(1): loaded http://localhost:47633/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:47633/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:41:59 INFO - PROCESS | 9012 | RSS: Main: 162889728 05:41:59 INFO - PROCESS | 9012 | 05:42:00 INFO - PROCESS | 9012 | Cycle 1(1): loaded http://localhost:47633/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:47633/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:42:01 INFO - PROCESS | 9012 | RSS: Main: 162926592 05:42:01 INFO - PROCESS | 9012 | 05:42:03 INFO - PROCESS | 9012 | Cycle 1(1): loaded http://localhost:47633/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:47633/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:42:04 INFO - PROCESS | 9012 | RSS: Main: 163119104 05:42:04 INFO - PROCESS | 9012 | 05:42:07 INFO - PROCESS | 9012 | Cycle 1(1): loaded http://localhost:47633/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:47633/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:42:07 INFO - PROCESS | 9012 | RSS: Main: 163594240 05:42:07 INFO - PROCESS | 9012 | 05:42:10 INFO - PROCESS | 9012 | Cycle 1(1): loaded http://localhost:47633/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:47633/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:42:11 INFO - PROCESS | 9012 | RSS: Main: 163028992 05:42:11 INFO - PROCESS | 9012 | 05:42:12 INFO - PROCESS | 9012 | Cycle 1(1): loaded http://localhost:47633/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:47633/tests/kraken/driver.html?testName=ai-astar) 05:42:13 INFO - PROCESS | 9012 | RSS: Main: 164945920 05:42:13 INFO - PROCESS | 9012 | 05:42:13 INFO - PROCESS | 9012 | __start_tp_report 05:42:13 INFO - PROCESS | 9012 | _x_x_mozilla_page_load 05:42:13 INFO - PROCESS | 9012 | _x_x_mozilla_page_load_details 05:42:13 INFO - PROCESS | 9012 | |i|pagename|runs| 05:42:13 INFO - PROCESS | 9012 | |0;ai-astar;108;113;103;110;111;111;105;109;110;109 05:42:13 INFO - PROCESS | 9012 | |1;audio-beat-detection;150;124;153;198;125;149;152;129;190;126 05:42:13 INFO - PROCESS | 9012 | |2;audio-dft;163;163;164;162;166;169;167;162;163;164 05:42:13 INFO - PROCESS | 9012 | |3;audio-fft;75;78;75;168;76;76;73;84;171;75 05:42:13 INFO - PROCESS | 9012 | |4;audio-oscillator;91;91;180;92;93;92;145;92;92;147 05:42:13 INFO - PROCESS | 9012 | |5;imaging-gaussian-blur;118;117;118;117;117;117;117;117;117;120 05:42:13 INFO - PROCESS | 9012 | |6;imaging-darkroom;147;145;148;146;147;147;146;146;145;146 05:42:13 INFO - PROCESS | 9012 | |7;imaging-desaturate;100;95;96;96;95;95;96;96;95;95 05:42:13 INFO - PROCESS | 9012 | |8;json-parse-financial;59;59;60;60;60;58;58;110;58;56 05:42:13 INFO - PROCESS | 9012 | |9;json-stringify-tinderbox;58;57;58;61;59;58;58;58;60;58 05:42:13 INFO - PROCESS | 9012 | |10;stanford-crypto-aes;78;84;79;79;79;81;80;81;80;79 05:42:13 INFO - PROCESS | 9012 | |11;stanford-crypto-ccm;128;126;132;125;142;135;137;126;188;126 05:42:13 INFO - PROCESS | 9012 | |12;stanford-crypto-pbkdf2;170;178;170;176;173;169;171;170;167;171 05:42:13 INFO - PROCESS | 9012 | |13;stanford-crypto-sha256-iterative;69;66;64;66;69;64;65;63;64;64 05:42:13 INFO - PROCESS | 9012 | __end_tp_report 05:42:13 INFO - PROCESS | 9012 | __start_cc_report 05:42:13 INFO - PROCESS | 9012 | _x_x_mozilla_cycle_collect,837 05:42:13 INFO - PROCESS | 9012 | __end_cc_report 05:42:13 INFO - PROCESS | 9012 | __startTimestamp1463056933161__endTimestamp 05:42:13 INFO - PROCESS | 9012 | 05:42:13 INFO - PROCESS | 9012 | ------- Summary: start ------- 05:42:13 INFO - PROCESS | 9012 | Number of tests: 14 05:42:13 INFO - PROCESS | 9012 | 05:42:13 INFO - PROCESS | 9012 | [#0] ai-astar Cycles:10 Average:108.90 Median:110.00 stddev:2.96 (2.7%) stddev-sans-first:3.12 05:42:13 INFO - PROCESS | 9012 | Values: 108.0 113.0 103.0 110.0 111.0 111.0 105.0 109.0 110.0 109.0 05:42:13 INFO - PROCESS | 9012 | 05:42:13 INFO - PROCESS | 9012 | [#1] audio-beat-detection Cycles:10 Average:149.60 Median:151.00 stddev:26.32 (17.4%) stddev-sans-first:27.92 05:42:13 INFO - PROCESS | 9012 | Values: 150.0 124.0 153.0 198.0 125.0 149.0 152.0 129.0 190.0 126.0 05:42:13 INFO - PROCESS | 9012 | 05:42:13 INFO - PROCESS | 9012 | [#2] audio-dft Cycles:10 Average:164.30 Median:164.00 stddev:2.31 (1.4%) stddev-sans-first:2.40 05:42:13 INFO - PROCESS | 9012 | Values: 163.0 163.0 164.0 162.0 166.0 169.0 167.0 162.0 163.0 164.0 05:42:13 INFO - PROCESS | 9012 | 05:42:13 INFO - PROCESS | 9012 | [#3] audio-fft Cycles:10 Average:95.10 Median:75.50 stddev:39.33 (52.1%) stddev-sans-first:41.04 05:42:13 INFO - PROCESS | 9012 | Values: 75.0 78.0 75.0 168.0 76.0 76.0 73.0 84.0 171.0 75.0 05:42:13 INFO - PROCESS | 9012 | 05:42:13 INFO - PROCESS | 9012 | [#4] audio-oscillator Cycles:10 Average:111.50 Median:92.00 stddev:32.96 (35.8%) stddev-sans-first:34.12 05:42:13 INFO - PROCESS | 9012 | Values: 91.0 91.0 180.0 92.0 93.0 92.0 145.0 92.0 92.0 147.0 05:42:13 INFO - PROCESS | 9012 | 05:42:13 INFO - PROCESS | 9012 | [#5] imaging-gaussian-blur Cycles:10 Average:117.50 Median:117.00 stddev:0.97 (0.8%) stddev-sans-first:1.01 05:42:13 INFO - PROCESS | 9012 | Values: 118.0 117.0 118.0 117.0 117.0 117.0 117.0 117.0 117.0 120.0 05:42:13 INFO - PROCESS | 9012 | 05:42:13 INFO - PROCESS | 9012 | [#6] imaging-darkroom Cycles:10 Average:146.30 Median:146.50 stddev:0.95 (0.6%) stddev-sans-first:0.97 05:42:13 INFO - PROCESS | 9012 | Values: 147.0 145.0 148.0 146.0 147.0 147.0 146.0 146.0 145.0 146.0 05:42:13 INFO - PROCESS | 9012 | 05:42:13 INFO - PROCESS | 9012 | [#7] imaging-desaturate Cycles:10 Average:95.90 Median:95.50 stddev:1.52 (1.6%) stddev-sans-first:0.53 05:42:13 INFO - PROCESS | 9012 | Values: 100.0 95.0 96.0 96.0 95.0 95.0 96.0 96.0 95.0 95.0 05:42:13 INFO - PROCESS | 9012 | 05:42:13 INFO - PROCESS | 9012 | [#8] json-parse-financial Cycles:10 Average:63.80 Median:59.00 stddev:16.28 (27.6%) stddev-sans-first:17.18 05:42:13 INFO - PROCESS | 9012 | Values: 59.0 59.0 60.0 60.0 60.0 58.0 58.0 110.0 58.0 56.0 05:42:13 INFO - PROCESS | 9012 | 05:42:13 INFO - PROCESS | 9012 | [#9] json-stringify-tinderbox Cycles:10 Average:58.50 Median:58.00 stddev:1.18 (2.0%) stddev-sans-first:1.24 05:42:13 INFO - PROCESS | 9012 | Values: 58.0 57.0 58.0 61.0 59.0 58.0 58.0 58.0 60.0 58.0 05:42:13 INFO - PROCESS | 9012 | 05:42:13 INFO - PROCESS | 9012 | [#10] stanford-crypto-aes Cycles:10 Average:80.00 Median:80.00 stddev:1.70 (2.1%) stddev-sans-first:1.64 05:42:13 INFO - PROCESS | 9012 | Values: 78.0 84.0 79.0 79.0 79.0 81.0 80.0 81.0 80.0 79.0 05:42:13 INFO - PROCESS | 9012 | 05:42:13 INFO - PROCESS | 9012 | [#11] stanford-crypto-ccm Cycles:10 Average:136.50 Median:133.50 stddev:18.98 (14.2%) stddev-sans-first:19.88 05:42:13 INFO - PROCESS | 9012 | Values: 128.0 126.0 132.0 125.0 142.0 135.0 137.0 126.0 188.0 126.0 05:42:13 INFO - PROCESS | 9012 | 05:42:13 INFO - PROCESS | 9012 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.50 Median:171.00 stddev:3.31 (1.9%) stddev-sans-first:3.46 05:42:13 INFO - PROCESS | 9012 | Values: 170.0 178.0 170.0 176.0 173.0 169.0 171.0 170.0 167.0 171.0 05:42:13 INFO - PROCESS | 9012 | 05:42:13 INFO - PROCESS | 9012 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.40 Median:65.50 stddev:2.12 (3.2%) stddev-sans-first:1.80 05:42:13 INFO - PROCESS | 9012 | Values: 69.0 66.0 64.0 66.0 69.0 64.0 65.0 63.0 64.0 64.0 05:42:13 INFO - PROCESS | 9012 | -------- Summary: end -------- 05:42:13 INFO - PROCESS | 9012 | 05:42:14 INFO - TEST-INFO | 9012: exit 0 05:42:14 INFO - TEST-OK | kraken | took 61854ms 05:42:14 INFO - SUITE-END | took 1171s 05:42:14 INFO - Completed test suite (00:19:31) 05:42:14 INFO - javascript benchmark 05:42:14 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3144.4340018796615, 3172.6351469581004, 3200.6319273283566, 3220.1466611347996, 3220.82285474299, 5392.811251606199, 5418.458154184585, 5436.619436639432, 5461.863453413666, 5493.697846618445, 5256.526912577867, 5399.082156033474, 5419.783208671648, 5430.484104010123, 5469.288992430978, 4813.085513752385, 4847.321375007502, 4970.055689419017, 4982.302477653131, 5037.244413337992, 953.5925887012411, 4833.419989601235, 4977.328060711794, 4998.300237966688, 5029.446760856303, 4737.697133288339, 4805.351277577517, 4816.229403295473, 4827.396575428072, 4853.417589889218, 4702.576768090888, 4800.279958006327, 4836.927446088312, 4841.709497430165, 4894.241392584119, 4780.7092085137165, 4799.880002999918, 4811.566958973709, 4834.347363106006, 4835.661503694742, 5251.474852514756, 5298.894022119574, 5299.6025298102795, 5299.920501192485, 5391.379991301012, 5704.087346024616, 5715.856828634248, 5783.913241301384, 5793.536517078624, 5803.419658034205, 5795.478406943395, 5826.679532625704, 5850.531957443394, 5855.941440585582, 5917.112433135021, 5426.050441172819, 5456.536194423479, 5456.563474922076, 5458.072127738294, 5462.0441422751255, 5827.88344233113, 5935.821925342247, 5965.463108320272, 6003.339632640406, 6074.726637301332, 5833.562482813806, 5858.297004359504, 5858.50202732764, 5862.208601838697, 5868.295804503401, 5956.642601443928, 5958.374370691102, 5969.253843269591, 5970.701464926737, 5981.700914954322, 5692.857678557986, 5769.423057694197, 5789.681567513827, 5832.737526811304, 5863.882722345615, 5591.832245032656, 5727.684977326204, 5733.569982251264, 5779.711014449261, 5856.443637854397, 4339.067100573392, 4364.41080454141, 4372.693911426233, 4396.516383197845, 4410.316400957857, 3911.5892831252877, 3930.941035884464, 3933.468981687493, 3950.6246906543834, 3965.147493288957, 5557.861053473695, 5588.748506317225, 5592.804251851166, 5600.635958662674, 5602.1036634138345, 5832.912506312408, 5893.702510243887, 5903.144044113666, 5930.080837470199, 5936.881262374728, 5402.594805389612, 5598.832035038955, 5605.215269862222, 5621.831345059655, 5646.661200327993, 5062.898742025212, 5087.923681144785, 5099.286099946011, 5117.0, 5119.667221630657, 5914.585978981516, 6138.815835524942, 6140.4473597376455, 6177.166082578884, 6181.443670069625, 5816.738246778989, 5825.737841797045, 5849.678267695318, 5877.764889404376, 5912.142739302691, 6016.87966240664, 6020.578559500968, 6031.306399764083, 6035.637861728311, 6046.09308603713, 5589.664620122806, 5644.407337229696, 5769.653820770767, 5774.538036957118, 5777.335606405317, 5764.394738552392, 5826.067829147316, 5832.766689332379, 5833.766649333979, 5882.794102206402, 5637.154426836008, 5658.292713410824, 5671.716414179357, 5707.143928410772, 5714.257146570919, 5550.250716153268, 5623.662580245198, 5657.12314591239, 5667.688277144796, 5675.233843431084, 5410.972945135407, 5461.1262198048125, 5470.097433923358, 5553.278073850373, 5689.288838895138, 5661.122526008476, 5695.487406133468, 5705.600607957569, 5709.8287051388525, 5768.769249229984, 5566.610337276514, 5567.832965011056, 5606.607537472338, 5608.242887210175, 5639.125935480007], "value": 5327.493709926368, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [583.7810820942146, 611.9877602447951, 638.6072565372295, 640.6924676155448, 659.4164164714226, 43169.35245971309, 44138.55861441389, 44364.11271774563, 44424.3336349955, 48181.518184818226, 44784.3282350765, 44935.101297974106, 45113.77443112788, 45306.54693453065, 45325.093498130016, 8880.33397495189, 9031.864522032174, 9034.638614455447, 9058.139476749731, 9067.45595264283, 8887.777805554848, 8908.599113039929, 8918.197362237397, 8954.507502087383, 8990.910090899106, 8921.643134274622, 8932.910670893272, 8949.95525022374, 8985.326100542452, 9021.233195178418, 8913.777155571097, 8940.061293564213, 8977.865332020025, 8994.100589940987, 9012.77468063297, 8835.514046727427, 8938.284937205008, 8978.326625503114, 8991.775205619912, 9013.324000699908, 41413.37879931804, 41518.58481415208, 42008.15983680309, 42087.789561052, 42165.0, 3657.140571965586, 3691.169486865461, 3700.0564855961825, 3804.524434445694, 3942.0, 31410.68589314123, 31594.368112637618, 31753.0, 32090.679093209004, 33851.15372115692, 4330.415393921811, 4368.956310436887, 4432.113577284556, 4496.9325460118125, 4569.390350528561, 218556.0, 220774.0, 221826.0, 222860.0, 223734.0, 34773.47839782405, 35662.643373566454, 35757.46363804545, 56431.0, 57460.71269643625, 36411.81794091013, 36670.8166459166, 36782.816085919396, 36919.0, 36939.26121477556, 14548.345324460424, 14557.344919478648, 14585.41658333676, 14588.927055364868, 14591.562253132422, 11890.346030968207, 12017.098717596215, 12034.097442691658, 12076.818847717292, 12132.0, 8412.284955778705, 8442.0, 8487.70293039753, 8513.36149788756, 8564.48613083217, 3198.792078514889, 3202.407554602406, 3224.27453822888, 3241.0, 3259.3970115528705, 1041.6874339599992, 1071.8713754349371, 1124.9662510124547, 1139.202558209257, 1152.4410660829492, 32113.678863211535, 32531.349373012406, 32711.182220444203, 32908.17729556732, 36335.81832090823, 1158.0, 1184.4788293150987, 1187.709011292239, 1196.1865931166717, 1216.6410908781754, 16549.338026478807, 16556.58608534772, 16627.334906603843, 17013.06428146439, 17050.23273952675, 452334.0, 453027.0, 455221.0, 457718.0, 460249.0, 56865.0, 56912.43087569154, 57051.42948570544, 57054.7147264261, 57178.0, 1388.645895296713, 1402.9859701402856, 1413.5829930170623, 1434.985650143506, 1435.2751860310477, 30333.544996825505, 31209.53185702171, 31248.843755781076, 31618.683813162494, 32145.678543214268, 50837.74581127219, 55046.72476637591, 56541.71729141493, 56662.7166864163, 62841.68579157075, 435047.0, 435469.0, 438051.0, 438538.0, 439476.0, 43690.12619747651, 44366.33450498181, 44633.10733785371, 44665.0, 44973.10053798971, 49107.26339104845, 49244.50755492399, 49358.0, 50505.24242136297, 50574.747126264134, 40879.795601023, 41396.79301603593, 42284.788576056926, 42982.35526467043, 43187.0, 41257.38113928233, 41569.58430415778, 41732.0, 41998.0, 42269.154616906875, 36961.26077478382, 39837.203255935296, 41180.58819411887, 41415.37876931909, 41595.58404416038, 35456.64543354533, 36586.0, 36929.0, 37273.0, 37363.62636373602, 28376.85811570929, 28783.712162878102, 28904.0, 29075.85462072676, 29224.415511690073], "value": 18949.037821386024, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [393.1959143551437, 393.89955561331874, 397.3562828218286, 405.50933370621544, 424.9978750106249, 13980.93009534951, 17890.46328610142, 18695.158717857696, 19735.605287894232, 19741.309054183108, 18630.62738745224, 18648.34730784423, 18998.525036874085, 19419.223231070777, 19537.511562210915, 5593.468620481058, 5654.632448890829, 5722.0, 5777.335606405275, 5825.0, 5349.411564727877, 5687.374388817227, 5749.683767392792, 5752.309722833266, 5794.5943783935145, 5675.9432405675825, 5682.374938756733, 5709.65742055478, 5709.71451427429, 5716.342620598622, 5321.680699158062, 5638.577106716992, 5643.492085712285, 5685.772569097231, 5720.2849643794525, 5439.238506609078, 5486.588505862076, 5780.5953583248765, 5787.33445653751, 5789.247397838254, 22038.33884983453, 22328.66028038307, 22447.551048979094, 22520.774792252196, 22650.207242746426, 2380.190735150057, 2403.542061986073, 2417.5164967006667, 2479.4917042006527, 2967.6883927187764, 14240.216788076652, 15387.923060384739, 15458.845411545853, 15955.282012309473, 16173.029618222827, 2034.3896830950655, 2145.753238377575, 2422.9515409691708, 2450.754924507553, 2482.131254061082, 93754.53122734342, 96057.51971240099, 96075.51962240213, 96850.0, 98755.0, 17009.404670836462, 17767.733483997752, 18042.458726238234, 18183.727244091348, 18276.543086422946, 32741.345173096404, 32943.67056329454, 33101.0, 33282.16794580106, 33358.66641333604, 9767.16979056774, 10030.44832534203, 10064.14454771338, 10104.04011618895, 10202.183825293958, 8128.349732021424, 8160.102388737234, 8189.918100819034, 8274.338052955749, 8312.958435207785, 8736.78158046054, 8938.10618938114, 8984.595693193822, 8988.685396011108, 9105.499197544066, 3410.2156504003724, 3481.216726236627, 3527.0, 3615.475756015363, 3640.7087433005295, 921.2721949659827, 1027.8715160604925, 1031.159604921986, 1065.4938904020528, 1069.337011053152, 17985.190666420043, 18686.626267474847, 18744.437666870017, 18799.718004229948, 19003.539884866237, 988.016923161459, 1025.041586116983, 1032.0814475117152, 1039.3763741754888, 1047.9056884880397, 10181.694549163536, 10384.896151038543, 10583.31208471447, 10613.893861061291, 10640.148788096934, 488037.0, 488504.0, 505207.0, 512951.0, 518248.0, 51479.22781158286, 64863.0, 92957.5352123235, 93655.0, 94721.5263923676, 1023.1354505442889, 1032.8812186598636, 1044.8119338518989, 1047.874255089379, 1059.6979860739652, 16597.41909033178, 16914.830851691324, 17030.233639486007, 17107.48677539676, 17206.053667048433, 39285.80357098196, 39342.606573933896, 39441.60558394379, 39998.80000599979, 40666.79666601648, 56567.43432565621, 56981.43018569761, 57190.42809571851, 57536.71231643815, 58193.70903145457, 7052.612106333996, 7613.695452181851, 8336.208060234268, 8616.439931404439, 8678.869816952625, 7495.700171993278, 10253.692389228336, 12281.631551053482, 13032.152910060815, 13488.393022314216, 11339.659810205707, 11519.596814111466, 11580.88419115798, 11999.940000299943, 12058.0, 11476.426178690865, 11957.641270761891, 11990.220635658654, 12260.938695306466, 12284.385780711107, 12061.75876482483, 12070.698232544257, 12088.0, 12120.515179392725, 12175.0, 8306.377021723516, 8310.293625041939, 8476.32189424857, 8516.233538981522, 8562.0, 11022.779544409228, 11657.125715571197, 11829.112816538622, 11835.11236657237, 11929.224600400947], "value": 10056.067299802497, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1494.484402881006, 1532.6551525906668, 1667.749837524372, 1807.8282563156513, 1836.7520384748063, 7269.20038795732, 8125.959370203155, 8143.87784183237, 8691.695790647329, 8692.000419951699, 8221.917780822205, 8455.788605284872, 8486.151384861527, 8572.614232359543, 8583.570821458934, 2264.2527965771296, 2294.701688780456, 2296.701428814252, 2300.114455934469, 2301.470661747799, 2308.4228942764307, 2321.245595181564, 2341.5433990371885, 2352.9176478823247, 2355.528894221154, 2258.7289525256992, 2269.965950510744, 2275.9089636414524, 2317.050009496107, 2338.3452633262627, 2257.8532395394245, 2266.603344414721, 2269.387265438339, 2270.750217476076, 2306.815454763615, 2216.268631351666, 2218.43429925368, 2228.320362289501, 2231.9330420087263, 2234.128689810975, 8397.496150230945, 8411.705590304371, 8412.663493460253, 8455.661773529051, 8463.788405289917, 5055.443901170868, 5195.506426889439, 5206.583473322163, 5248.239005344204, 5263.157894736862, 3180.141361832292, 3243.5783348164823, 3392.6607339265875, 3395.6604339565847, 3400.2828571571445, 1013.6198925402974, 1014.8782146142437, 1059.8569193158903, 1081.194510089988, 1081.4808891732014, 11764.764704705944, 11774.587889423914, 11804.291742495478, 11812.40937953099, 11824.113191510585, 4462.799174037176, 4566.13243483737, 4627.652926030494, 4634.420697412824, 4644.95355046452, 9920.454375009444, 9969.102780749767, 9980.451075190935, 9996.100350968447, 10025.598976041023, 5216.58267338612, 5217.295665085241, 5218.712970786643, 5223.582113430916, 5281.313429254173, 4181.435506206623, 4619.815207391734, 4809.038192361541, 4819.566239038503, 4858.101251268456, 3892.552356479041, 3919.098607320332, 3994.0613955720382, 4029.7582145071387, 4034.0519977805193, 1284.3706583773883, 1291.9935400323127, 1297.7858653322096, 1300.4018151650157, 1313.6387493439381, 946.9195118414874, 959.0649117110761, 960.4093482508207, 970.69908328417, 975.8243516167158, 5382.2464854920345, 5400.21696853954, 5489.670619762827, 5596.160575913564, 5672.744726487318, 920.1396694091046, 926.0878035135386, 951.5289931483821, 994.572333896417, 1027.9280450368403, 3722.516072910504, 3849.557300910431, 3852.5184351956004, 3906.121122747359, 3956.188981258835, 9823.26325525574, 10324.638637647646, 10351.844722329171, 10602.575896964036, 10674.679759607225, 10478.842817357745, 10502.737431564272, 10535.262531622866, 10590.947045264878, 10666.46667666629, 7823.726169584037, 7826.960865195638, 7920.9999999998845, 7962.7213047543055, 7964.482308649919, 5566.860828479321, 5745.913811292918, 5847.8245652630485, 5855.443732845373, 5877.971355012941, 9586.376885502421, 9595.376300540442, 9791.363561368487, 9870.161036311994, 9904.950475247577, 9635.236066277184, 9749.415035097916, 9980.051895069957, 10001.649942251986, 10110.0, 6524.249711283263, 7418.221086786026, 7529.924700752922, 7584.886226706714, 7645.770626881203, 8483.533405662747, 8506.447080939719, 8516.574171291535, 8531.146885311418, 8544.615492302986, 7520.586367749826, 7757.7672669819995, 7766.611669416619, 7833.295003449717, 7862.646180921986, 7468.626568671436, 7606.8098297543, 7649.082110146818, 7698.769036928901, 7729.497582657109, 6639.6680165992475, 6721.865562688621, 6725.19297684281, 6725.428338591275, 6734.494912881455, 4817.445993710769, 4922.360093187863, 4931.3835770528685, 4940.851774446773, 4998.550130488274, 4879.585235254901, 4891.584215341736, 4924.803007879645, 4932.186189278729, 4935.851924442272], "value": 4532.381134911177, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [344.8086312096786, 345.574942820331, 346.81272113058947, 352.5047308531514, 353.918598722294, 5586.86032849179, 5643.492085712285, 5678.205051292812, 5684.573656975723, 5695.686737229451, 5553.972230138854, 5557.249771280876, 5559.332880054389, 5561.63849349792, 5582.162675598663, 3851.61483851614, 4090.4682391289166, 4197.7901104944785, 4250.9149817003645, 4251.681123915703, 4200.180964711883, 4244.893877653052, 4275.850345237917, 4277.443932288814, 4288.935665965013, 4265.552117027714, 4265.616094551489, 4270.377256642471, 4271.8718438446895, 4280.59334363235, 4146.668266538685, 4197.853075142355, 4229.936550951738, 4246.830126794925, 4251.723637963538, 3722.9255414891823, 3733.925321493582, 3747.38168202249, 3754.436834474823, 3775.093977445393, 5203.16749320107, 5309.389420216686, 5317.8404647860625, 5336.4930331618825, 5355.518003379676, 4743.383360163191, 4893.21708526634, 4912.238603016538, 4914.533119353656, 4951.207806750903, 5720.48515633595, 5767.942320576824, 5777.422257774231, 5790.189373487715, 5796.88406231873, 4137.193247316774, 4189.455370801807, 4198.91602167958, 4201.6008479194425, 4219.2194444027955, 5889.587728858982, 6007.7596896124105, 6015.669138197398, 6076.513878889678, 6079.787207447718, 4225.345071513921, 4225.72532785368, 4235.2800023996, 4246.532881383046, 4254.617249393959, 4274.5939135782055, 4307.440032795716, 4321.459817522809, 4325.178216138924, 4327.394164816928, 4707.8701111732935, 4896.485868983706, 4918.9508104919205, 4933.618586795703, 4955.900881982412, 5859.414058594191, 5867.325257595431, 5889.411058894162, 5895.616784908967, 5915.438033386821, 1485.3687182947203, 1502.5942995391183, 1504.3982407037272, 1506.8493150684844, 1508.58513908676, 943.2406912435418, 972.988092383927, 983.685220729374, 995.9253056020827, 1016.3038318751679, 4863.124637565202, 4937.185364414831, 4978.278149668277, 5025.874353141201, 5033.697978121324, 5259.605529585297, 5295.179247216687, 5306.575473962073, 5337.599680024014, 5397.460253974649, 4941.728204948762, 5025.14572522672, 5028.32117664118, 5241.9213711794355, 5675.77296908119, 3227.80633162011, 3232.5636039134415, 3233.708966193054, 3242.4974129010043, 3250.2199472126517, 6168.321484636686, 6250.968745156245, 6274.525486510666, 6278.775638750491, 6282.528810339151, 5914.733836977346, 6161.96919015411, 6192.628442293477, 6196.411340922605, 6216.533759968021, 6133.294671112879, 6196.81409557723, 6243.594166379171, 6268.905966410507, 6285.748570057238, 6020.909686354796, 6038.969805150946, 6047.727852246571, 6081.840946463942, 6109.877802444015, 5808.5062769665055, 5946.43508866657, 6029.51763858899, 6047.909281360695, 6063.727132279147, 6198.597091189058, 6241.719122639583, 6256.280527739186, 6265.091561723616, 6271.0, 4119.464469618931, 4150.481189851269, 4179.9791001046015, 4220.15596880619, 4269.935950960676, 5960.3443621202505, 6020.247469066367, 6044.760824031151, 6054.39456054391, 6063.969680151571, 5875.412458754261, 5879.323877753942, 5910.970445147746, 5914.521369657586, 5946.0, 5622.437756224344, 5934.554908381975, 5957.255343082114, 5973.940260597512, 6003.519718422603, 5888.0, 5888.17565540816, 5917.053271476543, 5943.108533719804, 5946.881062378815, 5792.391798861059, 5876.764929402947, 5916.822495325147, 6019.337872834072, 6060.454559089703, 5768.644368573466, 5859.179714840011, 5867.794627188028, 5880.764769409176, 5902.2622172228475], "value": 4414.870431862728, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [668.8060462465885, 676.9898451523228, 712.9786106416808, 724.6666533394638, 735.2316828913785, 13643.386047627855, 14425.350859211334, 16096.275667594942, 16994.660106797855, 17031.48905532833, 15611.375544978187, 15642.139682317467, 15681.451149209779, 15742.842571574281, 15821.920890395531, 704.2077662629541, 822.2969361196181, 828.668532586964, 829.5478963964654, 829.7096016394244, 819.8278361544053, 819.8563004608568, 824.9381296402764, 829.4318391901567, 831.584207896052, 865.3293697384514, 868.4789126524066, 869.1004810021628, 869.1786261982429, 871.9258862996653, 856.0754385263931, 859.2438653984516, 860.7761981884693, 861.8491763941286, 863.7883718488947, 626.627156841683, 631.034517188702, 633.6134957675814, 633.9397757213058, 634.1344065350809, 14262.215578143197, 14268.072575282573, 14274.500392486318, 14289.142651440947, 14370.281485925661, 13265.602031939057, 13339.533116340881, 13436.395362208725, 13769.105008174536, 14037.578872633838, 10111.797764044752, 10155.89844101557, 10197.49012549377, 10226.335288206243, 10295.536700848479, 3739.3082279778328, 3767.2088861339153, 3786.0345611869075, 3825.5218097737784, 3934.8229329680235, 54270.72864635691, 54280.17793186598, 54348.92383071246, 54488.45511544913, 55262.723686381716, 10893.945530272456, 11096.753672743202, 11130.944345278383, 11230.66308010761, 11420.543178272776, 19112.617747645247, 19231.807681923, 19245.230190792492, 19408.8059119407, 19642.11610477532, 8067.556284404297, 8268.958655206685, 8305.621298820655, 8384.0, 8448.112948140357, 4341.565843415633, 4347.9565204347555, 4772.379590653262, 4891.24185751202, 4961.082199792978, 3423.4180189367867, 3514.789112653249, 3526.083218363193, 3528.6118526962014, 3536.257385948928, 630.8864404407251, 649.954503184782, 666.0309250041179, 673.2022553274386, 673.2056173714965, 548.1531034551627, 591.3701907468533, 606.7209083821491, 656.875193713193, 679.8917764044576, 8524.232819046316, 8553.401261911606, 8579.742607721779, 8826.779330516789, 8848.380613357132, 666.4734859460972, 680.2075581947075, 682.8975653651992, 683.6991723641581, 686.4576984182539, 4424.513303536608, 4487.730736155841, 4509.774511274423, 4583.954160458353, 4615.215413379734, 64397.0, 65419.0, 66074.66962665156, 66684.0, 67162.32837671658, 54086.729566352704, 55729.164062539094, 56263.71868140715, 56568.71715641396, 57661.711691441276, 52041.21938170931, 52189.47810521922, 52632.0, 52883.73558132262, 53316.46683533193, 8308.87536686938, 8365.163483651586, 8457.323414126731, 8521.701740439055, 8888.022317544936, 32809.0, 32987.835060824546, 33202.667973319956, 33228.00315990524, 34310.82844585761, 41922.0, 43580.34629480624, 44310.33534496921, 44605.77697111494, 44669.55330446654, 11554.94222528882, 11585.6524304271, 11672.824907626562, 11697.707557311136, 12252.571160009356, 12311.56909508163, 12879.742405151657, 12973.54592589255, 13739.0, 13844.930775346058, 11613.477393517482, 12125.818112728493, 12211.572594959134, 12608.117431779687, 13127.277999710108, 12142.39288035577, 12201.20692155007, 12363.134580579273, 12604.747905041664, 12949.417276222404, 11972.0, 12096.879031209928, 12440.440180192094, 12535.623931282076, 12848.743025139633, 10779.0, 11163.482016828557, 11192.328460292409, 11289.71775705614, 11583.884161158281, 10866.348019118877, 10914.290571112851, 10963.232573719763, 11068.391238481961, 11188.216824822184], "value": 6723.373885916195, "unit": "score"}], "name": "dromaeo_css", "value": 7176.326734730339, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 113.0, 103.0, 110.0, 111.0, 111.0, 105.0, 109.0, 110.0, 109.0], "value": 108.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 124.0, 153.0, 198.0, 125.0, 149.0, 152.0, 129.0, 190.0, 126.0], "value": 149.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 163.0, 164.0, 162.0, 166.0, 169.0, 167.0, 162.0, 163.0, 164.0], "value": 164.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 78.0, 75.0, 168.0, 76.0, 76.0, 73.0, 84.0, 171.0, 75.0], "value": 95.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 91.0, 180.0, 92.0, 93.0, 92.0, 145.0, 92.0, 92.0, 147.0], "value": 111.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 117.0, 118.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 120.0], "value": 117.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 145.0, 148.0, 146.0, 147.0, 147.0, 146.0, 146.0, 145.0, 146.0], "value": 146.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 95.0, 96.0, 96.0, 95.0, 95.0, 96.0, 96.0, 95.0, 95.0], "value": 95.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 59.0, 60.0, 60.0, 60.0, 58.0, 58.0, 110.0, 58.0, 56.0], "value": 63.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 57.0, 58.0, 61.0, 59.0, 58.0, 58.0, 58.0, 60.0, 58.0], "value": 58.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 84.0, 79.0, 79.0, 79.0, 81.0, 80.0, 81.0, 80.0, 79.0], "value": 80.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [128.0, 126.0, 132.0, 125.0, 142.0, 135.0, 137.0, 126.0, 188.0, 126.0], "value": 136.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 178.0, 170.0, 176.0, 173.0, 169.0, 171.0, 170.0, 167.0, 171.0], "value": 171.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 66.0, 64.0, 66.0, 69.0, 64.0, 65.0, 63.0, 64.0, 64.0], "value": 65.4, "unit": "score"}], "name": "kraken", "value": 1564.8000000000002, "alertThreshold": 2.0}]} 05:42:14 INFO - Return code: 0 05:42:14 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 05:42:14 ERROR - # TBPL SUCCESS # 05:42:14 INFO - Running post-action listener: _resource_record_post_action 05:42:14 INFO - ##### 05:42:14 INFO - ##### Finished run-tests step (success) 05:42:14 INFO - ##### 05:42:14 INFO - Running post-run listener: _resource_record_post_run 05:42:15 INFO - Total resource usage - Wall time: 1189s; CPU: 13.0%; Read bytes: 4096; Write bytes: 539500544; Read time: 12; Write time: 480884 05:42:15 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 244940800; Read time: 0; Write time: 275728 05:42:15 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 4096; Write bytes: 294559744; Read time: 12; Write time: 205156 05:42:15 INFO - Running post-run listener: _upload_blobber_files 05:42:15 INFO - Blob upload gear active. 05:42:15 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:42:15 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 05:42:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:42:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:42:15 INFO - (blobuploader) - INFO - Open directory for files ... 05:42:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:42:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:42:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:42:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:42:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:42:16 INFO - (blobuploader) - INFO - Done attempting. 05:42:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:42:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:42:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:42:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:42:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:42:17 INFO - (blobuploader) - INFO - Done attempting. 05:42:17 INFO - (blobuploader) - INFO - Iteration through files over. 05:42:17 INFO - Return code: 0 05:42:17 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:42:17 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:42:17 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/09bdfe3409f322d5b9351859d54a50432f3f08c0f578dd275fda1a448be1e4273bf40e9ef012033a06357a143369154d2b51527904de3195e29308bea5229660", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fe66e7ea9eb723cbb6d05c3da14a32ada28251f5c9f3f6e811886f3fafbed0cdfcb0d2d08800a80d4a5d975a95be09966cc4d3eb27903635bca817ca3d0fb118"} 05:42:17 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:42:17 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:42:17 INFO - Contents: 05:42:17 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/09bdfe3409f322d5b9351859d54a50432f3f08c0f578dd275fda1a448be1e4273bf40e9ef012033a06357a143369154d2b51527904de3195e29308bea5229660", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fe66e7ea9eb723cbb6d05c3da14a32ada28251f5c9f3f6e811886f3fafbed0cdfcb0d2d08800a80d4a5d975a95be09966cc4d3eb27903635bca817ca3d0fb118"} 05:42:17 INFO - Running post-run listener: copy_logs_to_upload_dir 05:42:17 INFO - Copying logs to upload dir... 05:42:17 INFO - mkdir: /builds/slave/test/build/upload/logs 05:42:17 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1242.775887 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 42 secs) (at 2016-05-12 05:42:17.630670) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-12 05:42:17.633852) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=0274524eba3bdaad075c2247000001d4-1463055161.483907-940509389 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/09bdfe3409f322d5b9351859d54a50432f3f08c0f578dd275fda1a448be1e4273bf40e9ef012033a06357a143369154d2b51527904de3195e29308bea5229660", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fe66e7ea9eb723cbb6d05c3da14a32ada28251f5c9f3f6e811886f3fafbed0cdfcb0d2d08800a80d4a5d975a95be09966cc4d3eb27903635bca817ca3d0fb118"} build_url:https://queue.taskcluster.net/v1/task/L4mV7LDAR2CnVRmLYvfCmw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011252 build_url: 'https://queue.taskcluster.net/v1/task/L4mV7LDAR2CnVRmLYvfCmw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/09bdfe3409f322d5b9351859d54a50432f3f08c0f578dd275fda1a448be1e4273bf40e9ef012033a06357a143369154d2b51527904de3195e29308bea5229660", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fe66e7ea9eb723cbb6d05c3da14a32ada28251f5c9f3f6e811886f3fafbed0cdfcb0d2d08800a80d4a5d975a95be09966cc4d3eb27903635bca817ca3d0fb118"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-12 05:42:17.658432) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 05:42:17.658777) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=0274524eba3bdaad075c2247000001d4-1463055161.483907-940509389 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004369 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 05:42:17.742275) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-12 05:42:17.742597) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-12 05:42:17.742896) ========= ========= Total master_lag: 0.18 =========