builder: fx-team_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-026 starttime: 1463109393.01 results: success (0) buildid: 20160512173002 builduid: a0ca6b4540c14774bbe81b70ad62902f revision: bec48bfd05a8c6703aab63181441869a3dcaa4cc ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-12 20:16:33.014123) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-12 20:16:33.014592) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-12 20:16:33.014882) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-12 20:16:33.110457) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 20:16:33.110709) ========= 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=41bf3e9aed84707956354da8000001c5-1463108004.356592-147707600 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-12 20:16: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.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 227M=0s 2016-05-12 20:16:33 (227 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.216476 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 20:16:33.359225) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 20:16:33.359560) ========= 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=41bf3e9aed84707956354da8000001c5-1463108004.356592-147707600 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.121066 ========= master_lag: 0.01 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 20:16:33.492704) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 20:16:33.493059) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev bec48bfd05a8c6703aab63181441869a3dcaa4cc --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 bec48bfd05a8c6703aab63181441869a3dcaa4cc --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=41bf3e9aed84707956354da8000001c5-1463108004.356592-147707600 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-12 20:16:33,496 truncating revision to first 12 chars 2016-05-12 20:16:33,497 Setting DEBUG logging. 2016-05-12 20:16:33,497 attempt 1/10 2016-05-12 20:16:33,497 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/bec48bfd05a8?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-12 20:16:33,738 unpacking tar archive at: fx-team-bec48bfd05a8/testing/mozharness/ program finished with exit code 0 elapsedTime=0.555340 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 20:16:34.060822) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-12 20:16:34.061137) ========= script_repo_revision: bec48bfd05a8c6703aab63181441869a3dcaa4cc ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-12 20:16:34.061547) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-12 20:16:34.061819) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-12 20:16:34.071843) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2016-05-12 20:16:34.072112) ========= /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=41bf3e9aed84707956354da8000001c5-1463108004.356592-147707600 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 20:16:34 INFO - MultiFileLogger online at 20160512 20:16:34 in /builds/slave/test 20:16:34 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 20:16:34 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 20:16:34 INFO - {'append_to_log': False, 20:16:34 INFO - 'base_work_dir': '/builds/slave/test', 20:16:34 INFO - 'blob_upload_branch': 'Fx-Team', 20:16:34 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 20:16:34 INFO - 'branch': 'Fx-Team', 20:16:34 INFO - 'buildbot_json_path': 'buildprops.json', 20:16:34 INFO - 'config_files': ('talos/linux_config.py',), 20:16:34 INFO - 'default_actions': ('clobber', 20:16:34 INFO - 'read-buildbot-config', 20:16:34 INFO - 'download-and-extract', 20:16:34 INFO - 'populate-webroot', 20:16:34 INFO - 'create-virtualenv', 20:16:34 INFO - 'install', 20:16:34 INFO - 'run-tests'), 20:16:34 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 20:16:34 INFO - 'download_minidump_stackwalk': True, 20:16:34 INFO - 'download_symbols': 'ondemand', 20:16:34 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 20:16:34 INFO - 'tooltool.py': '/tools/tooltool.py', 20:16:34 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 20:16:34 INFO - '/tools/misc-python/virtualenv.py')}, 20:16:34 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 20:16:34 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 20:16:34 INFO - 'installer_path': 'installer.exe', 20:16:34 INFO - 'log_level': 'info', 20:16:34 INFO - 'log_name': 'talos', 20:16:34 INFO - 'log_to_console': True, 20:16:34 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 20:16:34 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 20:16:34 INFO - 'opt_config_files': (), 20:16:34 INFO - 'pip_index': False, 20:16:34 INFO - 'sps_profile': False, 20:16:34 INFO - 'sps_profile_interval': 0, 20:16:34 INFO - 'suite': 'dromaeojs-e10s', 20:16:34 INFO - 'system_bits': '32', 20:16:34 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 20:16:34 INFO - 'title': 'talos-linux64-ix-026', 20:16:34 INFO - 'tooltool_cache': '/builds/tooltool_cache', 20:16:34 INFO - 'use_talos_json': True, 20:16:34 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 20:16:34 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 20:16:34 INFO - 'work_dir': 'build'} 20:16:34 INFO - ##### 20:16:34 INFO - ##### Running clobber step. 20:16:34 INFO - ##### 20:16:34 INFO - Running pre-action listener: _resource_record_pre_action 20:16:34 INFO - Running main action method: clobber 20:16:34 INFO - rmtree: /builds/slave/test/build 20:16:34 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 20:16:36 INFO - Running post-action listener: _resource_record_post_action 20:16:36 INFO - ##### 20:16:36 INFO - ##### Finished clobber step (success) 20:16:36 INFO - ##### 20:16:36 INFO - ##### 20:16:36 INFO - ##### Running read-buildbot-config step. 20:16:36 INFO - ##### 20:16:36 INFO - Running pre-action listener: _resource_record_pre_action 20:16:36 INFO - Running main action method: read_buildbot_config 20:16:36 INFO - Using buildbot properties: 20:16:36 INFO - { 20:16:36 INFO - "project": "", 20:16:36 INFO - "product": "firefox", 20:16:36 INFO - "script_repo_revision": "production", 20:16:36 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs-e10s", 20:16:36 INFO - "repository": "", 20:16:36 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs-e10s", 20:16:36 INFO - "buildid": "20160512173002", 20:16:36 INFO - "pgo_build": "True", 20:16:36 INFO - "basedir": "/builds/slave/test", 20:16:36 INFO - "buildnumber": 460, 20:16:36 INFO - "slavename": "talos-linux64-ix-026", 20:16:36 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 20:16:36 INFO - "platform": "ubuntu64_hw", 20:16:36 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 20:16:36 INFO - "branch": "fx-team", 20:16:36 INFO - "repo_path": "integration/fx-team", 20:16:36 INFO - "revision": "bec48bfd05a8c6703aab63181441869a3dcaa4cc", 20:16:36 INFO - "stage_platform": "linux64-pgo", 20:16:36 INFO - "builduid": "a0ca6b4540c14774bbe81b70ad62902f", 20:16:36 INFO - "slavebuilddir": "test" 20:16:36 INFO - } 20:16:36 INFO - Found installer url https://queue.taskcluster.net/v1/task/Xo5WwOniQJWIVYiSxjFSbQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 20:16:36 INFO - Running post-action listener: _resource_record_post_action 20:16:36 INFO - ##### 20:16:36 INFO - ##### Finished read-buildbot-config step (success) 20:16:36 INFO - ##### 20:16:36 INFO - ##### 20:16:36 INFO - ##### Running download-and-extract step. 20:16:36 INFO - ##### 20:16:36 INFO - Running pre-action listener: _resource_record_pre_action 20:16:36 INFO - Running main action method: download_and_extract 20:16:36 INFO - mkdir: /builds/slave/test/build/tests 20:16:36 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')]} 20:16:36 INFO - https://queue.taskcluster.net/v1/task/Xo5WwOniQJWIVYiSxjFSbQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 20:16:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Xo5WwOniQJWIVYiSxjFSbQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 20:16:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Xo5WwOniQJWIVYiSxjFSbQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 20:16:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Xo5WwOniQJWIVYiSxjFSbQ/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 20:16:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Xo5WwOniQJWIVYiSxjFSbQ/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 20:16:38 INFO - Downloaded 1361 bytes. 20:16:38 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 20:16:38 INFO - Using the following test package requirements: 20:16:38 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 20:16:38 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 20:16:38 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 20:16:38 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 20:16:38 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 20:16:38 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 20:16:38 INFO - u'jsshell-linux-x86_64.zip'], 20:16:38 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 20:16:38 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 20:16:38 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 20:16:38 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 20:16:38 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 20:16:38 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 20:16:38 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 20:16:38 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 20:16:38 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 20:16:38 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 20:16:38 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 20:16:38 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 20:16:38 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')]} 20:16:38 INFO - https://queue.taskcluster.net/v1/task/Xo5WwOniQJWIVYiSxjFSbQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 20:16:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Xo5WwOniQJWIVYiSxjFSbQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 20:16:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Xo5WwOniQJWIVYiSxjFSbQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 20:16:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Xo5WwOniQJWIVYiSxjFSbQ/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 20:16:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Xo5WwOniQJWIVYiSxjFSbQ/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 20:16:39 INFO - Downloaded 23902869 bytes. 20:16:39 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'] 20:16:39 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 20:16:41 INFO - Return code: 0 20:16:41 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 20:16: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')]} 20:16:41 INFO - https://queue.taskcluster.net/v1/task/Xo5WwOniQJWIVYiSxjFSbQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 20:16:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Xo5WwOniQJWIVYiSxjFSbQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 20:16:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Xo5WwOniQJWIVYiSxjFSbQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 20:16:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Xo5WwOniQJWIVYiSxjFSbQ/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 20:16:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Xo5WwOniQJWIVYiSxjFSbQ/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 20:16:46 INFO - Downloaded 23902869 bytes. 20:16:46 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'] 20:16:46 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 20:16:48 INFO - Return code: 0 20:16:48 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')]} 20:16:48 INFO - https://queue.taskcluster.net/v1/task/Xo5WwOniQJWIVYiSxjFSbQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 20:16:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Xo5WwOniQJWIVYiSxjFSbQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 20:16:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Xo5WwOniQJWIVYiSxjFSbQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 20:16:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Xo5WwOniQJWIVYiSxjFSbQ/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 20:16:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Xo5WwOniQJWIVYiSxjFSbQ/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 20:16:49 INFO - Downloaded 10840239 bytes. 20:16:49 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'] 20:16:49 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 20:16:49 INFO - Return code: 0 20:16:49 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')]} 20:16:49 INFO - https://queue.taskcluster.net/v1/task/Xo5WwOniQJWIVYiSxjFSbQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 20:16:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Xo5WwOniQJWIVYiSxjFSbQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 20:16:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Xo5WwOniQJWIVYiSxjFSbQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 20:16:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Xo5WwOniQJWIVYiSxjFSbQ/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 20:16:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Xo5WwOniQJWIVYiSxjFSbQ/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 20:16:50 INFO - Downloaded 58370315 bytes. 20:16:50 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Xo5WwOniQJWIVYiSxjFSbQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 20:16:50 INFO - mkdir: /builds/slave/test/properties 20:16:50 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 20:16:50 INFO - Writing to file /builds/slave/test/properties/build_url 20:16:50 INFO - Contents: 20:16:50 INFO - build_url:https://queue.taskcluster.net/v1/task/Xo5WwOniQJWIVYiSxjFSbQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 20:16:51 INFO - Running post-action listener: _resource_record_post_action 20:16:51 INFO - Running post-action listener: set_extra_try_arguments 20:16:51 INFO - ##### 20:16:51 INFO - ##### Finished download-and-extract step (success) 20:16:51 INFO - ##### 20:16:51 INFO - ##### 20:16:51 INFO - ##### Running populate-webroot step. 20:16:51 INFO - ##### 20:16:51 INFO - Running pre-action listener: _resource_record_pre_action 20:16:51 INFO - Running main action method: populate_webroot 20:16:51 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 20:16:51 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 20:16:51 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 20:16:51 INFO - '--tppagecycles', 20:16:51 INFO - '7'], 20:16:51 INFO - 'tests': ['tsvgm']}}, 20:16:51 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 20:16:51 INFO - 'tests': ['tresize', 'tcanvasmark']}, 20:16:51 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 20:16:51 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 20:16:51 INFO - 'tests': ['dromaeo_css', 'kraken']}, 20:16:51 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 20:16:51 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:16:51 INFO - 'talos_options': ['--disable-e10s'], 20:16:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:16:51 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:16:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:16:51 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:16:51 INFO - 'talos_options': ['--disable-e10s'], 20:16:51 INFO - 'tests': ['damp', 'tps']}, 20:16:51 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:16:51 INFO - 'tests': ['damp', 'tps']}, 20:16:51 INFO - 'g3': {'talos_options': ['--disable-e10s'], 20:16:51 INFO - 'tests': ['dromaeo_dom']}, 20:16:51 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 20:16:51 INFO - 'other': {'talos_options': ['--disable-e10s'], 20:16:51 INFO - 'tests': ['a11yr', 20:16:51 INFO - 'ts_paint', 20:16:51 INFO - 'tpaint', 20:16:51 INFO - 'sessionrestore', 20:16:51 INFO - 'sessionrestore_no_auto_restore', 20:16:51 INFO - 'tabpaint']}, 20:16:51 INFO - 'other-e10s': {'tests': ['a11yr', 20:16:51 INFO - 'ts_paint', 20:16:51 INFO - 'tpaint', 20:16:51 INFO - 'sessionrestore', 20:16:51 INFO - 'sessionrestore_no_auto_restore', 20:16:51 INFO - 'tabpaint']}, 20:16:51 INFO - 'other-e10s_l64': {'tests': ['a11yr', 20:16:51 INFO - 'ts_paint', 20:16:51 INFO - 'tpaint', 20:16:51 INFO - 'sessionrestore', 20:16:51 INFO - 'sessionrestore_no_auto_restore', 20:16:51 INFO - 'tabpaint']}, 20:16:51 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 20:16:51 INFO - 'ts_paint', 20:16:51 INFO - 'tpaint', 20:16:51 INFO - 'sessionrestore', 20:16:51 INFO - 'sessionrestore_no_auto_restore', 20:16:51 INFO - 'tabpaint']}, 20:16:51 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 20:16:51 INFO - 'tests': ['a11yr', 20:16:51 INFO - 'ts_paint', 20:16:51 INFO - 'tpaint', 20:16:51 INFO - 'sessionrestore', 20:16:51 INFO - 'sessionrestore_no_auto_restore', 20:16:51 INFO - 'tabpaint']}, 20:16:51 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 20:16:51 INFO - 'tests': ['a11yr', 20:16:51 INFO - 'ts_paint', 20:16:51 INFO - 'tpaint', 20:16:51 INFO - 'sessionrestore', 20:16:51 INFO - 'sessionrestore_no_auto_restore', 20:16:51 INFO - 'tabpaint']}, 20:16:51 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 20:16:51 INFO - 'tests': ['tsvgx', 20:16:51 INFO - 'tsvgr_opacity', 20:16:51 INFO - 'tart', 20:16:51 INFO - 'tscrollx', 20:16:51 INFO - 'cart']}, 20:16:51 INFO - 'svgr-e10s': {'tests': ['tsvgx', 20:16:51 INFO - 'tsvgr_opacity', 20:16:51 INFO - 'tart', 20:16:51 INFO - 'tscrollx', 20:16:51 INFO - 'cart']}, 20:16:51 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:16:51 INFO - 'talos_options': ['--disable-e10s'], 20:16:51 INFO - 'tests': ['tp5o']}, 20:16:51 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:16:51 INFO - 'tests': ['tp5o']}, 20:16:51 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:16:51 INFO - 'talos_options': ['--disable-e10s', 20:16:51 INFO - '--xperf_path', 20:16:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:16:51 INFO - 'tests': ['tp5n']}, 20:16:51 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:16:51 INFO - 'talos_options': ['--xperf_path', 20:16:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:16:51 INFO - 'tests': ['tp5n']}}, 20:16:51 INFO - 'talos.zip': {'path': '', 20:16:51 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 20:16:51 INFO - Running post-action listener: _resource_record_post_action 20:16:51 INFO - ##### 20:16:51 INFO - ##### Finished populate-webroot step (success) 20:16:51 INFO - ##### 20:16:51 INFO - ##### 20:16:51 INFO - ##### Running create-virtualenv step. 20:16:51 INFO - ##### 20:16:51 INFO - Running pre-action listener: _resource_record_pre_action 20:16:51 INFO - Running main action method: create_virtualenv 20:16:51 INFO - Creating virtualenv /builds/slave/test/build/venv 20:16:51 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 20:16:51 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 20:16:51 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 20:16:51 INFO - Using real prefix '/usr' 20:16:51 INFO - New python executable in /builds/slave/test/build/venv/bin/python 20:16:52 INFO - Installing distribute.............................................................................................................................................................................................done. 20:16:54 INFO - Installing pip.................done. 20:16:54 INFO - Return code: 0 20:16:54 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 20:16: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')]} 20:16:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:16:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:16: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')]} 20:16:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:16:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:16:54 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 0x7fdb8fe77f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23658a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2430770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24bf9c0>, '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 0x24c08c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24c0d50>, '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': '41bf3e9aed84707956354da8000001c5-1463108004.356592-147707600', '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 20:16:54 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 20:16:54 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 20:16:54 INFO - Using env: {'DISPLAY': ':0', 20:16:54 INFO - 'HOME': '/home/cltbld', 20:16:54 INFO - 'LANG': 'en_US.UTF-8', 20:16:54 INFO - 'LANGUAGE': 'en_US:en', 20:16:54 INFO - 'LOGNAME': 'cltbld', 20:16:54 INFO - 'MAIL': '/var/mail/cltbld', 20:16:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:16:54 INFO - 'MOZ_NO_REMOTE': '1', 20:16:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:16:54 INFO - 'NO_EM_RESTART': '1', 20:16:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:16:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:16:54 INFO - 'PWD': '/builds/slave/test', 20:16:54 INFO - 'SHELL': '/bin/bash', 20:16:54 INFO - 'SHLVL': '1', 20:16:54 INFO - 'TERM': 'linux', 20:16:54 INFO - 'TMOUT': '86400', 20:16:54 INFO - 'USER': 'cltbld', 20:16:54 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1463108004.356592-147707600', 20:16:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:16:54 INFO - '_': '/tools/buildbot/bin/python'} 20:16:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:16:54 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 20:16:54 INFO - Cleaning up... 20:16:54 INFO - Return code: 0 20:16:54 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 20:16: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')]} 20:16:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:16:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:16: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')]} 20:16:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:16:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:16:54 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 0x7fdb8fe77f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23658a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2430770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24bf9c0>, '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 0x24c08c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24c0d50>, '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': '41bf3e9aed84707956354da8000001c5-1463108004.356592-147707600', '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 20:16:54 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 20:16:54 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 20:16:54 INFO - Using env: {'DISPLAY': ':0', 20:16:54 INFO - 'HOME': '/home/cltbld', 20:16:54 INFO - 'LANG': 'en_US.UTF-8', 20:16:54 INFO - 'LANGUAGE': 'en_US:en', 20:16:54 INFO - 'LOGNAME': 'cltbld', 20:16:54 INFO - 'MAIL': '/var/mail/cltbld', 20:16:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:16:54 INFO - 'MOZ_NO_REMOTE': '1', 20:16:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:16:54 INFO - 'NO_EM_RESTART': '1', 20:16:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:16:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:16:54 INFO - 'PWD': '/builds/slave/test', 20:16:54 INFO - 'SHELL': '/bin/bash', 20:16:54 INFO - 'SHLVL': '1', 20:16:54 INFO - 'TERM': 'linux', 20:16:54 INFO - 'TMOUT': '86400', 20:16:54 INFO - 'USER': 'cltbld', 20:16:54 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1463108004.356592-147707600', 20:16:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:16:54 INFO - '_': '/tools/buildbot/bin/python'} 20:16:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:16:54 INFO - Downloading/unpacking psutil>=0.7.1 20:16:54 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 20:16:54 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 20:16:54 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 20:16:54 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 20:16:54 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 20:16:54 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 20:16:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 20:16:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:16:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:16:56 INFO - Installing collected packages: psutil 20:16:56 INFO - Running setup.py install for psutil 20:16:56 INFO - building 'psutil._psutil_linux' extension 20:16:56 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 20:16:58 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 20:16:58 INFO - building 'psutil._psutil_posix' extension 20:16:58 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 20:16:58 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 20:16:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:16:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:16:58 INFO - Successfully installed psutil 20:16:58 INFO - Cleaning up... 20:16:58 INFO - Return code: 0 20:16:58 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 20:16: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')]} 20:16:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:16:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:16: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')]} 20:16:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:16:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:16:58 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 0x7fdb8fe77f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23658a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2430770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24bf9c0>, '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 0x24c08c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24c0d50>, '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': '41bf3e9aed84707956354da8000001c5-1463108004.356592-147707600', '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 20:16:58 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 20:16:58 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 20:16:58 INFO - Using env: {'DISPLAY': ':0', 20:16:58 INFO - 'HOME': '/home/cltbld', 20:16:58 INFO - 'LANG': 'en_US.UTF-8', 20:16:58 INFO - 'LANGUAGE': 'en_US:en', 20:16:58 INFO - 'LOGNAME': 'cltbld', 20:16:58 INFO - 'MAIL': '/var/mail/cltbld', 20:16:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:16:58 INFO - 'MOZ_NO_REMOTE': '1', 20:16:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:16:58 INFO - 'NO_EM_RESTART': '1', 20:16:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:16:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:16:58 INFO - 'PWD': '/builds/slave/test', 20:16:58 INFO - 'SHELL': '/bin/bash', 20:16:58 INFO - 'SHLVL': '1', 20:16:58 INFO - 'TERM': 'linux', 20:16:58 INFO - 'TMOUT': '86400', 20:16:58 INFO - 'USER': 'cltbld', 20:16:58 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1463108004.356592-147707600', 20:16:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:16:58 INFO - '_': '/tools/buildbot/bin/python'} 20:16:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:16:58 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 20:16:58 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 20:16:58 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 20:16:58 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 20:16:58 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 20:16:58 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 20:16:58 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 20:17:00 INFO - Downloading mozsystemmonitor-0.0.tar.gz 20:17:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 20:17:00 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 20:17:00 INFO - Installing collected packages: mozsystemmonitor 20:17:00 INFO - Running setup.py install for mozsystemmonitor 20:17:00 INFO - Successfully installed mozsystemmonitor 20:17:00 INFO - Cleaning up... 20:17:00 INFO - Return code: 0 20:17:00 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 20:17:00 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')]} 20:17:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:17:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:17:00 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')]} 20:17:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:17:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:17:00 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 0x7fdb8fe77f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23658a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2430770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24bf9c0>, '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 0x24c08c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24c0d50>, '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': '41bf3e9aed84707956354da8000001c5-1463108004.356592-147707600', '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 20:17:00 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 20:17:00 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 20:17:00 INFO - Using env: {'DISPLAY': ':0', 20:17:00 INFO - 'HOME': '/home/cltbld', 20:17:00 INFO - 'LANG': 'en_US.UTF-8', 20:17:00 INFO - 'LANGUAGE': 'en_US:en', 20:17:00 INFO - 'LOGNAME': 'cltbld', 20:17:00 INFO - 'MAIL': '/var/mail/cltbld', 20:17:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:17:00 INFO - 'MOZ_NO_REMOTE': '1', 20:17:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:17:00 INFO - 'NO_EM_RESTART': '1', 20:17:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:17:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:17:00 INFO - 'PWD': '/builds/slave/test', 20:17:00 INFO - 'SHELL': '/bin/bash', 20:17:00 INFO - 'SHLVL': '1', 20:17:00 INFO - 'TERM': 'linux', 20:17:00 INFO - 'TMOUT': '86400', 20:17:00 INFO - 'USER': 'cltbld', 20:17:00 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1463108004.356592-147707600', 20:17:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:17:00 INFO - '_': '/tools/buildbot/bin/python'} 20:17:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:17:00 INFO - Downloading/unpacking blobuploader==1.2.4 20:17:00 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 20:17:00 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 20:17:00 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 20:17:00 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 20:17:00 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 20:17:00 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 20:17:02 INFO - Downloading blobuploader-1.2.4.tar.gz 20:17:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 20:17:02 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 20:17:02 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 20:17:02 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 20:17:02 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 20:17:02 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 20:17:02 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 20:17:02 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 20:17:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 20:17:03 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 20:17: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 20:17: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 20:17: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 20:17: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 20:17: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 20:17: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 20:17:03 INFO - Downloading docopt-0.6.1.tar.gz 20:17:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 20:17:03 INFO - Installing collected packages: blobuploader, requests, docopt 20:17:03 INFO - Running setup.py install for blobuploader 20:17:03 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 20:17:03 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 20:17:03 INFO - Running setup.py install for requests 20:17:04 INFO - Running setup.py install for docopt 20:17:04 INFO - Successfully installed blobuploader requests docopt 20:17:04 INFO - Cleaning up... 20:17:04 INFO - Return code: 0 20:17:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:17:04 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')]} 20:17:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:17:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:17:04 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')]} 20:17:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:17:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:17:04 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 0x7fdb8fe77f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23658a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2430770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24bf9c0>, '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 0x24c08c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24c0d50>, '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': '41bf3e9aed84707956354da8000001c5-1463108004.356592-147707600', '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 20:17:04 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 20:17:04 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 20:17:04 INFO - Using env: {'DISPLAY': ':0', 20:17:04 INFO - 'HOME': '/home/cltbld', 20:17:04 INFO - 'LANG': 'en_US.UTF-8', 20:17:04 INFO - 'LANGUAGE': 'en_US:en', 20:17:04 INFO - 'LOGNAME': 'cltbld', 20:17:04 INFO - 'MAIL': '/var/mail/cltbld', 20:17:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:17:04 INFO - 'MOZ_NO_REMOTE': '1', 20:17:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:17:04 INFO - 'NO_EM_RESTART': '1', 20:17:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:17:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:17:04 INFO - 'PWD': '/builds/slave/test', 20:17:04 INFO - 'SHELL': '/bin/bash', 20:17:04 INFO - 'SHLVL': '1', 20:17:04 INFO - 'TERM': 'linux', 20:17:04 INFO - 'TMOUT': '86400', 20:17:04 INFO - 'USER': 'cltbld', 20:17:04 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1463108004.356592-147707600', 20:17:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:17:04 INFO - '_': '/tools/buildbot/bin/python'} 20:17:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:17:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:17:04 INFO - Running setup.py (path:/tmp/pip-jt1OMz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:17:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:17:04 INFO - Running setup.py (path:/tmp/pip-2fPDk6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:17:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:17:04 INFO - Running setup.py (path:/tmp/pip-y73J9l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:17:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:17:04 INFO - Running setup.py (path:/tmp/pip-SpASrN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:17:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:17:04 INFO - Running setup.py (path:/tmp/pip-Pdr0Ow-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:17:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:17:04 INFO - Running setup.py (path:/tmp/pip-3A_QMP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:17:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:17:04 INFO - Running setup.py (path:/tmp/pip-ltzKzv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:17:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:17:04 INFO - Running setup.py (path:/tmp/pip-eWC_2i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:17:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:17:05 INFO - Running setup.py (path:/tmp/pip-WfXmdq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:17:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:17:05 INFO - Running setup.py (path:/tmp/pip-lR8Peh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:17:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:17:05 INFO - Running setup.py (path:/tmp/pip-sQJg1l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:17:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:17:05 INFO - Running setup.py (path:/tmp/pip-MCtXZi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:17:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:17:05 INFO - Running setup.py (path:/tmp/pip-r2LdEd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:17:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:17:05 INFO - Running setup.py (path:/tmp/pip-JdhPTl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:17:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:17:05 INFO - Running setup.py (path:/tmp/pip-sbLKkd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:17:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:17:05 INFO - Running setup.py (path:/tmp/pip-2BlpSx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:17:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:17:05 INFO - Running setup.py (path:/tmp/pip-HeCTXE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:17:05 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 20:17:05 INFO - Running setup.py install for manifestparser 20:17:05 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 20:17:05 INFO - Running setup.py install for mozcrash 20:17:05 INFO - Running setup.py install for mozdebug 20:17:05 INFO - Running setup.py install for mozdevice 20:17:06 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 20:17:06 INFO - Installing dm script to /builds/slave/test/build/venv/bin 20:17:06 INFO - Running setup.py install for mozfile 20:17:06 INFO - Running setup.py install for mozhttpd 20:17:06 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 20:17:06 INFO - Running setup.py install for mozinfo 20:17:06 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 20:17:06 INFO - Running setup.py install for mozInstall 20:17:06 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 20:17:06 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 20:17:06 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 20:17:06 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 20:17:06 INFO - Running setup.py install for mozleak 20:17:06 INFO - Running setup.py install for mozlog 20:17:06 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 20:17:06 INFO - Running setup.py install for moznetwork 20:17:06 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 20:17:06 INFO - Running setup.py install for mozprocess 20:17:06 INFO - Running setup.py install for mozprofile 20:17:06 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 20:17:06 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 20:17:06 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 20:17:06 INFO - Running setup.py install for mozrunner 20:17:07 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 20:17:07 INFO - Running setup.py install for mozscreenshot 20:17:07 INFO - Running setup.py install for moztest 20:17:07 INFO - Running setup.py install for mozversion 20:17:07 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 20:17:07 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 20:17:07 INFO - Cleaning up... 20:17:07 INFO - Return code: 0 20:17:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:17:07 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')]} 20:17:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:17:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:17:07 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')]} 20:17:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:17:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:17:07 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 0x7fdb8fe77f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23658a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2430770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24bf9c0>, '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 0x24c08c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24c0d50>, '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': '41bf3e9aed84707956354da8000001c5-1463108004.356592-147707600', '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 20:17:07 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 20:17:07 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 20:17:07 INFO - Using env: {'DISPLAY': ':0', 20:17:07 INFO - 'HOME': '/home/cltbld', 20:17:07 INFO - 'LANG': 'en_US.UTF-8', 20:17:07 INFO - 'LANGUAGE': 'en_US:en', 20:17:07 INFO - 'LOGNAME': 'cltbld', 20:17:07 INFO - 'MAIL': '/var/mail/cltbld', 20:17:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:17:07 INFO - 'MOZ_NO_REMOTE': '1', 20:17:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:17:07 INFO - 'NO_EM_RESTART': '1', 20:17:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:17:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:17:07 INFO - 'PWD': '/builds/slave/test', 20:17:07 INFO - 'SHELL': '/bin/bash', 20:17:07 INFO - 'SHLVL': '1', 20:17:07 INFO - 'TERM': 'linux', 20:17:07 INFO - 'TMOUT': '86400', 20:17:07 INFO - 'USER': 'cltbld', 20:17:07 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1463108004.356592-147707600', 20:17:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:17:07 INFO - '_': '/tools/buildbot/bin/python'} 20:17:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:17:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:17:07 INFO - Running setup.py (path:/tmp/pip-AHiu6_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:17:07 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)) 20:17:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:17:07 INFO - Running setup.py (path:/tmp/pip-tPaAXV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:17:07 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)) 20:17:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:17:07 INFO - Running setup.py (path:/tmp/pip-0ZdJnr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:17:07 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)) 20:17:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:17:07 INFO - Running setup.py (path:/tmp/pip-WjRhVM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:17:07 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)) 20:17:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:17:07 INFO - Running setup.py (path:/tmp/pip-Aap3Zx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:17:08 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)) 20:17:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:17:08 INFO - Running setup.py (path:/tmp/pip-GbeUv3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:17:08 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)) 20:17:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:17:08 INFO - Running setup.py (path:/tmp/pip-tfM8s_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:17:08 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)) 20:17:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:17:08 INFO - Running setup.py (path:/tmp/pip-l2G77B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:17:08 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)) 20:17:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:17:08 INFO - Running setup.py (path:/tmp/pip-iO6RQK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:17:08 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)) 20:17:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:17:08 INFO - Running setup.py (path:/tmp/pip-W49Ta9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:17:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 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)) 20:17:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:17:08 INFO - Running setup.py (path:/tmp/pip-RInbUC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:17:08 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)) 20:17:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:17:08 INFO - Running setup.py (path:/tmp/pip-eXT3Pv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:17:08 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)) 20:17:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:17:08 INFO - Running setup.py (path:/tmp/pip-eKCto6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:17:08 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)) 20:17:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:17:08 INFO - Running setup.py (path:/tmp/pip-Dm9gs7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:17:08 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)) 20:17:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:17:08 INFO - Running setup.py (path:/tmp/pip-mk4J9Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:17:08 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)) 20:17:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:17:08 INFO - Running setup.py (path:/tmp/pip-nmKeuB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:17:08 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)) 20:17:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:17:08 INFO - Running setup.py (path:/tmp/pip-Lxmgwu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:17:08 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)) 20:17:08 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)) 20:17:08 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)) 20:17:08 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)) 20:17:08 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)) 20:17:08 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)) 20:17:08 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:17: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 20:17: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 20:17: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 20:17: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 20:17: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 20:17: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 20:17:10 INFO - Downloading blessings-1.6.tar.gz 20:17:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 20:17:10 INFO - Installing collected packages: blessings 20:17:10 INFO - Running setup.py install for blessings 20:17:10 INFO - Successfully installed blessings 20:17:10 INFO - Cleaning up... 20:17:11 INFO - Return code: 0 20:17:11 INFO - Done creating virtualenv /builds/slave/test/build/venv. 20:17:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:17:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:17:11 INFO - Reading from file tmpfile_stdout 20:17:11 INFO - Current package versions: 20:17:11 INFO - argparse == 1.2.1 20:17:11 INFO - blessings == 1.6 20:17:11 INFO - blobuploader == 1.2.4 20:17:11 INFO - docopt == 0.6.1 20:17:11 INFO - manifestparser == 1.1 20:17:11 INFO - mozInstall == 1.12 20:17:11 INFO - mozcrash == 0.17 20:17:11 INFO - mozdebug == 0.1 20:17:11 INFO - mozdevice == 0.48 20:17:11 INFO - mozfile == 1.2 20:17:11 INFO - mozhttpd == 0.7 20:17:11 INFO - mozinfo == 0.9 20:17:11 INFO - mozleak == 0.1 20:17:11 INFO - mozlog == 3.2 20:17:11 INFO - moznetwork == 0.27 20:17:11 INFO - mozprocess == 0.22 20:17:11 INFO - mozprofile == 0.28 20:17:11 INFO - mozrunner == 6.11 20:17:11 INFO - mozscreenshot == 0.1 20:17:11 INFO - mozsystemmonitor == 0.0 20:17:11 INFO - moztest == 0.7 20:17:11 INFO - mozversion == 1.4 20:17:11 INFO - psutil == 3.1.1 20:17:11 INFO - requests == 1.2.3 20:17:11 INFO - wsgiref == 0.1.2 20:17:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:17: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')]} 20:17:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:17:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:17: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')]} 20:17:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:17:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:17:11 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 0x7fdb8fe77f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23658a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2430770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24bf9c0>, '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 0x24c08c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24c0d50>, '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': '41bf3e9aed84707956354da8000001c5-1463108004.356592-147707600', '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 20:17:11 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 20:17:11 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 20:17:11 INFO - Using env: {'DISPLAY': ':0', 20:17:11 INFO - 'HOME': '/home/cltbld', 20:17:11 INFO - 'LANG': 'en_US.UTF-8', 20:17:11 INFO - 'LANGUAGE': 'en_US:en', 20:17:11 INFO - 'LOGNAME': 'cltbld', 20:17:11 INFO - 'MAIL': '/var/mail/cltbld', 20:17:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:17:11 INFO - 'MOZ_NO_REMOTE': '1', 20:17:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:17:11 INFO - 'NO_EM_RESTART': '1', 20:17:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:17:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:17:11 INFO - 'PWD': '/builds/slave/test', 20:17:11 INFO - 'SHELL': '/bin/bash', 20:17:11 INFO - 'SHLVL': '1', 20:17:11 INFO - 'TERM': 'linux', 20:17:11 INFO - 'TMOUT': '86400', 20:17:11 INFO - 'USER': 'cltbld', 20:17:11 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1463108004.356592-147707600', 20:17:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:17:11 INFO - '_': '/tools/buildbot/bin/python'} 20:17:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:17:11 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)) 20:17:11 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)) 20:17:11 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)) 20:17:11 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)) 20:17:11 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)) 20:17:11 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)) 20:17:11 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)) 20:17:11 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)) 20:17:11 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)) 20:17:11 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)) 20:17:11 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)) 20:17:11 INFO - Cleaning up... 20:17:11 INFO - Return code: 0 20:17:11 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 20:17: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')]} 20:17:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:17:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:17: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')]} 20:17:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:17:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:17:11 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 0x7fdb8fe77f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23658a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2430770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24bf9c0>, '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 0x24c08c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24c0d50>, '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': '41bf3e9aed84707956354da8000001c5-1463108004.356592-147707600', '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 20:17:11 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 20:17:11 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 20:17:11 INFO - Using env: {'DISPLAY': ':0', 20:17:11 INFO - 'HOME': '/home/cltbld', 20:17:11 INFO - 'LANG': 'en_US.UTF-8', 20:17:11 INFO - 'LANGUAGE': 'en_US:en', 20:17:11 INFO - 'LOGNAME': 'cltbld', 20:17:11 INFO - 'MAIL': '/var/mail/cltbld', 20:17:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:17:11 INFO - 'MOZ_NO_REMOTE': '1', 20:17:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:17:11 INFO - 'NO_EM_RESTART': '1', 20:17:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:17:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:17:11 INFO - 'PWD': '/builds/slave/test', 20:17:11 INFO - 'SHELL': '/bin/bash', 20:17:11 INFO - 'SHLVL': '1', 20:17:11 INFO - 'TERM': 'linux', 20:17:11 INFO - 'TMOUT': '86400', 20:17:11 INFO - 'USER': 'cltbld', 20:17:11 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1463108004.356592-147707600', 20:17:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:17:11 INFO - '_': '/tools/buildbot/bin/python'} 20:17:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:17:11 INFO - Downloading/unpacking jsonschema 20:17:11 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 20:17:11 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 20:17:11 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 20:17:11 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 20:17:11 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 20:17:11 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 20:17:13 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 20:17:13 INFO - Downloading/unpacking functools32 (from jsonschema) 20:17:13 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 20:17:13 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 20:17:13 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 20:17:13 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 20:17:13 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 20:17:13 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 20:17:13 INFO - Downloading functools32-3.2.3-2.tar.gz 20:17:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 20:17:13 INFO - warning: no files found matching '*.txt' 20:17:13 INFO - no previously-included directories found matching 'build' 20:17:13 INFO - no previously-included directories found matching 'dist' 20:17:13 INFO - no previously-included directories found matching '.git*' 20:17:13 INFO - Installing collected packages: jsonschema, functools32 20:17:13 INFO - Running setup.py install for functools32 20:17:13 INFO - warning: no files found matching '*.txt' 20:17:13 INFO - no previously-included directories found matching 'build' 20:17:13 INFO - no previously-included directories found matching 'dist' 20:17:13 INFO - no previously-included directories found matching '.git*' 20:17:14 INFO - Successfully installed jsonschema functools32 20:17:14 INFO - Cleaning up... 20:17:14 INFO - Return code: 0 20:17:14 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 20:17: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')]} 20:17:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:17:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:17: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')]} 20:17:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:17:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:17:14 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 0x7fdb8fe77f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23658a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2430770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24bf9c0>, '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 0x24c08c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24c0d50>, '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': '41bf3e9aed84707956354da8000001c5-1463108004.356592-147707600', '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 20:17:14 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 20:17:14 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 20:17:14 INFO - Using env: {'DISPLAY': ':0', 20:17:14 INFO - 'HOME': '/home/cltbld', 20:17:14 INFO - 'LANG': 'en_US.UTF-8', 20:17:14 INFO - 'LANGUAGE': 'en_US:en', 20:17:14 INFO - 'LOGNAME': 'cltbld', 20:17:14 INFO - 'MAIL': '/var/mail/cltbld', 20:17:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:17:14 INFO - 'MOZ_NO_REMOTE': '1', 20:17:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:17:14 INFO - 'NO_EM_RESTART': '1', 20:17:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:17:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:17:14 INFO - 'PWD': '/builds/slave/test', 20:17:14 INFO - 'SHELL': '/bin/bash', 20:17:14 INFO - 'SHLVL': '1', 20:17:14 INFO - 'TERM': 'linux', 20:17:14 INFO - 'TMOUT': '86400', 20:17:14 INFO - 'USER': 'cltbld', 20:17:14 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1463108004.356592-147707600', 20:17:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:17:14 INFO - '_': '/tools/buildbot/bin/python'} 20:17:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:17:14 INFO - Downloading/unpacking flake8 20:17:14 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 20:17:14 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 20:17:14 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 20:17:14 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 20:17:14 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 20:17:14 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 20:17:16 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 20:17:16 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 20:17: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 20:17: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 20:17: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 20:17: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 20:17: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 20:17: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 20:17:16 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 20:17:16 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 20:17: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 20:17: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 20:17: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 20:17: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 20:17: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 20:17: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 20:17:16 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 20:17: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 20:17: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 20:17: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 20:17: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 20:17: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 20:17: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 20:17:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 20:17:16 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 20:17:16 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 20:17:16 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 20:17:16 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 20:17:16 INFO - no previously-included directories found matching 'docs/_build' 20:17:16 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 20:17:16 INFO - Running setup.py install for pep8 20:17:16 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 20:17:16 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 20:17:16 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 20:17:16 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 20:17:16 INFO - no previously-included directories found matching 'docs/_build' 20:17:17 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 20:17:17 INFO - Successfully installed flake8 mccabe pyflakes pep8 20:17:17 INFO - Cleaning up... 20:17:17 INFO - Return code: 0 20:17:17 INFO - Running post-action listener: _resource_record_post_action 20:17:17 INFO - Running post-action listener: _start_resource_monitoring 20:17:17 INFO - Starting resource monitoring. 20:17:17 INFO - ##### 20:17:17 INFO - ##### Finished create-virtualenv step (success) 20:17:17 INFO - ##### 20:17:17 INFO - ##### 20:17:17 INFO - ##### Running install step. 20:17:17 INFO - ##### 20:17:17 INFO - Running pre-action listener: _resource_record_pre_action 20:17:17 INFO - Running main action method: install 20:17:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:17:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:17:17 INFO - Reading from file tmpfile_stdout 20:17:17 INFO - Detecting whether we're running mozinstall >=1.0... 20:17:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 20:17:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 20:17:17 INFO - Reading from file tmpfile_stdout 20:17:17 INFO - Output received: 20:17:17 INFO - Usage: mozinstall [options] installer 20:17:17 INFO - Options: 20:17:17 INFO - -h, --help show this help message and exit 20:17:17 INFO - -d DEST, --destination=DEST 20:17:17 INFO - Directory to install application into. [default: 20:17:17 INFO - "/builds/slave/test"] 20:17:17 INFO - --app=APP Application being installed. [default: firefox] 20:17:17 INFO - mkdir: /builds/slave/test/build/application 20:17:17 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'] 20:17:17 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 20:17:33 INFO - Reading from file tmpfile_stdout 20:17:33 INFO - Output received: 20:17:33 INFO - /builds/slave/test/build/application/firefox/firefox 20:17:33 INFO - Running post-action listener: _resource_record_post_action 20:17:33 INFO - ##### 20:17:33 INFO - ##### Finished install step (success) 20:17:33 INFO - ##### 20:17:33 INFO - ##### 20:17:33 INFO - ##### Running run-tests step. 20:17:33 INFO - ##### 20:17:33 INFO - Running pre-action listener: _resource_record_pre_action 20:17:33 INFO - Running main action method: run_tests 20:17:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 20:17:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 20:17:33 INFO - Python 2.7.3 20:17:33 INFO - Return code: 0 20:17:33 INFO - grabbing minidump binary from tooltool 20:17:33 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')]} 20:17:33 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 0x24bf9c0>, '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 0x24c08c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24c0d50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 20:17:33 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 20:17:33 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 20:17:33 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 20:17:34 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 20:17:34 INFO - Return code: 0 20:17:34 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 20:17:34 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 20:17:34 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 20:17:34 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 20:17:34 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 20:17:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 20:17:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 20:17:35 INFO - Return code: 0 20:17:35 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/Xo5WwOniQJWIVYiSxjFSbQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-026', '--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 20:17:35 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/Xo5WwOniQJWIVYiSxjFSbQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-026 --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 20:17:35 INFO - Using env: {'DISPLAY': ':0', 20:17:35 INFO - 'HOME': '/home/cltbld', 20:17:35 INFO - 'LANG': 'en_US.UTF-8', 20:17:35 INFO - 'LANGUAGE': 'en_US:en', 20:17:35 INFO - 'LOGNAME': 'cltbld', 20:17:35 INFO - 'MAIL': '/var/mail/cltbld', 20:17:35 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 20:17:35 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 20:17:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:17:35 INFO - 'MOZ_NO_REMOTE': '1', 20:17:35 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 20:17:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:17:35 INFO - 'NO_EM_RESTART': '1', 20:17:35 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:17:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:17:35 INFO - 'PWD': '/builds/slave/test', 20:17:35 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 20:17:35 INFO - 'SHELL': '/bin/bash', 20:17:35 INFO - 'SHLVL': '1', 20:17:35 INFO - 'TERM': 'linux', 20:17:35 INFO - 'TMOUT': '86400', 20:17:35 INFO - 'USER': 'cltbld', 20:17:35 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1463108004.356592-147707600', 20:17:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:17:35 INFO - '_': '/tools/buildbot/bin/python'} 20:17:35 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/Xo5WwOniQJWIVYiSxjFSbQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-026', '--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 20:17:35 INFO - mozversion application_buildid: 20160512173002 20:17:35 INFO - mozversion application_changeset: bec48bfd05a8c6703aab63181441869a3dcaa4cc 20:17:35 INFO - mozversion application_display_name: Nightly 20:17:35 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 20:17:35 INFO - mozversion application_name: Firefox 20:17:35 INFO - mozversion application_remotingname: firefox 20:17:35 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 20:17:35 INFO - mozversion application_vendor: Mozilla 20:17:35 INFO - mozversion application_version: 49.0a1 20:17:35 INFO - mozversion platform_buildid: 20160512173002 20:17:35 INFO - mozversion platform_changeset: bec48bfd05a8c6703aab63181441869a3dcaa4cc 20:17:35 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 20:17:35 INFO - mozversion platform_version: 49.0a1 20:17:35 INFO - using testdate: 1463109455 20:17:35 INFO - actual date: 1463109455 20:17:35 INFO - starting webserver on 'localhost:57393' 20:17:35 INFO - SUITE-START | Running 2 tests 20:17:35 INFO - TEST-START | dromaeo_css 20:17:35 INFO - operating with platform_type : linux_ 20:17:35 INFO - Initialising browser for dromaeo_css test... 20:17:35 INFO - TEST-INFO | started process 3305 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpEfLSme/profile http://localhost:57393/getInfo.html) 20:17:39 INFO - PROCESS | 3305 | 20:17:39 INFO - PROCESS | 3305 | (process:3379): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 20:17:41 INFO - PROCESS | 3305 | __metrics Screen width/height:1600/1200 20:17:41 INFO - PROCESS | 3305 | colorDepth:24 20:17:41 INFO - PROCESS | 3305 | Browser inner width/height: 1024/697 20:17:41 INFO - PROCESS | 3305 | __metrics 20:17:42 ERROR - PROCESS | 3305 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 20:17:43 INFO - TEST-INFO | 3305: exit 0 20:17:43 INFO - Browser initialized. 20:17:43 INFO - Running cycle 1/1 for dromaeo_css test... 20:17:43 INFO - TEST-INFO | started process 3431 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpEfLSme/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:17:45 INFO - PROCESS | 3431 | 20:17:45 INFO - PROCESS | 3431 | (process:3494): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 20:17:55 INFO - PROCESS | 3431 | RSS: Main: 163328000 20:17:55 INFO - PROCESS | 3431 | 20:18:44 INFO - PROCESS | 3431 | 1463109524673 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] 20:20:52 INFO - PROCESS | 3431 | Cycle 1(1): loaded http://localhost:57393/tests/dromaeo/cssquery-dojo.html (next: http://localhost:57393/tests/dromaeo/cssquery-ext.html) 20:20:53 INFO - PROCESS | 3431 | RSS: Main: 165810176 20:20:53 INFO - PROCESS | 3431 | 20:23:55 INFO - PROCESS | 3431 | Cycle 1(1): loaded http://localhost:57393/tests/dromaeo/cssquery-ext.html (next: http://localhost:57393/tests/dromaeo/cssquery-jquery.html) 20:23:56 INFO - PROCESS | 3431 | RSS: Main: 166416384 20:23:56 INFO - PROCESS | 3431 | 20:26:58 INFO - PROCESS | 3431 | Cycle 1(1): loaded http://localhost:57393/tests/dromaeo/cssquery-jquery.html (next: http://localhost:57393/tests/dromaeo/cssquery-mootools.html) 20:26:58 INFO - PROCESS | 3431 | RSS: Main: 167833600 20:26:58 INFO - PROCESS | 3431 | 20:30:00 INFO - PROCESS | 3431 | Cycle 1(1): loaded http://localhost:57393/tests/dromaeo/cssquery-mootools.html (next: http://localhost:57393/tests/dromaeo/cssquery-prototype.html) 20:30:01 INFO - PROCESS | 3431 | RSS: Main: 165343232 20:30:01 INFO - PROCESS | 3431 | 20:33:03 INFO - PROCESS | 3431 | Cycle 1(1): loaded http://localhost:57393/tests/dromaeo/cssquery-prototype.html (next: http://localhost:57393/tests/dromaeo/cssquery-yui.html) 20:33:04 INFO - PROCESS | 3431 | RSS: Main: 166952960 20:33:04 INFO - PROCESS | 3431 | 20:36:06 INFO - PROCESS | 3431 | Cycle 1(1): loaded http://localhost:57393/tests/dromaeo/cssquery-yui.html (next: http://localhost:57393/tests/dromaeo/cssquery-dojo.html) 20:36:06 INFO - PROCESS | 3431 | RSS: Main: 168243200 20:36:06 INFO - PROCESS | 3431 | 20:36:06 INFO - PROCESS | 3431 | __start_tp_report 20:36:06 INFO - PROCESS | 3431 | _x_x_mozilla_page_load 20:36:06 INFO - PROCESS | 3431 | _x_x_mozilla_page_load_details 20:36:06 INFO - PROCESS | 3431 | |i|pagename|runs| 20:36:06 INFO - PROCESS | 3431 | |0;dojo.html;3163.984180079099;3179.713825755683;3203.887863924762;3213.550102985584;3236.6925142111504;5352.518273355402;5389.137737961927;5405;5453.427390124039;5511.696856672881;5374.7043912584795;5445.319335083114;5494.80768173114;5521.033819081655;5534.667919924797;4906.067847109047;4924.4583095859425;5043.167877300241;5061.342025536694;5106.259592359106;4926.679765815211;5157.5358217760395;5166.5608423283875;5167.9483205168035;5194.428612852583;4909.288153217782;4920.557149856494;4944.307796908436;5011.724355160465;5034.82378116766;4998.875028124289;5000.349954505928;5005.199168133081;5010.498950104997;5076.162433198518;4948;4963.503649635044;4980.402351717781;4997.450280469182;5027.974860125712;5247.580193584542;5372.7313634318125;5390.973045134789;5406.621536492447;5469.72651367434;5859.53123750099;5920.496757775594;5925.466707996258;5925.881482370329;5931.08068249422;5751.626144300607;5968.820935371946;5974.4324289192455;5983.491403230731;5989.610675306135;5481.945180548183;5496.725163741797;5533;5576.525995290364;5577.553795696334;6072.666003369769;6075.5747097703625;6080.0575910733905;6080.483158931452;6085.695715214221;5652.943470565242;5720.141978703145;5832.096025116029;5838.065909454467;5838.67887266196;5770.509506691894;5874.882502349929;5878.030125029408;5884.382139875337;5938.376470470625;5703.543716502752;5827.446392592697;5843.474087332161;5851.444112809361;5864.120381942657;5720.713964301768;5778.451047150514;5886.411358864165;5923.200367950358;5934.762609495659;4415.27148020573;4464.754438505914;4479.395281636937;4482.574155455227;4524.2082635538645;3973.6026397360033;3991.7804520751056;3995.440638310639;3997.8800635980965;4013.418054382098;5578.8326350208745;5602.523785478198;5608.270924779753;5665.093585026459;5669.546436285087;5815.912761308584;5864.589478736533;5877.23595932526;5899.557533185029;5899.970500147472;5635.464630860061;5649.830505084854;5653.491185793216;5658.151277308437;5673.290838645169;4797.232442809134;4842.128416884924;4844.297576851407;4863.8540843774745;4870.586000190024;6111.908321375183;6127.172831667757;6135.662538560333;6136.754529818846;6140.631562106288;5781.132830075522;5935;5935.169076329231;5935.970320148372;5953.1070339449425;6050.334463209131;6096.695165241631;6098.573099882966;6137.877242455036;6145.324014358506;5827.737751801094;5843.474087332161;5855.3559108497175;5857.7949771757785;5860;5662.801801936913;5892.381299963442;5940.376260492586;5954.523638109027;5959.553033522417;5659.547236221175;5769.307683078057;5828.708564571839;5831.679257640701;5897.026990546512;5682.090865461506;5712.714364281853;5713.05734553794;5761.827145185651;5830.562707797017;5529.64057336288;5718.142278658234;5719.599628025998;5722.513586345207;5726.112452569859;5694.259746232963;5719.599628026164;5721.684012677024;5763.452472015152;5771.394003629558;5670.206171135962;5683.488486036276;5694.886102277848;5695.601307908407;5706.572007099568 20:36:06 INFO - PROCESS | 3431 | |1;ext.html;604.7248501931622;638.984025399365;643.536653609401;654.4993080293576;660.9339066093389;43614.12771744563;44886.102277954466;45214.32178517325;47006.76496617512;47316.526834731645;43876;44366.556334436646;44455;44478.777606112;44530.33204501927;8648.827023459527;8819.073997230262;8828.514431706286;8828.558572071372;8831.60257788398;8734.432261902988;8785.42894711845;8806.86789698155;8812.559372031405;8816.691415800447;8629.784255393603;8666.350023748213;8691.130886911289;8695.130486951286;8707.738767836974;8826.293896488298;8827.249683776887;8829.867551986725;8866.246369058605;8896.199342059246;8760.08019157992;8781.297496200352;8785.560721963877;8804.075572064905;8809.911900881038;40192.59807401947;40206.19587608232;40242;40253.597464025275;40326;3608.639136086397;3734.2158146789207;3798.9430158547643;3826.942595861064;3893.6301051400073;31506.52740208899;31862.362752745048;31932.680673193205;32018;32129.678703212903;4392.165488557164;4407.3609326647775;4407.933880991788;4411.338299255105;4447.644188464915;309872;310323.4483827589;312395;312424;314225;58351;59463.405365945786;59540;59584.70207648934;59653;36735;37513.43729844054;37547.81226093907;39176.80411597924;39221.2155756889;17253.309867605156;17253.913730431268;17350.826491735173;17372.913135434494;17375.47873563795;12819;12833.294168820617;12900.741985160244;12902.483900643869;12968.870311296767;19521.414357569294;19726.210951562065;19796.406107816787;19887.303944361876;19981.300654477312;7203.455421566446;7426.665800039012;7538.736144234926;7565.886511702329;7578.772636820904;1042.4214560918763;1064.292245656645;1121.472907733364;1122.831575263717;1127.881572434899;30442.847785761373;31025;31784.841075794935;31791.26904361474;32233.838830806166;1203.1938601137258;1203.8916497515265;1227.5089964014287;1230.5569994801865;1258.6224132760135;16539;17063.317467301167;17089.316427343016;17159.828401716073;17220.741688874677;489004;489345;490776;495046;495506;56662.7166864163;56858.431415685314;57292.71353643205;57331;57430.42569574251;1403.7964495148146;1415.5611760354323;1427.7001829615897;1437.8274607047222;1447.609145530722;30382;30442.391152177275;31511.054668359986;31924.840375797972;32266.193345166557;49502.50497494979;56245.71877140588;57926.99999999831;58036.419635803104;62421;451003;452829;455821;456537;457891;42837;43300.35049474324;43377;43874.341884871115;44101.33847992218;48396.75801620969;48844.26733598928;48964.510354895996;50005.249921250484;50368.74815626045;40273.597264028154;41321.173576528905;42120;42213.78893105515;42524;41155;41240.793796030826;41622.76712082351;41949.580504194564;42229.78885105555;42229.78885105555;42319.5768042328;42795.14409711726;42859;44814.77592612147;34339.82830085834;36123;36824;36936.26127477489;36977.81511092535;28154.2961425966;28647.713522864506;28781.136565903056;28822.71177288284;28904.40108983229 20:36:06 INFO - PROCESS | 3431 | |2;jquery.html;406.8108329626723;407.3502315621733;410.73097121385496;413.3345314044386;427.49982520451067;13661.385237664304;17555.912220438895;18439.631207375845;19502.219911203552;19528.51178720531;18718.438446846616;18867.24531018761;19078.23687052516;19532.70700939487;19727;5675.035244008528;5681.431856814316;5760.539156867461;5830.883382332351;5832.708364581754;5365.678059316434;5611.438856114376;5766.596338256324;5771.047777116771;5792.913106303409;5704.11586204137;5710.2862142232225;5728.140778883159;5728.914066288989;5737.311522617271;5308.628396012261;5581.246531718227;5630.662160270376;5631.943680563183;5644.181593668936;5315.202719592054;5423.837284881421;5538.833834984957;5605.131204663284;5619.072852979253;13800.240986745725;14128.434862605483;14153.433862645481;14253.857461425358;14260.215688137148;2276.8064714499287;2288.5422915416984;2316.6061769499224;2445.5475736988537;2498.1880888711244;9799.412035277835;9972.952839951773;10225.181985441146;10455.84316235257;10552.788944221149;1830.1489807239698;1861.655593715167;1877.2866310801808;1892.7728672559244;1900.163927871732;64740.3525964739;72073;75984.24015759827;80892.59553702253;84759.5762021192;10698.197635177392;11402.714932126597;11454.198206125491;11461.140414468948;11489.080873530098;15748.133852637986;15997.360105595879;16016.839831601535;16038.839611603968;16048.27782749779;8558.144185581392;8578.18507241811;8682.522461264565;8705.39062265648;8776.956115219511;8227.794305142419;8259.587020648944;8302.792430189294;8463.830723385498;8568.271696905833;4863.975680121577;4931.457539670633;4933.506649335038;4954.455009948902;4971.602271818246;2001.6096861111944;2044.356027851222;2059.4439501334555;2062.9277975271093;2113.524456997194;991.2813210422386;1023.8668973033458;1072.8766191887876;1074.5755426606447;1086.217923095386;18493.352732654294;19113;19206.559508036953;19327.226910923404;19610.313639022566;1036.657902892059;1053.6364954090827;1054.7890421915495;1084.6583326252362;1088.1947358954317;10488.02909788551;10870.293430926964;10872;10921.836172457419;10957.232993690364;521575;534846;536838;541933;544609;52076;68962.65518672374;89072.5546372277;90155;90469;1034.3483605328595;1044.8328267477318;1045.8012977534245;1062.9415382154057;1063.8191507443753;16746.83253167453;17137;17211.655766884844;17265.482035538953;17267.91366043162;39338;41066;41395.58604413917;42320.788396057826;42540.149197015264;55735;57120.42879571151;57827.71086144543;58058;58113.70943145426;8660.870086948575;8769.166929141722;8918.509481978554;8940.240079593308;8955.328350373618;11507.561554805648;12754.234745915275;13397.397117129558;13444.865551344361;13519.12125711865;11601.129915256559;11905.940470297593;11984.64046078619;12106.092043096627;12201.755964880831;11628.360440175871;11676.649700508999;11695.590654327058;11735.354555499189;11743.647690569296;12104.576339828063;12125.75748485043;12243.816342754688;12270.202436841577;12290.078244131904;7539.208383119693;8836.823263534565;8858.379913406;8916.643334266557;9051.094890510896;12034.939825300817;12412.131150819356;12413;12469.314187719763;12696.746065078461 20:36:06 INFO - PROCESS | 3431 | |3;mootools.html;1522.086747951229;1547.0872185410608;1692.8645708343333;1850.0749625187407;1856.2482194711138;7877.251661092187;8640.956795216023;8780.121987801216;9243.861342079867;9296.395734277266;8703.825923481527;8947.194752472276;9007.774805629862;9035.096490350961;9047.592858321375;2312.1560630369877;2360.4334959609655;2361.4332560185517;2367.9644805327935;2370.3481542575755;2355.95288094238;2375.524895020994;2392.031227352916;2395.161693407311;2399.316194884458;2361.4686695493547;2381.714194296687;2393.401649587603;2418.613021916494;2420.745821688724;2297.356740112771;2308.6306191009357;2310.4223944013997;2315.780000899912;2345.6129738593113;2274.7725227477285;2281.7147856517936;2298.7356454007836;2304.2741536416142;2306.0545176477563;9004.954975225148;9043.728688139367;9068;9073.274138068939;9075.63697452101;5135.768890399903;5298.496642818926;5325.707086110262;5335.386430560497;5341.786328546854;3365.394229038772;3417.5215469834243;3463.8960831175104;3532.2758834439137;3546.184377593142;1120.4117838134962;1124.42092322454;1142.320319409958;1146.1289420040746;1146.2778449576797;12532;12561.371931403393;12566.81149782754;12594.43325050375;12626.558070467581;4701.177293973541;4710.26990816424;4780.95219047805;4789.185838407479;4839.128956787812;10536.209784266211;10571.682849514527;10615.363078215332;10627.14982801374;10648.574057037631;5403.702796346239;5463.235147079412;5470.8085217017215;5472.425395333433;5496.340439147328;4407.713498622579;4813.831515896927;4846.466888642247;4946.629002824802;4990.925136122961;4085.9387109193385;4148.709590328648;4162.771047592411;4179.2895207814745;4188.706790524634;1293.6442478318538;1315.15829868884;1327.3296985022696;1337;1345.663584103974;956.636478138303;978.2907392140784;985.2315194148575;992.9056739609725;1005.9949700251452;5367.677939323653;5569.415211402745;5588.804391846347;5605.411431799684;5640.915386269209;483.5792860211568;486.46488862251243;489.21969458713295;1069.337011053152;1089.9891001090045;3956.248312820555;4076.408920706481;4101.733387329814;4122.113745544722;4138.544760076449;10722.624708135334;11312.547498099984;11405.885941140648;11484.827727584094;11488.310701357945;11051.502682379312;11197.272177308449;11314.151438642135;11335.206535542598;11345.602903898489;8413.873791893126;8463.195996380335;8542.658293668184;8580.914190858137;8581.78545536354;5964.821055368346;5967.373425790403;5985.102234664835;6049.364816694359;6080.695965201634;9739.41563506192;10176.745581360228;10299;10529.315594486334;10558.788824223318;10479.42363170033;10479.633212837514;10498.47507624631;10512.474376281309;10578.735531611772;7330.81672958159;7867.685292588461;8084.272415482635;8290.253877151084;8548.230659240699;8554.059053503985;8556.786080348042;8583.399162058597;8585.527795971282;8822;7530.698772049057;7860.528368297921;8065.951426314541;8075.030996280484;8162.469439486415;7689.423293252917;7815.452918295665;7856;7887.092984306878;7936.365090792839;6915;6946.37482626566;7024.473164512579;7068.222495525385;7070.964645176741;5036.8992620146655;5059.089363914531;5153.175491921274;5193.61047921415;5207.0366982108435;4919.704817710949;5039.672421292605;5071.2139618359215;5088.974555127201;5214.061468935628 20:36:06 INFO - PROCESS | 3431 | |4;prototype.html;335.1035978756825;342.2180317973431;344.918417351285;349.07146989009243;349.5211560162576;5391.99999999999;5487.807926722566;5524.723763811813;5537.861553461161;5573.972130139354;5469.371022332433;5476.945230547693;5502.706863886983;5597.557502136187;5603.159526071081;4321.935170972438;4336.306191009439;4339.631131353839;4343.6959412841115;4362.585554372329;4073.8981525461954;4288.849890253841;4319.855238361836;4356.760378179199;4373.671974601902;4207.474065741782;4226.323788193889;4233.111046680186;4261.701680882339;4265.210935976838;4260.808263628144;4269.658427325822;4281.2293787118615;4282.2077915585405;4286.207051695446;3532.523109380252;3712.7586706863967;3720.4605332226674;3728.3475391806323;3731.9626803731885;5239.711815850127;5277.551408130314;5286.3392075990505;5326.440723723992;5351.170568561879;4867.51324867514;4880.341153944208;4908.478371704748;4912.508749125094;4912.6806757561;5645.616823878177;5755.194272801811;5759.568032397545;5782.513519186748;5790.7973220937065;4086.6322031017053;4091.8158682859344;4120.546739858613;4123.9381409278585;4129.215449064668;5761.337446193699;5982.312034116088;5991.850203744898;5999.610025348338;6010.57925945184;4186.811593478301;4210.621044106045;4211.073563815956;4213.705040647125;4222.303319952235;4239.876432745325;4246.893827654271;4259.74441533509;4270.864567716142;4295.763732994653;4889.095517329305;4940.382452193476;4958.652894297436;4967.627427942919;4973.35346404965;5632.760792625616;5724.742386592613;5756.654600723886;5810.215620891126;5839.328477225088;1455.2505459688268;1463.6121427821636;1481.0595272002356;1488.4939120699014;1493.0295308049638;943.8725772020689;958.4584709639032;960.6637676813059;961.6730311694058;967.0281367225892;5029.108262748934;5031.018951304533;5052.520010598987;5060.974695126501;5065.316182315414;5243;5275.762590683428;5306.8142615009065;5334.946650533522;5359.22291267764;5016.313009937693;5043.52086551777;5062.240663900371;5280.498352656491;5754.74103665336;3195.584574005411;3203.9038828835173;3210.4863221884852;3213.196700824794;3225.53229781685;6048.274207095089;6121.785737499166;6168.907466388007;6171.660558669317;6214.378562143749;6107.633541987495;6112.441327461469;6116.510679145657;6117.816465506042;6151.6001459904965;6212.968935155386;6227.450353298576;6234.407731265523;6250.218722659668;6271.648131950518;5829.854253643693;5968.970155149196;5985.234355864944;6000.639961602318;6035;5906.2026626406605;5933.406659334032;5936.1095835625;5991.610545314541;6010.278766547867;5921.733521991435;6173.722182501709;6228.595141315981;6257.906131407942;6263.843403914939;4196.433481479961;4205.3271476563605;4227.915441691088;4230.450041494709;4232.5662743922985;5686.346070201815;5744.253247077853;5973.761049557969;5980.5215582754145;5991.022962422478;5730.398308177581;5763.9135412968;5767.9711601443405;5809.477147056785;5858;5732.254806875247;5733.397993210653;5761.452661997104;5793.391693872083;5825.077724350869;5648.971755141198;5653.717314134195;5655.388214358888;5662.801801936913;5748.252727145445;5764.711764411846;5767.68277744728;5806.68063256525;5812.418758124154;5828.883422331614;5449.809256675997;5613.971930140323;5630.465105814941;5696.829095127153;5697.914531282117 20:36:06 INFO - PROCESS | 3431 | |5;yui.html;729.1578227147644;752.9435292353074;766.4826242286457;777.3742137579246;777.7861088200748;14404.495842645509;14491.985561010733;14564.490242841503;14667.11997280164;15258.313375898084;14076;14163.291835408214;14308.71382572348;14314.642133946656;14336.354864031118;832.4339449174558;836.4437648963432;849.02362283374;852.846487632226;860.6471346747835;858.2104463893202;860.1957170046027;860.8321377331391;862.7584276402586;863.6545381847249;890.1410139215656;893.2942975049702;898.9730308090735;899.9010108888017;903.9276857851389;881.0220655072864;881.5636260051284;884.5886662701843;885.791838917857;887.4675194883083;648.3516483516484;652.4454213918132;653.9836504087389;656.1437324291797;656.1961597043584;13787.103838250581;13843.100198487065;13850.93074534631;13856.722865542733;13872.098313609584;13497.2576508292;13648.863511364858;13664.65838354039;13814;13827.861721382758;10677.012185126492;10811.675649730521;10865.782684346348;10899.564017439294;10954.780904381867;3724.3110024645525;3797.4493698413853;3839.2513459875336;3849.8652547160996;3855.7879316637573;52501;52957;53245.46754532444;53411.198832017166;53546;10670.46647667629;10680.893191068146;10715.46422678863;10841.295315804447;10919.235653504338;16454.42409515685;18363.63272734538;18364.724529132072;18472.722909156375;18479.81520184781;8250.504969701837;8430.283425908743;8446.577671116542;8486.787830304293;8525.31797456202;4739.573438390561;4762.499937506581;4781.354517140211;4852.8786780330065;4964.478729733326;3346.247094403788;3482.7736197147105;3483.529723487297;3486.599041110305;3503.7722548034294;648.3192647719875;657.9769708060195;659.1595715462823;669.8526324208666;674.844785699292;629.8425393651587;732.4067505320708;748.6107224243362;758.135725273189;766.7744322442536;9005.369624126379;9005.819883602291;9132.452052876848;9294.907050929403;9414.811703765887;673.3603077076797;750.4746677325894;753.1376573822942;790.4433614644022;794.8688466403095;4480.395146655225;4567.5432456754725;4619.769011549409;4621.930671039937;4736.171170045228;61758.382416175264;61811.690941545006;61880.69059654673;63229;65584.34415655783;55469.1679624806;57237.42762572404;57500;57759.42240577625;59211.40788592059;51452.742736286076;51710.74144629253;51765.7411712939;52251;53073.734631326595;8006.959965200137;8272.214139656722;8394.622241999246;9124.817503650023;9509.524523773922;33945.151371215914;34831.65168348284;35198.29603407866;35382;36006.27987440289;21648.675269870648;21705.565888682453;30085.247868803453;33958.66041339654;45608.54391456043;12620.242785432903;12646.30445325516;12655.683607909876;12689.746205076031;12880.098393112392;13150.27673477968;13151.408186631434;13202.405891735087;13208.73582528325;13607.455701771818;12105.576304829288;12410.81383779262;12464.940480058935;12536.185147965352;13003.609891703263;12579.07409161361;12860.549880754128;12903.032272579781;12983.610491685264;12992.220466771645;11536.59621913229;11658.12564057716;11759.353235572125;12539.811902821648;12602.180858244184;9762.658306959222;10026.69919902404;10082.092611664986;10223.335483193569;10351.120154786928;10916.727081823017;10939.343639381663;10997.065249453886;11032.172587055842;11033.172512061788 20:36:06 INFO - PROCESS | 3431 | __end_tp_report 20:36:06 INFO - PROCESS | 3431 | __start_cc_report 20:36:06 INFO - PROCESS | 3431 | _x_x_mozilla_cycle_collect,329 20:36:06 INFO - PROCESS | 3431 | __end_cc_report 20:36:06 INFO - PROCESS | 3431 | __startTimestamp1463110566825__endTimestamp 20:36:06 INFO - PROCESS | 3431 | 20:36:06 INFO - PROCESS | 3431 | ------- Summary: start ------- 20:36:06 INFO - PROCESS | 3431 | Number of tests: 6 20:36:06 INFO - PROCESS | 3431 | 20:36:06 INFO - PROCESS | 3431 | [#0] dojo.html Cycles:165 Average:5464.31 Median:5694.26 stddev:631.27 (11.1%) stddev-sans-first:606.86 20:36:06 INFO - PROCESS | 3431 | Values: 3164.0 3179.7 3203.9 3213.6 3236.7 5352.5 5389.1 5405.0 5453.4 5511.7 5374.7 5445.3 5494.8 5521.0 5534.7 4906.1 4924.5 5043.2 5061.3 5106.3 4926.7 5157.5 5166.6 5167.9 5194.4 4909.3 4920.6 4944.3 5011.7 5034.8 4998.9 5000.3 5005.2 5010.5 5076.2 4948.0 4963.5 4980.4 4997.5 5028.0 5247.6 5372.7 5391.0 5406.6 5469.7 5859.5 5920.5 5925.5 5925.9 5931.1 5751.6 5968.8 5974.4 5983.5 5989.6 5481.9 5496.7 5533.0 5576.5 5577.6 6072.7 6075.6 6080.1 6080.5 6085.7 5652.9 5720.1 5832.1 5838.1 5838.7 5770.5 5874.9 5878.0 5884.4 5938.4 5703.5 5827.4 5843.5 5851.4 5864.1 5720.7 5778.5 5886.4 5923.2 5934.8 4415.3 4464.8 4479.4 4482.6 4524.2 3973.6 3991.8 3995.4 3997.9 4013.4 5578.8 5602.5 5608.3 5665.1 5669.5 5815.9 5864.6 5877.2 5899.6 5900.0 5635.5 5649.8 5653.5 5658.2 5673.3 4797.2 4842.1 4844.3 4863.9 4870.6 6111.9 6127.2 6135.7 6136.8 6140.6 5781.1 5935.0 5935.2 5936.0 5953.1 6050.3 6096.7 6098.6 6137.9 6145.3 5827.7 5843.5 5855.4 5857.8 5860.0 5662.8 5892.4 5940.4 5954.5 5959.6 5659.5 5769.3 5828.7 5831.7 5897.0 5682.1 5712.7 5713.1 5761.8 5830.6 5529.6 5718.1 5719.6 5722.5 5726.1 5694.3 5719.6 5721.7 5763.5 5771.4 5670.2 5683.5 5694.9 5695.6 5706.6 20:36:06 INFO - PROCESS | 3431 | 20:36:06 INFO - PROCESS | 3431 | [#1] ext.html Cycles:180 Average:58603.58 Median:42421.79 stddev:112863.72 (266.1%) stddev-sans-first:113096.33 20:36:06 INFO - PROCESS | 3431 | Values: 604.7 639.0 643.5 654.5 660.9 43614.1 44886.1 45214.3 47006.8 47316.5 43876.0 44366.6 44455.0 44478.8 44530.3 8648.8 8819.1 8828.5 8828.6 8831.6 8734.4 8785.4 8806.9 8812.6 8816.7 8629.8 8666.4 8691.1 8695.1 8707.7 8826.3 8827.2 8829.9 8866.2 8896.2 8760.1 8781.3 8785.6 8804.1 8809.9 40192.6 40206.2 40242.0 40253.6 40326.0 3608.6 3734.2 3798.9 3826.9 3893.6 31506.5 31862.4 31932.7 32018.0 32129.7 4392.2 4407.4 4407.9 4411.3 4447.6 309872.0 310323.4 312395.0 312424.0 314225.0 58351.0 59463.4 59540.0 59584.7 59653.0 36735.0 37513.4 37547.8 39176.8 39221.2 17253.3 17253.9 17350.8 17372.9 17375.5 12819.0 12833.3 12900.7 12902.5 12968.9 19521.4 19726.2 19796.4 19887.3 19981.3 7203.5 7426.7 7538.7 7565.9 7578.8 1042.4 1064.3 1121.5 1122.8 1127.9 30442.8 31025.0 31784.8 31791.3 32233.8 1203.2 1203.9 1227.5 1230.6 1258.6 16539.0 17063.3 17089.3 17159.8 17220.7 489004.0 489345.0 490776.0 495046.0 495506.0 56662.7 56858.4 57292.7 57331.0 57430.4 1403.8 1415.6 1427.7 1437.8 1447.6 30382.0 30442.4 31511.1 31924.8 32266.2 49502.5 56245.7 57927.0 58036.4 62421.0 451003.0 452829.0 455821.0 456537.0 457891.0 42837.0 43300.4 43377.0 43874.3 44101.3 48396.8 48844.3 48964.5 50005.2 50368.7 40273.6 41321.2 42120.0 42213.8 42524.0 41155.0 41240.8 41622.8 41949.6 42229.8 42229.8 42319.6 42795.1 42859.0 44814.8 34339.8 36123.0 36824.0 36936.3 36977.8 28154.3 28647.7 28781.1 28822.7 28904.4 20:36:06 INFO - PROCESS | 3431 | 20:36:06 INFO - PROCESS | 3431 | [#2] jquery.html Cycles:180 Average:29522.75 Median:2088.23 stddev:87877.25 (4208.2%) stddev-sans-first:88096.57 20:36:06 INFO - PROCESS | 3431 | Values: 406.8 407.4 410.7 413.3 427.5 13661.4 17555.9 18439.6 19502.2 19528.5 18718.4 18867.2 19078.2 19532.7 19727.0 5675.0 5681.4 5760.5 5830.9 5832.7 5365.7 5611.4 5766.6 5771.0 5792.9 5704.1 5710.3 5728.1 5728.9 5737.3 5308.6 5581.2 5630.7 5631.9 5644.2 5315.2 5423.8 5538.8 5605.1 5619.1 13800.2 14128.4 14153.4 14253.9 14260.2 2276.8 2288.5 2316.6 2445.5 2498.2 9799.4 9973.0 10225.2 10455.8 10552.8 1830.1 1861.7 1877.3 1892.8 1900.2 64740.4 72073.0 75984.2 80892.6 84759.6 10698.2 11402.7 11454.2 11461.1 11489.1 15748.1 15997.4 16016.8 16038.8 16048.3 8558.1 8578.2 8682.5 8705.4 8777.0 8227.8 8259.6 8302.8 8463.8 8568.3 4864.0 4931.5 4933.5 4954.5 4971.6 2001.6 2044.4 2059.4 2062.9 2113.5 991.3 1023.9 1072.9 1074.6 1086.2 18493.4 19113.0 19206.6 19327.2 19610.3 1036.7 1053.6 1054.8 1084.7 1088.2 10488.0 10870.3 10872.0 10921.8 10957.2 521575.0 534846.0 536838.0 541933.0 544609.0 52076.0 68962.7 89072.6 90155.0 90469.0 1034.3 1044.8 1045.8 1062.9 1063.8 16746.8 17137.0 17211.7 17265.5 17267.9 39338.0 41066.0 41395.6 42320.8 42540.1 55735.0 57120.4 57827.7 58058.0 58113.7 8660.9 8769.2 8918.5 8940.2 8955.3 11507.6 12754.2 13397.4 13444.9 13519.1 11601.1 11905.9 11984.6 12106.1 12201.8 11628.4 11676.6 11695.6 11735.4 11743.6 12104.6 12125.8 12243.8 12270.2 12290.1 7539.2 8836.8 8858.4 8916.6 9051.1 12034.9 12412.1 12413.0 12469.3 12696.7 20:36:06 INFO - PROCESS | 3431 | 20:36:06 INFO - PROCESS | 3431 | [#3] mootools.html Cycles:180 Average:5904.54 Median:4785.07 stddev:3396.18 (71.0%) stddev-sans-first:3389.74 20:36:06 INFO - PROCESS | 3431 | Values: 1522.1 1547.1 1692.9 1850.1 1856.2 7877.3 8641.0 8780.1 9243.9 9296.4 8703.8 8947.2 9007.8 9035.1 9047.6 2312.2 2360.4 2361.4 2368.0 2370.3 2356.0 2375.5 2392.0 2395.2 2399.3 2361.5 2381.7 2393.4 2418.6 2420.7 2297.4 2308.6 2310.4 2315.8 2345.6 2274.8 2281.7 2298.7 2304.3 2306.1 9005.0 9043.7 9068.0 9073.3 9075.6 5135.8 5298.5 5325.7 5335.4 5341.8 3365.4 3417.5 3463.9 3532.3 3546.2 1120.4 1124.4 1142.3 1146.1 1146.3 12532.0 12561.4 12566.8 12594.4 12626.6 4701.2 4710.3 4781.0 4789.2 4839.1 10536.2 10571.7 10615.4 10627.1 10648.6 5403.7 5463.2 5470.8 5472.4 5496.3 4407.7 4813.8 4846.5 4946.6 4990.9 4085.9 4148.7 4162.8 4179.3 4188.7 1293.6 1315.2 1327.3 1337.0 1345.7 956.6 978.3 985.2 992.9 1006.0 5367.7 5569.4 5588.8 5605.4 5640.9 483.6 486.5 489.2 1069.3 1090.0 3956.2 4076.4 4101.7 4122.1 4138.5 10722.6 11312.5 11405.9 11484.8 11488.3 11051.5 11197.3 11314.2 11335.2 11345.6 8413.9 8463.2 8542.7 8580.9 8581.8 5964.8 5967.4 5985.1 6049.4 6080.7 9739.4 10176.7 10299.0 10529.3 10558.8 10479.4 10479.6 10498.5 10512.5 10578.7 7330.8 7867.7 8084.3 8290.3 8548.2 8554.1 8556.8 8583.4 8585.5 8822.0 7530.7 7860.5 8066.0 8075.0 8162.5 7689.4 7815.5 7856.0 7887.1 7936.4 6915.0 6946.4 7024.5 7068.2 7071.0 5036.9 5059.1 5153.2 5193.6 5207.0 4919.7 5039.7 5071.2 5089.0 5214.1 20:36:06 INFO - PROCESS | 3431 | 20:36:06 INFO - PROCESS | 3431 | [#4] prototype.html Cycles:180 Average:4831.33 Median:5459.59 stddev:1425.16 (26.1%) stddev-sans-first:1388.62 20:36:06 INFO - PROCESS | 3431 | Values: 335.1 342.2 344.9 349.1 349.5 5392.0 5487.8 5524.7 5537.9 5574.0 5469.4 5476.9 5502.7 5597.6 5603.2 4321.9 4336.3 4339.6 4343.7 4362.6 4073.9 4288.8 4319.9 4356.8 4373.7 4207.5 4226.3 4233.1 4261.7 4265.2 4260.8 4269.7 4281.2 4282.2 4286.2 3532.5 3712.8 3720.5 3728.3 3732.0 5239.7 5277.6 5286.3 5326.4 5351.2 4867.5 4880.3 4908.5 4912.5 4912.7 5645.6 5755.2 5759.6 5782.5 5790.8 4086.6 4091.8 4120.5 4123.9 4129.2 5761.3 5982.3 5991.9 5999.6 6010.6 4186.8 4210.6 4211.1 4213.7 4222.3 4239.9 4246.9 4259.7 4270.9 4295.8 4889.1 4940.4 4958.7 4967.6 4973.4 5632.8 5724.7 5756.7 5810.2 5839.3 1455.3 1463.6 1481.1 1488.5 1493.0 943.9 958.5 960.7 961.7 967.0 5029.1 5031.0 5052.5 5061.0 5065.3 5243.0 5275.8 5306.8 5334.9 5359.2 5016.3 5043.5 5062.2 5280.5 5754.7 3195.6 3203.9 3210.5 3213.2 3225.5 6048.3 6121.8 6168.9 6171.7 6214.4 6107.6 6112.4 6116.5 6117.8 6151.6 6213.0 6227.5 6234.4 6250.2 6271.6 5829.9 5969.0 5985.2 6000.6 6035.0 5906.2 5933.4 5936.1 5991.6 6010.3 5921.7 6173.7 6228.6 6257.9 6263.8 4196.4 4205.3 4227.9 4230.5 4232.6 5686.3 5744.3 5973.8 5980.5 5991.0 5730.4 5763.9 5768.0 5809.5 5858.0 5732.3 5733.4 5761.5 5793.4 5825.1 5649.0 5653.7 5655.4 5662.8 5748.3 5764.7 5767.7 5806.7 5812.4 5828.9 5449.8 5614.0 5630.5 5696.8 5697.9 20:36:06 INFO - PROCESS | 3431 | 20:36:06 INFO - PROCESS | 3431 | [#5] yui.html Cycles:180 Average:14490.43 Median:4593.66 stddev:16848.53 (366.8%) stddev-sans-first:16864.10 20:36:06 INFO - PROCESS | 3431 | Values: 729.2 752.9 766.5 777.4 777.8 14404.5 14492.0 14564.5 14667.1 15258.3 14076.0 14163.3 14308.7 14314.6 14336.4 832.4 836.4 849.0 852.8 860.6 858.2 860.2 860.8 862.8 863.7 890.1 893.3 899.0 899.9 903.9 881.0 881.6 884.6 885.8 887.5 648.4 652.4 654.0 656.1 656.2 13787.1 13843.1 13850.9 13856.7 13872.1 13497.3 13648.9 13664.7 13814.0 13827.9 10677.0 10811.7 10865.8 10899.6 10954.8 3724.3 3797.4 3839.3 3849.9 3855.8 52501.0 52957.0 53245.5 53411.2 53546.0 10670.5 10680.9 10715.5 10841.3 10919.2 16454.4 18363.6 18364.7 18472.7 18479.8 8250.5 8430.3 8446.6 8486.8 8525.3 4739.6 4762.5 4781.4 4852.9 4964.5 3346.2 3482.8 3483.5 3486.6 3503.8 648.3 658.0 659.2 669.9 674.8 629.8 732.4 748.6 758.1 766.8 9005.4 9005.8 9132.5 9294.9 9414.8 673.4 750.5 753.1 790.4 794.9 4480.4 4567.5 4619.8 4621.9 4736.2 61758.4 61811.7 61880.7 63229.0 65584.3 55469.2 57237.4 57500.0 57759.4 59211.4 51452.7 51710.7 51765.7 52251.0 53073.7 8007.0 8272.2 8394.6 9124.8 9509.5 33945.2 34831.7 35198.3 35382.0 36006.3 21648.7 21705.6 30085.2 33958.7 45608.5 12620.2 12646.3 12655.7 12689.7 12880.1 13150.3 13151.4 13202.4 13208.7 13607.5 12105.6 12410.8 12464.9 12536.2 13003.6 12579.1 12860.5 12903.0 12983.6 12992.2 11536.6 11658.1 11759.4 12539.8 12602.2 9762.7 10026.7 10082.1 10223.3 10351.1 10916.7 10939.3 10997.1 11032.2 11033.2 20:36:06 INFO - PROCESS | 3431 | -------- Summary: end -------- 20:36:06 INFO - PROCESS | 3431 | 20:36:07 INFO - TEST-INFO | 3431: exit 0 20:36:07 INFO - TEST-OK | dromaeo_css | took 1112072ms 20:36:07 INFO - TEST-START | kraken 20:36:07 INFO - operating with platform_type : linux_ 20:36:07 INFO - Initialising browser for kraken test... 20:36:07 INFO - TEST-INFO | started process 3668 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpE6qfgM/profile http://localhost:57393/getInfo.html) 20:36:11 INFO - PROCESS | 3668 | 20:36:11 INFO - PROCESS | 3668 | (process:3733): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 20:36:11 INFO - PROCESS | 3668 | __metrics Screen width/height:1600/1200 20:36:11 INFO - PROCESS | 3668 | colorDepth:24 20:36:11 INFO - PROCESS | 3668 | Browser inner width/height: 1024/697 20:36:11 INFO - PROCESS | 3668 | __metrics 20:36:12 ERROR - PROCESS | 3668 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 20:36:13 INFO - TEST-INFO | 3668: exit 0 20:36:13 INFO - Browser initialized. 20:36:13 INFO - Running cycle 1/1 for kraken test... 20:36:13 INFO - TEST-INFO | started process 3784 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpE6qfgM/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:36:15 INFO - PROCESS | 3784 | 20:36:15 INFO - PROCESS | 3784 | (process:3843): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 20:36:25 INFO - PROCESS | 3784 | RSS: Main: 163336192 20:36:25 INFO - PROCESS | 3784 | 20:36:27 INFO - PROCESS | 3784 | Cycle 1(1): loaded http://localhost:57393/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:57393/tests/kraken/driver.html?testName=audio-beat-detection) 20:36:28 INFO - PROCESS | 3784 | RSS: Main: 167776256 20:36:28 INFO - PROCESS | 3784 | 20:36:31 INFO - PROCESS | 3784 | Cycle 1(1): loaded http://localhost:57393/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:57393/tests/kraken/driver.html?testName=audio-dft) 20:36:31 INFO - PROCESS | 3784 | RSS: Main: 167837696 20:36:31 INFO - PROCESS | 3784 | 20:36:34 INFO - PROCESS | 3784 | Cycle 1(1): loaded http://localhost:57393/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:57393/tests/kraken/driver.html?testName=audio-fft) 20:36:35 INFO - PROCESS | 3784 | RSS: Main: 162869248 20:36:35 INFO - PROCESS | 3784 | 20:36:37 INFO - PROCESS | 3784 | Cycle 1(1): loaded http://localhost:57393/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:57393/tests/kraken/driver.html?testName=audio-oscillator) 20:36:38 INFO - PROCESS | 3784 | RSS: Main: 162766848 20:36:38 INFO - PROCESS | 3784 | 20:36:40 INFO - PROCESS | 3784 | Cycle 1(1): loaded http://localhost:57393/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:57393/tests/kraken/driver.html?testName=imaging-gaussian-blur) 20:36:41 INFO - PROCESS | 3784 | RSS: Main: 163934208 20:36:41 INFO - PROCESS | 3784 | 20:36:44 INFO - PROCESS | 3784 | Cycle 1(1): loaded http://localhost:57393/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:57393/tests/kraken/driver.html?testName=imaging-darkroom) 20:36:45 INFO - PROCESS | 3784 | RSS: Main: 163258368 20:36:45 INFO - PROCESS | 3784 | 20:36:48 INFO - PROCESS | 3784 | Cycle 1(1): loaded http://localhost:57393/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:57393/tests/kraken/driver.html?testName=imaging-desaturate) 20:36:49 INFO - PROCESS | 3784 | RSS: Main: 161865728 20:36:49 INFO - PROCESS | 3784 | 20:36:52 INFO - PROCESS | 3784 | Cycle 1(1): loaded http://localhost:57393/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:57393/tests/kraken/driver.html?testName=json-parse-financial) 20:36:52 INFO - PROCESS | 3784 | RSS: Main: 163373056 20:36:52 INFO - PROCESS | 3784 | 20:36:54 INFO - PROCESS | 3784 | Cycle 1(1): loaded http://localhost:57393/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:57393/tests/kraken/driver.html?testName=json-stringify-tinderbox) 20:36:54 INFO - PROCESS | 3784 | RSS: Main: 163565568 20:36:54 INFO - PROCESS | 3784 | 20:36:56 INFO - PROCESS | 3784 | Cycle 1(1): loaded http://localhost:57393/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:57393/tests/kraken/driver.html?testName=stanford-crypto-aes) 20:36:57 INFO - PROCESS | 3784 | RSS: Main: 163926016 20:36:57 INFO - PROCESS | 3784 | 20:36:59 INFO - PROCESS | 3784 | Cycle 1(1): loaded http://localhost:57393/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:57393/tests/kraken/driver.html?testName=stanford-crypto-ccm) 20:37:00 INFO - PROCESS | 3784 | RSS: Main: 163561472 20:37:00 INFO - PROCESS | 3784 | 20:37:02 INFO - PROCESS | 3784 | Cycle 1(1): loaded http://localhost:57393/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:57393/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 20:37:03 INFO - PROCESS | 3784 | RSS: Main: 164196352 20:37:03 INFO - PROCESS | 3784 | 20:37:06 INFO - PROCESS | 3784 | Cycle 1(1): loaded http://localhost:57393/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:57393/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 20:37:07 INFO - PROCESS | 3784 | RSS: Main: 164020224 20:37:07 INFO - PROCESS | 3784 | 20:37:08 INFO - PROCESS | 3784 | Cycle 1(1): loaded http://localhost:57393/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:57393/tests/kraken/driver.html?testName=ai-astar) 20:37:08 INFO - PROCESS | 3784 | RSS: Main: 165429248 20:37:08 INFO - PROCESS | 3784 | 20:37:08 INFO - PROCESS | 3784 | __start_tp_report 20:37:08 INFO - PROCESS | 3784 | _x_x_mozilla_page_load 20:37:08 INFO - PROCESS | 3784 | _x_x_mozilla_page_load_details 20:37:08 INFO - PROCESS | 3784 | |i|pagename|runs| 20:37:08 INFO - PROCESS | 3784 | |0;ai-astar;107;113;103;109;111;112;105;111;111;112 20:37:08 INFO - PROCESS | 3784 | |1;audio-beat-detection;152;123;150;207;125;149;159;127;190;126 20:37:08 INFO - PROCESS | 3784 | |2;audio-dft;162;162;162;163;164;170;164;166;162;161 20:37:08 INFO - PROCESS | 3784 | |3;audio-fft;76;76;76;167;86;75;75;76;166;80 20:37:08 INFO - PROCESS | 3784 | |4;audio-oscillator;91;93;175;92;94;92;142;91;92;155 20:37:08 INFO - PROCESS | 3784 | |5;imaging-gaussian-blur;117;116;117;117;118;118;117;118;116;119 20:37:08 INFO - PROCESS | 3784 | |6;imaging-darkroom;147;147;149;146;146;147;146;146;147;146 20:37:08 INFO - PROCESS | 3784 | |7;imaging-desaturate;95;95;95;95;95;95;95;95;95;95 20:37:08 INFO - PROCESS | 3784 | |8;json-parse-financial;57;58;62;58;59;58;59;57;58;59 20:37:08 INFO - PROCESS | 3784 | |9;json-stringify-tinderbox;59;59;60;60;59;60;59;59;59;59 20:37:08 INFO - PROCESS | 3784 | |10;stanford-crypto-aes;84;79;78;78;80;80;82;80;133;84 20:37:08 INFO - PROCESS | 3784 | |11;stanford-crypto-ccm;138;141;133;130;127;130;131;125;143;133 20:37:08 INFO - PROCESS | 3784 | |12;stanford-crypto-pbkdf2;167;166;171;166;170;166;166;163;164;169 20:37:08 INFO - PROCESS | 3784 | |13;stanford-crypto-sha256-iterative;65;68;65;64;64;63;64;64;65;67 20:37:08 INFO - PROCESS | 3784 | __end_tp_report 20:37:08 INFO - PROCESS | 3784 | __start_cc_report 20:37:08 INFO - PROCESS | 3784 | _x_x_mozilla_cycle_collect,830 20:37:08 INFO - PROCESS | 3784 | __end_cc_report 20:37:08 INFO - PROCESS | 3784 | __startTimestamp1463110628971__endTimestamp 20:37:08 INFO - PROCESS | 3784 | 20:37:08 INFO - PROCESS | 3784 | ------- Summary: start ------- 20:37:08 INFO - PROCESS | 3784 | Number of tests: 14 20:37:08 INFO - PROCESS | 3784 | 20:37:08 INFO - PROCESS | 3784 | [#0] ai-astar Cycles:10 Average:109.40 Median:111.00 stddev:3.34 (3.0%) stddev-sans-first:3.43 20:37:08 INFO - PROCESS | 3784 | Values: 107.0 113.0 103.0 109.0 111.0 112.0 105.0 111.0 111.0 112.0 20:37:08 INFO - PROCESS | 3784 | 20:37:08 INFO - PROCESS | 3784 | [#1] audio-beat-detection Cycles:10 Average:150.80 Median:151.00 stddev:28.65 (19.0%) stddev-sans-first:30.39 20:37:08 INFO - PROCESS | 3784 | Values: 152.0 123.0 150.0 207.0 125.0 149.0 159.0 127.0 190.0 126.0 20:37:08 INFO - PROCESS | 3784 | 20:37:08 INFO - PROCESS | 3784 | [#2] audio-dft Cycles:10 Average:163.60 Median:163.50 stddev:2.67 (1.6%) stddev-sans-first:2.77 20:37:08 INFO - PROCESS | 3784 | Values: 162.0 162.0 162.0 163.0 164.0 170.0 164.0 166.0 162.0 161.0 20:37:08 INFO - PROCESS | 3784 | 20:37:08 INFO - PROCESS | 3784 | [#3] audio-fft Cycles:10 Average:95.30 Median:76.00 stddev:37.67 (49.6%) stddev-sans-first:39.31 20:37:08 INFO - PROCESS | 3784 | Values: 76.0 76.0 76.0 167.0 86.0 75.0 75.0 76.0 166.0 80.0 20:37:08 INFO - PROCESS | 3784 | 20:37:08 INFO - PROCESS | 3784 | [#4] audio-oscillator Cycles:10 Average:111.70 Median:92.00 stddev:32.46 (35.3%) stddev-sans-first:33.56 20:37:08 INFO - PROCESS | 3784 | Values: 91.0 93.0 175.0 92.0 94.0 92.0 142.0 91.0 92.0 155.0 20:37:08 INFO - PROCESS | 3784 | 20:37:08 INFO - PROCESS | 3784 | [#5] imaging-gaussian-blur Cycles:10 Average:117.30 Median:117.50 stddev:0.95 (0.8%) stddev-sans-first:1.00 20:37:08 INFO - PROCESS | 3784 | Values: 117.0 116.0 117.0 117.0 118.0 118.0 117.0 118.0 116.0 119.0 20:37:08 INFO - PROCESS | 3784 | 20:37:08 INFO - PROCESS | 3784 | [#6] imaging-darkroom Cycles:10 Average:146.70 Median:147.00 stddev:0.95 (0.6%) stddev-sans-first:1.00 20:37:08 INFO - PROCESS | 3784 | Values: 147.0 147.0 149.0 146.0 146.0 147.0 146.0 146.0 147.0 146.0 20:37:08 INFO - PROCESS | 3784 | 20:37:08 INFO - PROCESS | 3784 | [#7] imaging-desaturate Cycles:10 Average:95.00 Median:95.00 stddev:0.00 (0.0%) stddev-sans-first:0.00 20:37:08 INFO - PROCESS | 3784 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 20:37:08 INFO - PROCESS | 3784 | 20:37:08 INFO - PROCESS | 3784 | [#8] json-parse-financial Cycles:10 Average:58.50 Median:58.50 stddev:1.43 (2.5%) stddev-sans-first:1.41 20:37:08 INFO - PROCESS | 3784 | Values: 57.0 58.0 62.0 58.0 59.0 58.0 59.0 57.0 58.0 59.0 20:37:08 INFO - PROCESS | 3784 | 20:37:08 INFO - PROCESS | 3784 | [#9] json-stringify-tinderbox Cycles:10 Average:59.30 Median:59.00 stddev:0.48 (0.8%) stddev-sans-first:0.50 20:37:08 INFO - PROCESS | 3784 | Values: 59.0 59.0 60.0 60.0 59.0 60.0 59.0 59.0 59.0 59.0 20:37:08 INFO - PROCESS | 3784 | 20:37:08 INFO - PROCESS | 3784 | [#10] stanford-crypto-aes Cycles:10 Average:85.80 Median:80.00 stddev:16.73 (20.9%) stddev-sans-first:17.73 20:37:08 INFO - PROCESS | 3784 | Values: 84.0 79.0 78.0 78.0 80.0 80.0 82.0 80.0 133.0 84.0 20:37:08 INFO - PROCESS | 3784 | 20:37:08 INFO - PROCESS | 3784 | [#11] stanford-crypto-ccm Cycles:10 Average:133.10 Median:133.00 stddev:5.88 (4.4%) stddev-sans-first:5.96 20:37:08 INFO - PROCESS | 3784 | Values: 138.0 141.0 133.0 130.0 127.0 130.0 131.0 125.0 143.0 133.0 20:37:08 INFO - PROCESS | 3784 | 20:37:08 INFO - PROCESS | 3784 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.80 Median:166.50 stddev:2.53 (1.5%) stddev-sans-first:2.68 20:37:08 INFO - PROCESS | 3784 | Values: 167.0 166.0 171.0 166.0 170.0 166.0 166.0 163.0 164.0 169.0 20:37:08 INFO - PROCESS | 3784 | 20:37:08 INFO - PROCESS | 3784 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.90 Median:65.00 stddev:1.52 (2.3%) stddev-sans-first:1.62 20:37:08 INFO - PROCESS | 3784 | Values: 65.0 68.0 65.0 64.0 64.0 63.0 64.0 64.0 65.0 67.0 20:37:08 INFO - PROCESS | 3784 | -------- Summary: end -------- 20:37:08 INFO - PROCESS | 3784 | 20:37:09 INFO - TEST-INFO | 3784: exit 0 20:37:09 INFO - TEST-OK | kraken | took 62410ms 20:37:09 INFO - SUITE-END | took 1174s 20:37:10 INFO - Completed test suite (00:19:34) 20:37:10 INFO - javascript benchmark 20:37:10 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7205.963025739457, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3163.984180079099, 3179.713825755683, 3203.887863924762, 3213.550102985584, 3236.6925142111504, 5352.518273355402, 5389.137737961927, 5405.0, 5453.427390124039, 5511.696856672881, 5374.7043912584795, 5445.319335083114, 5494.80768173114, 5521.033819081655, 5534.667919924797, 4906.067847109047, 4924.4583095859425, 5043.167877300241, 5061.342025536694, 5106.259592359106, 4926.679765815211, 5157.5358217760395, 5166.5608423283875, 5167.9483205168035, 5194.428612852583, 4909.288153217782, 4920.557149856494, 4944.307796908436, 5011.724355160465, 5034.82378116766, 4998.875028124289, 5000.349954505928, 5005.199168133081, 5010.498950104997, 5076.162433198518, 4948.0, 4963.503649635044, 4980.402351717781, 4997.450280469182, 5027.974860125712, 5247.580193584542, 5372.7313634318125, 5390.973045134789, 5406.621536492447, 5469.72651367434, 5859.53123750099, 5920.496757775594, 5925.466707996258, 5925.881482370329, 5931.08068249422, 5751.626144300607, 5968.820935371946, 5974.4324289192455, 5983.491403230731, 5989.610675306135, 5481.945180548183, 5496.725163741797, 5533.0, 5576.525995290364, 5577.553795696334, 6072.666003369769, 6075.5747097703625, 6080.0575910733905, 6080.483158931452, 6085.695715214221, 5652.943470565242, 5720.141978703145, 5832.096025116029, 5838.065909454467, 5838.67887266196, 5770.509506691894, 5874.882502349929, 5878.030125029408, 5884.382139875337, 5938.376470470625, 5703.543716502752, 5827.446392592697, 5843.474087332161, 5851.444112809361, 5864.120381942657, 5720.713964301768, 5778.451047150514, 5886.411358864165, 5923.200367950358, 5934.762609495659, 4415.27148020573, 4464.754438505914, 4479.395281636937, 4482.574155455227, 4524.2082635538645, 3973.6026397360033, 3991.7804520751056, 3995.440638310639, 3997.8800635980965, 4013.418054382098, 5578.8326350208745, 5602.523785478198, 5608.270924779753, 5665.093585026459, 5669.546436285087, 5815.912761308584, 5864.589478736533, 5877.23595932526, 5899.557533185029, 5899.970500147472, 5635.464630860061, 5649.830505084854, 5653.491185793216, 5658.151277308437, 5673.290838645169, 4797.232442809134, 4842.128416884924, 4844.297576851407, 4863.8540843774745, 4870.586000190024, 6111.908321375183, 6127.172831667757, 6135.662538560333, 6136.754529818846, 6140.631562106288, 5781.132830075522, 5935.0, 5935.169076329231, 5935.970320148372, 5953.1070339449425, 6050.334463209131, 6096.695165241631, 6098.573099882966, 6137.877242455036, 6145.324014358506, 5827.737751801094, 5843.474087332161, 5855.3559108497175, 5857.7949771757785, 5860.0, 5662.801801936913, 5892.381299963442, 5940.376260492586, 5954.523638109027, 5959.553033522417, 5659.547236221175, 5769.307683078057, 5828.708564571839, 5831.679257640701, 5897.026990546512, 5682.090865461506, 5712.714364281853, 5713.05734553794, 5761.827145185651, 5830.562707797017, 5529.64057336288, 5718.142278658234, 5719.599628025998, 5722.513586345207, 5726.112452569859, 5694.259746232963, 5719.599628026164, 5721.684012677024, 5763.452472015152, 5771.394003629558, 5670.206171135962, 5683.488486036276, 5694.886102277848, 5695.601307908407, 5706.572007099568], "value": 5421.261709147499, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [604.7248501931622, 638.984025399365, 643.536653609401, 654.4993080293576, 660.9339066093389, 43614.12771744563, 44886.102277954466, 45214.32178517325, 47006.76496617512, 47316.526834731645, 43876.0, 44366.556334436646, 44455.0, 44478.777606112, 44530.33204501927, 8648.827023459527, 8819.073997230262, 8828.514431706286, 8828.558572071372, 8831.60257788398, 8734.432261902988, 8785.42894711845, 8806.86789698155, 8812.559372031405, 8816.691415800447, 8629.784255393603, 8666.350023748213, 8691.130886911289, 8695.130486951286, 8707.738767836974, 8826.293896488298, 8827.249683776887, 8829.867551986725, 8866.246369058605, 8896.199342059246, 8760.08019157992, 8781.297496200352, 8785.560721963877, 8804.075572064905, 8809.911900881038, 40192.59807401947, 40206.19587608232, 40242.0, 40253.597464025275, 40326.0, 3608.639136086397, 3734.2158146789207, 3798.9430158547643, 3826.942595861064, 3893.6301051400073, 31506.52740208899, 31862.362752745048, 31932.680673193205, 32018.0, 32129.678703212903, 4392.165488557164, 4407.3609326647775, 4407.933880991788, 4411.338299255105, 4447.644188464915, 309872.0, 310323.4483827589, 312395.0, 312424.0, 314225.0, 58351.0, 59463.405365945786, 59540.0, 59584.70207648934, 59653.0, 36735.0, 37513.43729844054, 37547.81226093907, 39176.80411597924, 39221.2155756889, 17253.309867605156, 17253.913730431268, 17350.826491735173, 17372.913135434494, 17375.47873563795, 12819.0, 12833.294168820617, 12900.741985160244, 12902.483900643869, 12968.870311296767, 19521.414357569294, 19726.210951562065, 19796.406107816787, 19887.303944361876, 19981.300654477312, 7203.455421566446, 7426.665800039012, 7538.736144234926, 7565.886511702329, 7578.772636820904, 1042.4214560918763, 1064.292245656645, 1121.472907733364, 1122.831575263717, 1127.881572434899, 30442.847785761373, 31025.0, 31784.841075794935, 31791.26904361474, 32233.838830806166, 1203.1938601137258, 1203.8916497515265, 1227.5089964014287, 1230.5569994801865, 1258.6224132760135, 16539.0, 17063.317467301167, 17089.316427343016, 17159.828401716073, 17220.741688874677, 489004.0, 489345.0, 490776.0, 495046.0, 495506.0, 56662.7166864163, 56858.431415685314, 57292.71353643205, 57331.0, 57430.42569574251, 1403.7964495148146, 1415.5611760354323, 1427.7001829615897, 1437.8274607047222, 1447.609145530722, 30382.0, 30442.391152177275, 31511.054668359986, 31924.840375797972, 32266.193345166557, 49502.50497494979, 56245.71877140588, 57926.99999999831, 58036.419635803104, 62421.0, 451003.0, 452829.0, 455821.0, 456537.0, 457891.0, 42837.0, 43300.35049474324, 43377.0, 43874.341884871115, 44101.33847992218, 48396.75801620969, 48844.26733598928, 48964.510354895996, 50005.249921250484, 50368.74815626045, 40273.597264028154, 41321.173576528905, 42120.0, 42213.78893105515, 42524.0, 41155.0, 41240.793796030826, 41622.76712082351, 41949.580504194564, 42229.78885105555, 42229.78885105555, 42319.5768042328, 42795.14409711726, 42859.0, 44814.77592612147, 34339.82830085834, 36123.0, 36824.0, 36936.26127477489, 36977.81511092535, 28154.2961425966, 28647.713522864506, 28781.136565903056, 28822.71177288284, 28904.40108983229], "value": 20358.2498518459, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [406.8108329626723, 407.3502315621733, 410.73097121385496, 413.3345314044386, 427.49982520451067, 13661.385237664304, 17555.912220438895, 18439.631207375845, 19502.219911203552, 19528.51178720531, 18718.438446846616, 18867.24531018761, 19078.23687052516, 19532.70700939487, 19727.0, 5675.035244008528, 5681.431856814316, 5760.539156867461, 5830.883382332351, 5832.708364581754, 5365.678059316434, 5611.438856114376, 5766.596338256324, 5771.047777116771, 5792.913106303409, 5704.11586204137, 5710.2862142232225, 5728.140778883159, 5728.914066288989, 5737.311522617271, 5308.628396012261, 5581.246531718227, 5630.662160270376, 5631.943680563183, 5644.181593668936, 5315.202719592054, 5423.837284881421, 5538.833834984957, 5605.131204663284, 5619.072852979253, 13800.240986745725, 14128.434862605483, 14153.433862645481, 14253.857461425358, 14260.215688137148, 2276.8064714499287, 2288.5422915416984, 2316.6061769499224, 2445.5475736988537, 2498.1880888711244, 9799.412035277835, 9972.952839951773, 10225.181985441146, 10455.84316235257, 10552.788944221149, 1830.1489807239698, 1861.655593715167, 1877.2866310801808, 1892.7728672559244, 1900.163927871732, 64740.3525964739, 72073.0, 75984.24015759827, 80892.59553702253, 84759.5762021192, 10698.197635177392, 11402.714932126597, 11454.198206125491, 11461.140414468948, 11489.080873530098, 15748.133852637986, 15997.360105595879, 16016.839831601535, 16038.839611603968, 16048.27782749779, 8558.144185581392, 8578.18507241811, 8682.522461264565, 8705.39062265648, 8776.956115219511, 8227.794305142419, 8259.587020648944, 8302.792430189294, 8463.830723385498, 8568.271696905833, 4863.975680121577, 4931.457539670633, 4933.506649335038, 4954.455009948902, 4971.602271818246, 2001.6096861111944, 2044.356027851222, 2059.4439501334555, 2062.9277975271093, 2113.524456997194, 991.2813210422386, 1023.8668973033458, 1072.8766191887876, 1074.5755426606447, 1086.217923095386, 18493.352732654294, 19113.0, 19206.559508036953, 19327.226910923404, 19610.313639022566, 1036.657902892059, 1053.6364954090827, 1054.7890421915495, 1084.6583326252362, 1088.1947358954317, 10488.02909788551, 10870.293430926964, 10872.0, 10921.836172457419, 10957.232993690364, 521575.0, 534846.0, 536838.0, 541933.0, 544609.0, 52076.0, 68962.65518672374, 89072.5546372277, 90155.0, 90469.0, 1034.3483605328595, 1044.8328267477318, 1045.8012977534245, 1062.9415382154057, 1063.8191507443753, 16746.83253167453, 17137.0, 17211.655766884844, 17265.482035538953, 17267.91366043162, 39338.0, 41066.0, 41395.58604413917, 42320.788396057826, 42540.149197015264, 55735.0, 57120.42879571151, 57827.71086144543, 58058.0, 58113.70943145426, 8660.870086948575, 8769.166929141722, 8918.509481978554, 8940.240079593308, 8955.328350373618, 11507.561554805648, 12754.234745915275, 13397.397117129558, 13444.865551344361, 13519.12125711865, 11601.129915256559, 11905.940470297593, 11984.64046078619, 12106.092043096627, 12201.755964880831, 11628.360440175871, 11676.649700508999, 11695.590654327058, 11735.354555499189, 11743.647690569296, 12104.576339828063, 12125.75748485043, 12243.816342754688, 12270.202436841577, 12290.078244131904, 7539.208383119693, 8836.823263534565, 8858.379913406, 8916.643334266557, 9051.094890510896, 12034.939825300817, 12412.131150819356, 12413.0, 12469.314187719763, 12696.746065078461], "value": 9172.565005211656, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1522.086747951229, 1547.0872185410608, 1692.8645708343333, 1850.0749625187407, 1856.2482194711138, 7877.251661092187, 8640.956795216023, 8780.121987801216, 9243.861342079867, 9296.395734277266, 8703.825923481527, 8947.194752472276, 9007.774805629862, 9035.096490350961, 9047.592858321375, 2312.1560630369877, 2360.4334959609655, 2361.4332560185517, 2367.9644805327935, 2370.3481542575755, 2355.95288094238, 2375.524895020994, 2392.031227352916, 2395.161693407311, 2399.316194884458, 2361.4686695493547, 2381.714194296687, 2393.401649587603, 2418.613021916494, 2420.745821688724, 2297.356740112771, 2308.6306191009357, 2310.4223944013997, 2315.780000899912, 2345.6129738593113, 2274.7725227477285, 2281.7147856517936, 2298.7356454007836, 2304.2741536416142, 2306.0545176477563, 9004.954975225148, 9043.728688139367, 9068.0, 9073.274138068939, 9075.63697452101, 5135.768890399903, 5298.496642818926, 5325.707086110262, 5335.386430560497, 5341.786328546854, 3365.394229038772, 3417.5215469834243, 3463.8960831175104, 3532.2758834439137, 3546.184377593142, 1120.4117838134962, 1124.42092322454, 1142.320319409958, 1146.1289420040746, 1146.2778449576797, 12532.0, 12561.371931403393, 12566.81149782754, 12594.43325050375, 12626.558070467581, 4701.177293973541, 4710.26990816424, 4780.95219047805, 4789.185838407479, 4839.128956787812, 10536.209784266211, 10571.682849514527, 10615.363078215332, 10627.14982801374, 10648.574057037631, 5403.702796346239, 5463.235147079412, 5470.8085217017215, 5472.425395333433, 5496.340439147328, 4407.713498622579, 4813.831515896927, 4846.466888642247, 4946.629002824802, 4990.925136122961, 4085.9387109193385, 4148.709590328648, 4162.771047592411, 4179.2895207814745, 4188.706790524634, 1293.6442478318538, 1315.15829868884, 1327.3296985022696, 1337.0, 1345.663584103974, 956.636478138303, 978.2907392140784, 985.2315194148575, 992.9056739609725, 1005.9949700251452, 5367.677939323653, 5569.415211402745, 5588.804391846347, 5605.411431799684, 5640.915386269209, 483.5792860211568, 486.46488862251243, 489.21969458713295, 1069.337011053152, 1089.9891001090045, 3956.248312820555, 4076.408920706481, 4101.733387329814, 4122.113745544722, 4138.544760076449, 10722.624708135334, 11312.547498099984, 11405.885941140648, 11484.827727584094, 11488.310701357945, 11051.502682379312, 11197.272177308449, 11314.151438642135, 11335.206535542598, 11345.602903898489, 8413.873791893126, 8463.195996380335, 8542.658293668184, 8580.914190858137, 8581.78545536354, 5964.821055368346, 5967.373425790403, 5985.102234664835, 6049.364816694359, 6080.695965201634, 9739.41563506192, 10176.745581360228, 10299.0, 10529.315594486334, 10558.788824223318, 10479.42363170033, 10479.633212837514, 10498.47507624631, 10512.474376281309, 10578.735531611772, 7330.81672958159, 7867.685292588461, 8084.272415482635, 8290.253877151084, 8548.230659240699, 8554.059053503985, 8556.786080348042, 8583.399162058597, 8585.527795971282, 8822.0, 7530.698772049057, 7860.528368297921, 8065.951426314541, 8075.030996280484, 8162.469439486415, 7689.423293252917, 7815.452918295665, 7856.0, 7887.092984306878, 7936.365090792839, 6915.0, 6946.37482626566, 7024.473164512579, 7068.222495525385, 7070.964645176741, 5036.8992620146655, 5059.089363914531, 5153.175491921274, 5193.61047921415, 5207.0366982108435, 4919.704817710949, 5039.672421292605, 5071.2139618359215, 5088.974555127201, 5214.061468935628], "value": 4689.995677695168, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [335.1035978756825, 342.2180317973431, 344.918417351285, 349.07146989009243, 349.5211560162576, 5391.99999999999, 5487.807926722566, 5524.723763811813, 5537.861553461161, 5573.972130139354, 5469.371022332433, 5476.945230547693, 5502.706863886983, 5597.557502136187, 5603.159526071081, 4321.935170972438, 4336.306191009439, 4339.631131353839, 4343.6959412841115, 4362.585554372329, 4073.8981525461954, 4288.849890253841, 4319.855238361836, 4356.760378179199, 4373.671974601902, 4207.474065741782, 4226.323788193889, 4233.111046680186, 4261.701680882339, 4265.210935976838, 4260.808263628144, 4269.658427325822, 4281.2293787118615, 4282.2077915585405, 4286.207051695446, 3532.523109380252, 3712.7586706863967, 3720.4605332226674, 3728.3475391806323, 3731.9626803731885, 5239.711815850127, 5277.551408130314, 5286.3392075990505, 5326.440723723992, 5351.170568561879, 4867.51324867514, 4880.341153944208, 4908.478371704748, 4912.508749125094, 4912.6806757561, 5645.616823878177, 5755.194272801811, 5759.568032397545, 5782.513519186748, 5790.7973220937065, 4086.6322031017053, 4091.8158682859344, 4120.546739858613, 4123.9381409278585, 4129.215449064668, 5761.337446193699, 5982.312034116088, 5991.850203744898, 5999.610025348338, 6010.57925945184, 4186.811593478301, 4210.621044106045, 4211.073563815956, 4213.705040647125, 4222.303319952235, 4239.876432745325, 4246.893827654271, 4259.74441533509, 4270.864567716142, 4295.763732994653, 4889.095517329305, 4940.382452193476, 4958.652894297436, 4967.627427942919, 4973.35346404965, 5632.760792625616, 5724.742386592613, 5756.654600723886, 5810.215620891126, 5839.328477225088, 1455.2505459688268, 1463.6121427821636, 1481.0595272002356, 1488.4939120699014, 1493.0295308049638, 943.8725772020689, 958.4584709639032, 960.6637676813059, 961.6730311694058, 967.0281367225892, 5029.108262748934, 5031.018951304533, 5052.520010598987, 5060.974695126501, 5065.316182315414, 5243.0, 5275.762590683428, 5306.8142615009065, 5334.946650533522, 5359.22291267764, 5016.313009937693, 5043.52086551777, 5062.240663900371, 5280.498352656491, 5754.74103665336, 3195.584574005411, 3203.9038828835173, 3210.4863221884852, 3213.196700824794, 3225.53229781685, 6048.274207095089, 6121.785737499166, 6168.907466388007, 6171.660558669317, 6214.378562143749, 6107.633541987495, 6112.441327461469, 6116.510679145657, 6117.816465506042, 6151.6001459904965, 6212.968935155386, 6227.450353298576, 6234.407731265523, 6250.218722659668, 6271.648131950518, 5829.854253643693, 5968.970155149196, 5985.234355864944, 6000.639961602318, 6035.0, 5906.2026626406605, 5933.406659334032, 5936.1095835625, 5991.610545314541, 6010.278766547867, 5921.733521991435, 6173.722182501709, 6228.595141315981, 6257.906131407942, 6263.843403914939, 4196.433481479961, 4205.3271476563605, 4227.915441691088, 4230.450041494709, 4232.5662743922985, 5686.346070201815, 5744.253247077853, 5973.761049557969, 5980.5215582754145, 5991.022962422478, 5730.398308177581, 5763.9135412968, 5767.9711601443405, 5809.477147056785, 5858.0, 5732.254806875247, 5733.397993210653, 5761.452661997104, 5793.391693872083, 5825.077724350869, 5648.971755141198, 5653.717314134195, 5655.388214358888, 5662.801801936913, 5748.252727145445, 5764.711764411846, 5767.68277744728, 5806.68063256525, 5812.418758124154, 5828.883422331614, 5449.809256675997, 5613.971930140323, 5630.465105814941, 5696.829095127153, 5697.914531282117], "value": 4377.425401794695, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [729.1578227147644, 752.9435292353074, 766.4826242286457, 777.3742137579246, 777.7861088200748, 14404.495842645509, 14491.985561010733, 14564.490242841503, 14667.11997280164, 15258.313375898084, 14076.0, 14163.291835408214, 14308.71382572348, 14314.642133946656, 14336.354864031118, 832.4339449174558, 836.4437648963432, 849.02362283374, 852.846487632226, 860.6471346747835, 858.2104463893202, 860.1957170046027, 860.8321377331391, 862.7584276402586, 863.6545381847249, 890.1410139215656, 893.2942975049702, 898.9730308090735, 899.9010108888017, 903.9276857851389, 881.0220655072864, 881.5636260051284, 884.5886662701843, 885.791838917857, 887.4675194883083, 648.3516483516484, 652.4454213918132, 653.9836504087389, 656.1437324291797, 656.1961597043584, 13787.103838250581, 13843.100198487065, 13850.93074534631, 13856.722865542733, 13872.098313609584, 13497.2576508292, 13648.863511364858, 13664.65838354039, 13814.0, 13827.861721382758, 10677.012185126492, 10811.675649730521, 10865.782684346348, 10899.564017439294, 10954.780904381867, 3724.3110024645525, 3797.4493698413853, 3839.2513459875336, 3849.8652547160996, 3855.7879316637573, 52501.0, 52957.0, 53245.46754532444, 53411.198832017166, 53546.0, 10670.46647667629, 10680.893191068146, 10715.46422678863, 10841.295315804447, 10919.235653504338, 16454.42409515685, 18363.63272734538, 18364.724529132072, 18472.722909156375, 18479.81520184781, 8250.504969701837, 8430.283425908743, 8446.577671116542, 8486.787830304293, 8525.31797456202, 4739.573438390561, 4762.499937506581, 4781.354517140211, 4852.8786780330065, 4964.478729733326, 3346.247094403788, 3482.7736197147105, 3483.529723487297, 3486.599041110305, 3503.7722548034294, 648.3192647719875, 657.9769708060195, 659.1595715462823, 669.8526324208666, 674.844785699292, 629.8425393651587, 732.4067505320708, 748.6107224243362, 758.135725273189, 766.7744322442536, 9005.369624126379, 9005.819883602291, 9132.452052876848, 9294.907050929403, 9414.811703765887, 673.3603077076797, 750.4746677325894, 753.1376573822942, 790.4433614644022, 794.8688466403095, 4480.395146655225, 4567.5432456754725, 4619.769011549409, 4621.930671039937, 4736.171170045228, 61758.382416175264, 61811.690941545006, 61880.69059654673, 63229.0, 65584.34415655783, 55469.1679624806, 57237.42762572404, 57500.0, 57759.42240577625, 59211.40788592059, 51452.742736286076, 51710.74144629253, 51765.7411712939, 52251.0, 53073.734631326595, 8006.959965200137, 8272.214139656722, 8394.622241999246, 9124.817503650023, 9509.524523773922, 33945.151371215914, 34831.65168348284, 35198.29603407866, 35382.0, 36006.27987440289, 21648.675269870648, 21705.565888682453, 30085.247868803453, 33958.66041339654, 45608.54391456043, 12620.242785432903, 12646.30445325516, 12655.683607909876, 12689.746205076031, 12880.098393112392, 13150.27673477968, 13151.408186631434, 13202.405891735087, 13208.73582528325, 13607.455701771818, 12105.576304829288, 12410.81383779262, 12464.940480058935, 12536.185147965352, 13003.609891703263, 12579.07409161361, 12860.549880754128, 12903.032272579781, 12983.610491685264, 12992.220466771645, 11536.59621913229, 11658.12564057716, 11759.353235572125, 12539.811902821648, 12602.180858244184, 9762.658306959222, 10026.69919902404, 10082.092611664986, 10223.335483193569, 10351.120154786928, 10916.727081823017, 10939.343639381663, 10997.065249453886, 11032.172587055842, 11033.172512061788], "value": 6735.749304262324, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1558.1999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 113.0, 103.0, 109.0, 111.0, 112.0, 105.0, 111.0, 111.0, 112.0], "value": 109.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 123.0, 150.0, 207.0, 125.0, 149.0, 159.0, 127.0, 190.0, 126.0], "value": 150.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 162.0, 162.0, 163.0, 164.0, 170.0, 164.0, 166.0, 162.0, 161.0], "value": 163.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 76.0, 167.0, 86.0, 75.0, 75.0, 76.0, 166.0, 80.0], "value": 95.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 93.0, 175.0, 92.0, 94.0, 92.0, 142.0, 91.0, 92.0, 155.0], "value": 111.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 117.0, 117.0, 118.0, 118.0, 117.0, 118.0, 116.0, 119.0], "value": 117.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 149.0, 146.0, 146.0, 147.0, 146.0, 146.0, 147.0, 146.0], "value": 146.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 95.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 58.0, 62.0, 58.0, 59.0, 58.0, 59.0, 57.0, 58.0, 59.0], "value": 58.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 59.0, 60.0, 60.0, 59.0, 60.0, 59.0, 59.0, 59.0, 59.0], "value": 59.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 79.0, 78.0, 78.0, 80.0, 80.0, 82.0, 80.0, 133.0, 84.0], "value": 85.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 141.0, 133.0, 130.0, 127.0, 130.0, 131.0, 125.0, 143.0, 133.0], "value": 133.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 166.0, 171.0, 166.0, 170.0, 166.0, 166.0, 163.0, 164.0, 169.0], "value": 166.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 68.0, 65.0, 64.0, 64.0, 63.0, 64.0, 64.0, 65.0, 67.0], "value": 64.9, "unit": "score"}]}]} 20:37:10 INFO - Return code: 0 20:37:10 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 20:37:10 ERROR - # TBPL SUCCESS # 20:37:10 INFO - Running post-action listener: _resource_record_post_action 20:37:10 INFO - ##### 20:37:10 INFO - ##### Finished run-tests step (success) 20:37:10 INFO - ##### 20:37:10 INFO - Running post-run listener: _resource_record_post_run 20:37:11 INFO - Total resource usage - Wall time: 1193s; CPU: 13.0%; Read bytes: 7929856; Write bytes: 378900480; Read time: 1900; Write time: 288692 20:37:11 INFO - TinderboxPrint: CPU usage
13.4% 20:37:11 INFO - TinderboxPrint: I/O read bytes / time
7,929,856 / 1,900 20:37:11 INFO - TinderboxPrint: I/O write bytes / time
378,900,480 / 288,692 20:37:11 INFO - TinderboxPrint: CPU idle
8,244.8 (86.6%) 20:37:11 INFO - TinderboxPrint: CPU user
1,216.4 (12.8%) 20:37:11 INFO - TinderboxPrint: Swap in / out
0 / 0 20:37:11 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 92594176; Read time: 0; Write time: 89732 20:37:11 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 6193152; Write bytes: 286306304; Read time: 1864; Write time: 198960 20:37:11 INFO - Running post-run listener: _upload_blobber_files 20:37:11 INFO - Blob upload gear active. 20:37:11 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 20:37:11 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 20:37:11 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'] 20:37:11 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 20:37:11 INFO - (blobuploader) - INFO - Open directory for files ... 20:37:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 20:37:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:37:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:37:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 20:37:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:37:12 INFO - (blobuploader) - INFO - Done attempting. 20:37:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 20:37:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:37:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:37:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 20:37:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:37:13 INFO - (blobuploader) - INFO - Done attempting. 20:37:13 INFO - (blobuploader) - INFO - Iteration through files over. 20:37:13 INFO - Return code: 0 20:37:13 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 20:37:13 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 20:37:13 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/bce57046994e8e2aafd083f74d09e06188662efdd90b288e872912d95997582bbaef56e555b078013afda7bf835720d110c61bfd517af80b707f9ac7648f1107", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7e9741a13e08d349caae260ac237695a62cab2d17c4f6598e34ff97123aab61a4fc88b5ac7e184b93c56c44ef0c7c05f81459c86cb89ec343b542a8c3753133c"} 20:37:13 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 20:37:13 INFO - Writing to file /builds/slave/test/properties/blobber_files 20:37:13 INFO - Contents: 20:37:13 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/bce57046994e8e2aafd083f74d09e06188662efdd90b288e872912d95997582bbaef56e555b078013afda7bf835720d110c61bfd517af80b707f9ac7648f1107", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7e9741a13e08d349caae260ac237695a62cab2d17c4f6598e34ff97123aab61a4fc88b5ac7e184b93c56c44ef0c7c05f81459c86cb89ec343b542a8c3753133c"} 20:37:13 INFO - Running post-run listener: copy_logs_to_upload_dir 20:37:13 INFO - Copying logs to upload dir... 20:37:13 INFO - mkdir: /builds/slave/test/build/upload/logs 20:37:13 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1239.242081 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2016-05-12 20:37:13.331644) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-12 20:37:13.334857) ========= 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=41bf3e9aed84707956354da8000001c5-1463108004.356592-147707600 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/bce57046994e8e2aafd083f74d09e06188662efdd90b288e872912d95997582bbaef56e555b078013afda7bf835720d110c61bfd517af80b707f9ac7648f1107", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7e9741a13e08d349caae260ac237695a62cab2d17c4f6598e34ff97123aab61a4fc88b5ac7e184b93c56c44ef0c7c05f81459c86cb89ec343b542a8c3753133c"} build_url:https://queue.taskcluster.net/v1/task/Xo5WwOniQJWIVYiSxjFSbQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011179 build_url: 'https://queue.taskcluster.net/v1/task/Xo5WwOniQJWIVYiSxjFSbQ/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/bce57046994e8e2aafd083f74d09e06188662efdd90b288e872912d95997582bbaef56e555b078013afda7bf835720d110c61bfd517af80b707f9ac7648f1107", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7e9741a13e08d349caae260ac237695a62cab2d17c4f6598e34ff97123aab61a4fc88b5ac7e184b93c56c44ef0c7c05f81459c86cb89ec343b542a8c3753133c"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-12 20:37:13.359747) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 20:37:13.360064) ========= 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=41bf3e9aed84707956354da8000001c5-1463108004.356592-147707600 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004437 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 20:37:13.444454) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-12 20:37:13.444768) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-12 20:37:13.445063) ========= ========= Total master_lag: 0.17 =========