builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-034 starttime: 1463601573.3 results: success (0) buildid: 20160517073121 builduid: 91748e3a507046789edb563a3e6ea041 revision: 3780a3a6b83aeda143f9562829c830410a0c961e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-18 12:59:33.303670) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-18 12:59:33.304067) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-18 12:59:33.304339) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-18 12:59:33.432667) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 12:59:33.432929) ========= 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=d6bbd613908ccac0c6c9cb47000001f7-1463601238.620114-1974523070 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-18 12:59:33-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 38.1M=0s 2016-05-18 12:59:33 (38.1 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.106577 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 12:59:33.567629) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 12:59:33.567905) ========= 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=d6bbd613908ccac0c6c9cb47000001f7-1463601238.620114-1974523070 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018154 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 12:59:33.622754) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-18 12:59:33.623066) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 3780a3a6b83aeda143f9562829c830410a0c961e --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 3780a3a6b83aeda143f9562829c830410a0c961e --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=d6bbd613908ccac0c6c9cb47000001f7-1463601238.620114-1974523070 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-18 12:59:34,022 truncating revision to first 12 chars 2016-05-18 12:59:34,022 Setting DEBUG logging. 2016-05-18 12:59:34,022 attempt 1/10 2016-05-18 12:59:34,022 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/3780a3a6b83a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-18 12:59:35,160 unpacking tar archive at: mozilla-central-3780a3a6b83a/testing/mozharness/ program finished with exit code 0 elapsedTime=1.405906 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-18 12:59:35.045041) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-18 12:59:35.045394) ========= script_repo_revision: 3780a3a6b83aeda143f9562829c830410a0c961e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-18 12:59:35.045850) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-18 12:59:35.046148) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-18 12:59:35.055465) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 45 secs) (at 2016-05-18 12:59:35.055887) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] 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=d6bbd613908ccac0c6c9cb47000001f7-1463601238.620114-1974523070 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 12:59:35 INFO - MultiFileLogger online at 20160518 12:59:35 in /builds/slave/test 12:59:35 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 12:59:35 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:59:35 INFO - {'append_to_log': False, 12:59:35 INFO - 'base_work_dir': '/builds/slave/test', 12:59:35 INFO - 'blob_upload_branch': 'Firefox', 12:59:35 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:59:35 INFO - 'branch': 'Firefox', 12:59:35 INFO - 'buildbot_json_path': 'buildprops.json', 12:59:35 INFO - 'config_files': ('talos/linux_config.py',), 12:59:35 INFO - 'default_actions': ('clobber', 12:59:35 INFO - 'read-buildbot-config', 12:59:35 INFO - 'download-and-extract', 12:59:35 INFO - 'populate-webroot', 12:59:35 INFO - 'create-virtualenv', 12:59:35 INFO - 'install', 12:59:35 INFO - 'run-tests'), 12:59:35 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:59:35 INFO - 'download_minidump_stackwalk': True, 12:59:35 INFO - 'download_symbols': 'ondemand', 12:59:35 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:59:35 INFO - 'tooltool.py': '/tools/tooltool.py', 12:59:35 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:59:35 INFO - '/tools/misc-python/virtualenv.py')}, 12:59:35 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:59:35 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:59:35 INFO - 'installer_path': 'installer.exe', 12:59:35 INFO - 'log_level': 'info', 12:59:35 INFO - 'log_name': 'talos', 12:59:35 INFO - 'log_to_console': True, 12:59:35 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 12:59:35 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 12:59:35 INFO - 'opt_config_files': (), 12:59:35 INFO - 'pip_index': False, 12:59:35 INFO - 'sps_profile': False, 12:59:35 INFO - 'sps_profile_interval': 0, 12:59:35 INFO - 'suite': 'dromaeojs', 12:59:35 INFO - 'system_bits': '32', 12:59:35 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:59:35 INFO - 'title': 'talos-linux64-ix-034', 12:59:35 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:59:35 INFO - 'use_talos_json': True, 12:59:35 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:59:35 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:59:35 INFO - 'work_dir': 'build'} 12:59:35 INFO - ##### 12:59:35 INFO - ##### Running clobber step. 12:59:35 INFO - ##### 12:59:35 INFO - Running pre-action listener: _resource_record_pre_action 12:59:35 INFO - Running main action method: clobber 12:59:35 INFO - rmtree: /builds/slave/test/build 12:59:35 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:59:36 INFO - Running post-action listener: _resource_record_post_action 12:59:36 INFO - ##### 12:59:36 INFO - ##### Finished clobber step (success) 12:59:36 INFO - ##### 12:59:36 INFO - ##### 12:59:36 INFO - ##### Running read-buildbot-config step. 12:59:36 INFO - ##### 12:59:36 INFO - Running pre-action listener: _resource_record_pre_action 12:59:36 INFO - Running main action method: read_buildbot_config 12:59:36 INFO - Using buildbot properties: 12:59:36 INFO - { 12:59:36 INFO - "project": "", 12:59:36 INFO - "product": "firefox", 12:59:36 INFO - "script_repo_revision": "production", 12:59:36 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 12:59:36 INFO - "repository": "", 12:59:36 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 12:59:36 INFO - "buildid": "20160517073121", 12:59:36 INFO - "pgo_build": "True", 12:59:36 INFO - "basedir": "/builds/slave/test", 12:59:36 INFO - "buildnumber": 447, 12:59:36 INFO - "slavename": "talos-linux64-ix-034", 12:59:36 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 12:59:36 INFO - "platform": "ubuntu64_hw", 12:59:36 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 12:59:36 INFO - "branch": "mozilla-central", 12:59:36 INFO - "repo_path": "mozilla-central", 12:59:36 INFO - "revision": "3780a3a6b83aeda143f9562829c830410a0c961e", 12:59:36 INFO - "stage_platform": "linux64-pgo", 12:59:36 INFO - "builduid": "91748e3a507046789edb563a3e6ea041", 12:59:36 INFO - "slavebuilddir": "test" 12:59:36 INFO - } 12:59:36 INFO - Found installer url https://queue.taskcluster.net/v1/task/ARDPPwsRRDOz2R2e9Cnw-Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 12:59:36 INFO - Running post-action listener: _resource_record_post_action 12:59:36 INFO - ##### 12:59:36 INFO - ##### Finished read-buildbot-config step (success) 12:59:36 INFO - ##### 12:59:36 INFO - ##### 12:59:36 INFO - ##### Running download-and-extract step. 12:59:36 INFO - ##### 12:59:36 INFO - Running pre-action listener: _resource_record_pre_action 12:59:36 INFO - Running main action method: download_and_extract 12:59:36 INFO - mkdir: /builds/slave/test/build/tests 12:59: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')]} 12:59:36 INFO - https://queue.taskcluster.net/v1/task/ARDPPwsRRDOz2R2e9Cnw-Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 12:59:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ARDPPwsRRDOz2R2e9Cnw-Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 12:59:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ARDPPwsRRDOz2R2e9Cnw-Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 12:59:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ARDPPwsRRDOz2R2e9Cnw-Q/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 12:59:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ARDPPwsRRDOz2R2e9Cnw-Q/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 12:59:37 INFO - Downloaded 1361 bytes. 12:59:37 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 12:59:37 INFO - Using the following test package requirements: 12:59:37 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 12:59:37 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 12:59:37 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 12:59:37 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 12:59:37 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 12:59:37 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 12:59:37 INFO - u'jsshell-linux-x86_64.zip'], 12:59:37 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 12:59:37 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 12:59:37 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 12:59:37 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 12:59:37 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 12:59:37 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 12:59:37 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 12:59:37 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 12:59:37 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 12:59:37 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 12:59:37 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 12:59:37 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 12:59:37 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')]} 12:59:37 INFO - https://queue.taskcluster.net/v1/task/ARDPPwsRRDOz2R2e9Cnw-Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 12:59:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ARDPPwsRRDOz2R2e9Cnw-Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 12:59:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ARDPPwsRRDOz2R2e9Cnw-Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 12:59:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ARDPPwsRRDOz2R2e9Cnw-Q/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 12:59:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ARDPPwsRRDOz2R2e9Cnw-Q/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 12:59:38 INFO - Downloaded 24023024 bytes. 12:59:38 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'] 12:59:38 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 12:59:40 INFO - Return code: 0 12:59:40 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 12:59:40 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')]} 12:59:40 INFO - https://queue.taskcluster.net/v1/task/ARDPPwsRRDOz2R2e9Cnw-Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 12:59:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ARDPPwsRRDOz2R2e9Cnw-Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 12:59:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ARDPPwsRRDOz2R2e9Cnw-Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 12:59:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ARDPPwsRRDOz2R2e9Cnw-Q/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 12:59:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ARDPPwsRRDOz2R2e9Cnw-Q/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 12:59:43 INFO - Downloaded 24023024 bytes. 12:59:43 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'] 12:59:43 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 12:59:45 INFO - Return code: 0 12:59:45 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')]} 12:59:45 INFO - https://queue.taskcluster.net/v1/task/ARDPPwsRRDOz2R2e9Cnw-Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 12:59:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ARDPPwsRRDOz2R2e9Cnw-Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 12:59:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ARDPPwsRRDOz2R2e9Cnw-Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 12:59:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ARDPPwsRRDOz2R2e9Cnw-Q/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 12:59:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ARDPPwsRRDOz2R2e9Cnw-Q/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 12:59:48 INFO - Downloaded 10840194 bytes. 12:59:48 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'] 12:59:48 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 12:59:49 INFO - Return code: 0 12:59: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')]} 12:59:49 INFO - https://queue.taskcluster.net/v1/task/ARDPPwsRRDOz2R2e9Cnw-Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 12:59:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ARDPPwsRRDOz2R2e9Cnw-Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 12:59:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ARDPPwsRRDOz2R2e9Cnw-Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 12:59:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ARDPPwsRRDOz2R2e9Cnw-Q/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 12:59:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ARDPPwsRRDOz2R2e9Cnw-Q/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 12:59:54 INFO - Downloaded 58399391 bytes. 12:59:54 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ARDPPwsRRDOz2R2e9Cnw-Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 12:59:54 INFO - mkdir: /builds/slave/test/properties 12:59:54 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:59:54 INFO - Writing to file /builds/slave/test/properties/build_url 12:59:54 INFO - Contents: 12:59:54 INFO - build_url:https://queue.taskcluster.net/v1/task/ARDPPwsRRDOz2R2e9Cnw-Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 12:59:54 INFO - Running post-action listener: _resource_record_post_action 12:59:54 INFO - Running post-action listener: set_extra_try_arguments 12:59:54 INFO - ##### 12:59:54 INFO - ##### Finished download-and-extract step (success) 12:59:54 INFO - ##### 12:59:54 INFO - ##### 12:59:54 INFO - ##### Running populate-webroot step. 12:59:54 INFO - ##### 12:59:54 INFO - Running pre-action listener: _resource_record_pre_action 12:59:54 INFO - Running main action method: populate_webroot 12:59:54 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:59:54 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:59:54 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:59:54 INFO - '--tppagecycles', 12:59:54 INFO - '7'], 12:59:54 INFO - 'tests': ['tsvgm']}}, 12:59:54 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:59:54 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:59:54 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:59:54 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:59:54 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:59:54 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:59:54 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:59:54 INFO - 'talos_options': ['--disable-e10s'], 12:59:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:59:54 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:59:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:59:54 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:59:54 INFO - 'talos_options': ['--disable-e10s'], 12:59:54 INFO - 'tests': ['damp', 'tps']}, 12:59:54 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:59:54 INFO - 'tests': ['damp', 'tps']}, 12:59:54 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:59:54 INFO - 'tests': ['dromaeo_dom']}, 12:59:54 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:59:54 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:59:54 INFO - 'tests': ['a11yr', 12:59:54 INFO - 'ts_paint', 12:59:54 INFO - 'tpaint', 12:59:54 INFO - 'sessionrestore', 12:59:54 INFO - 'sessionrestore_no_auto_restore', 12:59:54 INFO - 'tabpaint']}, 12:59:54 INFO - 'other-e10s': {'tests': ['a11yr', 12:59:54 INFO - 'ts_paint', 12:59:54 INFO - 'tpaint', 12:59:54 INFO - 'sessionrestore', 12:59:54 INFO - 'sessionrestore_no_auto_restore', 12:59:54 INFO - 'tabpaint']}, 12:59:54 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:59:54 INFO - 'ts_paint', 12:59:54 INFO - 'tpaint', 12:59:54 INFO - 'sessionrestore', 12:59:54 INFO - 'sessionrestore_no_auto_restore', 12:59:54 INFO - 'tabpaint']}, 12:59:54 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:59:54 INFO - 'ts_paint', 12:59:54 INFO - 'tpaint', 12:59:54 INFO - 'sessionrestore', 12:59:54 INFO - 'sessionrestore_no_auto_restore', 12:59:54 INFO - 'tabpaint']}, 12:59:54 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:59:54 INFO - 'tests': ['a11yr', 12:59:54 INFO - 'ts_paint', 12:59:54 INFO - 'tpaint', 12:59:54 INFO - 'sessionrestore', 12:59:54 INFO - 'sessionrestore_no_auto_restore', 12:59:54 INFO - 'tabpaint']}, 12:59:54 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:59:54 INFO - 'tests': ['a11yr', 12:59:54 INFO - 'ts_paint', 12:59:54 INFO - 'tpaint', 12:59:54 INFO - 'sessionrestore', 12:59:54 INFO - 'sessionrestore_no_auto_restore', 12:59:54 INFO - 'tabpaint']}, 12:59:54 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:59:54 INFO - 'tests': ['tsvgx', 12:59:54 INFO - 'tsvgr_opacity', 12:59:54 INFO - 'tart', 12:59:54 INFO - 'tscrollx', 12:59:54 INFO - 'cart']}, 12:59:54 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:59:54 INFO - 'tsvgr_opacity', 12:59:54 INFO - 'tart', 12:59:54 INFO - 'tscrollx', 12:59:54 INFO - 'cart']}, 12:59:54 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:59:54 INFO - 'talos_options': ['--disable-e10s'], 12:59:54 INFO - 'tests': ['tp5o']}, 12:59:54 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:59:54 INFO - 'tests': ['tp5o']}, 12:59:54 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:59:54 INFO - 'talos_options': ['--disable-e10s', 12:59:54 INFO - '--xperf_path', 12:59:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:59:54 INFO - 'tests': ['tp5n']}, 12:59:54 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:59:54 INFO - 'talos_options': ['--xperf_path', 12:59:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:59:54 INFO - 'tests': ['tp5n']}}, 12:59:54 INFO - 'talos.zip': {'path': '', 12:59:54 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:59:54 INFO - Running post-action listener: _resource_record_post_action 12:59:54 INFO - ##### 12:59:54 INFO - ##### Finished populate-webroot step (success) 12:59:54 INFO - ##### 12:59:54 INFO - ##### 12:59:54 INFO - ##### Running create-virtualenv step. 12:59:54 INFO - ##### 12:59:54 INFO - Running pre-action listener: _resource_record_pre_action 12:59:54 INFO - Running main action method: create_virtualenv 12:59:54 INFO - Creating virtualenv /builds/slave/test/build/venv 12:59:54 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 12:59:54 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:59:54 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:59:54 INFO - Using real prefix '/usr' 12:59:54 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:59:55 INFO - Installing distribute.............................................................................................................................................................................................done. 12:59:57 INFO - Installing pip.................done. 12:59:57 INFO - Return code: 0 12:59:57 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:59:57 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')]} 12:59:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:59:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:59:57 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')]} 12:59:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:59:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:59:57 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 0x7fbe7d57cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13ae8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1479770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15089c0>, '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 0x15098c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1509d50>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1463601238.620114-1974523070', '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 12:59:57 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 12:59:57 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 12:59:57 INFO - Using env: {'DISPLAY': ':0', 12:59:57 INFO - 'HOME': '/home/cltbld', 12:59:57 INFO - 'LANG': 'en_US.UTF-8', 12:59:57 INFO - 'LANGUAGE': 'en_US:en', 12:59:57 INFO - 'LOGNAME': 'cltbld', 12:59:57 INFO - 'MAIL': '/var/mail/cltbld', 12:59:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:59:57 INFO - 'MOZ_NO_REMOTE': '1', 12:59:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:59:57 INFO - 'NO_EM_RESTART': '1', 12:59:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:59:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:59:57 INFO - 'PWD': '/builds/slave/test', 12:59:57 INFO - 'SHELL': '/bin/bash', 12:59:57 INFO - 'SHLVL': '1', 12:59:57 INFO - 'TERM': 'linux', 12:59:57 INFO - 'TMOUT': '86400', 12:59:57 INFO - 'USER': 'cltbld', 12:59:57 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1463601238.620114-1974523070', 12:59:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:59:57 INFO - '_': '/tools/buildbot/bin/python'} 12:59:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:59:57 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 12:59:57 INFO - Cleaning up... 12:59:57 INFO - Return code: 0 12:59:57 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:59:57 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')]} 12:59:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:59:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:59:57 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')]} 12:59:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:59:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:59:57 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>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe7d57cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13ae8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1479770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15089c0>, '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 0x15098c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1509d50>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1463601238.620114-1974523070', '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 12:59:57 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>=3.1.1'] in /builds/slave/test/build 12:59:57 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>=3.1.1 12:59:57 INFO - Using env: {'DISPLAY': ':0', 12:59:57 INFO - 'HOME': '/home/cltbld', 12:59:57 INFO - 'LANG': 'en_US.UTF-8', 12:59:57 INFO - 'LANGUAGE': 'en_US:en', 12:59:57 INFO - 'LOGNAME': 'cltbld', 12:59:57 INFO - 'MAIL': '/var/mail/cltbld', 12:59:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:59:57 INFO - 'MOZ_NO_REMOTE': '1', 12:59:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:59:57 INFO - 'NO_EM_RESTART': '1', 12:59:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:59:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:59:57 INFO - 'PWD': '/builds/slave/test', 12:59:57 INFO - 'SHELL': '/bin/bash', 12:59:57 INFO - 'SHLVL': '1', 12:59:57 INFO - 'TERM': 'linux', 12:59:57 INFO - 'TMOUT': '86400', 12:59:57 INFO - 'USER': 'cltbld', 12:59:57 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1463601238.620114-1974523070', 12:59:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:59:57 INFO - '_': '/tools/buildbot/bin/python'} 12:59:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:59:57 INFO - Downloading/unpacking psutil>=3.1.1 12:59:57 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 12:59:57 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 12:59:57 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 12:59:57 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 12:59:57 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 12:59:57 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 12:59:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:59:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:59:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:59:59 INFO - Installing collected packages: psutil 12:59:59 INFO - Running setup.py install for psutil 12:59:59 INFO - building 'psutil._psutil_linux' extension 12:59:59 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 13:00:00 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 13:00:00 INFO - building 'psutil._psutil_posix' extension 13:00:00 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 13:00:00 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 13:00:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:00:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:00:00 INFO - Successfully installed psutil 13:00:00 INFO - Cleaning up... 13:00:00 INFO - Return code: 0 13:00:00 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 13:00: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')]} 13:00:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:00:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:00: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')]} 13:00:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:00:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:00: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe7d57cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13ae8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1479770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15089c0>, '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 0x15098c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1509d50>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1463601238.620114-1974523070', '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 13:00: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 13:00: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 mozsystemmonitor==0.3 13:00:00 INFO - Using env: {'DISPLAY': ':0', 13:00:00 INFO - 'HOME': '/home/cltbld', 13:00:00 INFO - 'LANG': 'en_US.UTF-8', 13:00:00 INFO - 'LANGUAGE': 'en_US:en', 13:00:00 INFO - 'LOGNAME': 'cltbld', 13:00:00 INFO - 'MAIL': '/var/mail/cltbld', 13:00:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:00:00 INFO - 'MOZ_NO_REMOTE': '1', 13:00:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:00:00 INFO - 'NO_EM_RESTART': '1', 13:00:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:00:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:00:00 INFO - 'PWD': '/builds/slave/test', 13:00:00 INFO - 'SHELL': '/bin/bash', 13:00:00 INFO - 'SHLVL': '1', 13:00:00 INFO - 'TERM': 'linux', 13:00:00 INFO - 'TMOUT': '86400', 13:00:00 INFO - 'USER': 'cltbld', 13:00:00 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1463601238.620114-1974523070', 13:00:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:00:00 INFO - '_': '/tools/buildbot/bin/python'} 13:00:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:00:00 INFO - Downloading/unpacking mozsystemmonitor==0.3 13:00: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 13:00: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 13:00: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 13:00: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 13:00: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 13:00: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 13:00:02 INFO - Downloading mozsystemmonitor-0.3.tar.gz 13:00:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 13:00:02 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 13:00:02 INFO - Installing collected packages: mozsystemmonitor 13:00:02 INFO - Running setup.py install for mozsystemmonitor 13:00:02 INFO - Successfully installed mozsystemmonitor 13:00:02 INFO - Cleaning up... 13:00:02 INFO - Return code: 0 13:00:02 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 13:00:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:00:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:00:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:00:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:00:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:00:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:00:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fbe7d57cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13ae8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1479770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15089c0>, '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 0x15098c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1509d50>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1463601238.620114-1974523070', '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 13:00:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 13:00:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 13:00:02 INFO - Using env: {'DISPLAY': ':0', 13:00:02 INFO - 'HOME': '/home/cltbld', 13:00:02 INFO - 'LANG': 'en_US.UTF-8', 13:00:02 INFO - 'LANGUAGE': 'en_US:en', 13:00:02 INFO - 'LOGNAME': 'cltbld', 13:00:02 INFO - 'MAIL': '/var/mail/cltbld', 13:00:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:00:02 INFO - 'MOZ_NO_REMOTE': '1', 13:00:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:00:02 INFO - 'NO_EM_RESTART': '1', 13:00:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:00:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:00:02 INFO - 'PWD': '/builds/slave/test', 13:00:02 INFO - 'SHELL': '/bin/bash', 13:00:02 INFO - 'SHLVL': '1', 13:00:02 INFO - 'TERM': 'linux', 13:00:02 INFO - 'TMOUT': '86400', 13:00:02 INFO - 'USER': 'cltbld', 13:00:02 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1463601238.620114-1974523070', 13:00:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:00:02 INFO - '_': '/tools/buildbot/bin/python'} 13:00:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:00:02 INFO - Downloading/unpacking blobuploader==1.2.4 13:00: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 13:00: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 13:00: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 13:00: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 13:00: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 13:00: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 13:00:04 INFO - Downloading blobuploader-1.2.4.tar.gz 13:00:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 13:00:04 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 13:00:04 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 13:00:04 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 13:00:04 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 13:00:04 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 13:00:04 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 13:00:04 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 13:00:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 13:00:05 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 13:00:05 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 13:00:05 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 13:00:05 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 13:00:05 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 13:00:05 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 13:00:05 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 13:00:05 INFO - Downloading docopt-0.6.1.tar.gz 13:00:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 13:00:05 INFO - Installing collected packages: blobuploader, requests, docopt 13:00:05 INFO - Running setup.py install for blobuploader 13:00:05 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 13:00:05 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 13:00:05 INFO - Running setup.py install for requests 13:00:05 INFO - Running setup.py install for docopt 13:00:06 INFO - Successfully installed blobuploader requests docopt 13:00:06 INFO - Cleaning up... 13:00:06 INFO - Return code: 0 13:00:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:00:06 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')]} 13:00:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:00:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:00:06 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')]} 13:00:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:00:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:00:06 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 0x7fbe7d57cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13ae8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1479770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15089c0>, '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 0x15098c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1509d50>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1463601238.620114-1974523070', '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 13:00:06 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 13:00:06 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 13:00:06 INFO - Using env: {'DISPLAY': ':0', 13:00:06 INFO - 'HOME': '/home/cltbld', 13:00:06 INFO - 'LANG': 'en_US.UTF-8', 13:00:06 INFO - 'LANGUAGE': 'en_US:en', 13:00:06 INFO - 'LOGNAME': 'cltbld', 13:00:06 INFO - 'MAIL': '/var/mail/cltbld', 13:00:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:00:06 INFO - 'MOZ_NO_REMOTE': '1', 13:00:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:00:06 INFO - 'NO_EM_RESTART': '1', 13:00:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:00:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:00:06 INFO - 'PWD': '/builds/slave/test', 13:00:06 INFO - 'SHELL': '/bin/bash', 13:00:06 INFO - 'SHLVL': '1', 13:00:06 INFO - 'TERM': 'linux', 13:00:06 INFO - 'TMOUT': '86400', 13:00:06 INFO - 'USER': 'cltbld', 13:00:06 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1463601238.620114-1974523070', 13:00:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:00:06 INFO - '_': '/tools/buildbot/bin/python'} 13:00:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:00:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:00:06 INFO - Running setup.py (path:/tmp/pip-desmlA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:00:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:00:06 INFO - Running setup.py (path:/tmp/pip-kNpeQp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:00:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:00:06 INFO - Running setup.py (path:/tmp/pip-XhuBMP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:00:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:00:06 INFO - Running setup.py (path:/tmp/pip-ZTAs9T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:00:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:00:06 INFO - Running setup.py (path:/tmp/pip-Lnfvgt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:00:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:00:06 INFO - Running setup.py (path:/tmp/pip-Seqa6l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:00:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:00:06 INFO - Running setup.py (path:/tmp/pip-zoZPo3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:00:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:00:06 INFO - Running setup.py (path:/tmp/pip-lcaywa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:00:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:00:06 INFO - Running setup.py (path:/tmp/pip-EWpFUF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:00:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:00:06 INFO - Running setup.py (path:/tmp/pip-K93CAn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:00:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:00:07 INFO - Running setup.py (path:/tmp/pip-dwdfXW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:00:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:00:07 INFO - Running setup.py (path:/tmp/pip-oR32gg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:00:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:00:07 INFO - Running setup.py (path:/tmp/pip-u1qQxG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:00:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:00:07 INFO - Running setup.py (path:/tmp/pip-hclH5E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:00:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:00:07 INFO - Running setup.py (path:/tmp/pip-8MlrOt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:00:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:00:07 INFO - Running setup.py (path:/tmp/pip-40iZ1W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:00:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:00:07 INFO - Running setup.py (path:/tmp/pip-D9lken-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:00:07 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 13:00:07 INFO - Running setup.py install for manifestparser 13:00:07 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 13:00:07 INFO - Running setup.py install for mozcrash 13:00:07 INFO - Running setup.py install for mozdebug 13:00:07 INFO - Running setup.py install for mozdevice 13:00:07 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 13:00:07 INFO - Installing dm script to /builds/slave/test/build/venv/bin 13:00:08 INFO - Running setup.py install for mozfile 13:00:08 INFO - Running setup.py install for mozhttpd 13:00:08 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 13:00:08 INFO - Running setup.py install for mozinfo 13:00:08 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 13:00:08 INFO - Running setup.py install for mozInstall 13:00:08 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 13:00:08 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 13:00:08 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 13:00:08 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 13:00:08 INFO - Running setup.py install for mozleak 13:00:08 INFO - Running setup.py install for mozlog 13:00:08 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 13:00:08 INFO - Running setup.py install for moznetwork 13:00:08 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 13:00:08 INFO - Running setup.py install for mozprocess 13:00:08 INFO - Running setup.py install for mozprofile 13:00:08 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 13:00:08 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 13:00:08 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 13:00:08 INFO - Running setup.py install for mozrunner 13:00:09 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 13:00:09 INFO - Running setup.py install for mozscreenshot 13:00:09 INFO - Running setup.py install for moztest 13:00:09 INFO - Running setup.py install for mozversion 13:00:09 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 13:00:09 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 13:00:09 INFO - Cleaning up... 13:00:09 INFO - Return code: 0 13:00:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:00:09 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')]} 13:00:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:00:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:00:09 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')]} 13:00:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:00:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:00:09 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 0x7fbe7d57cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13ae8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1479770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15089c0>, '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 0x15098c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1509d50>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1463601238.620114-1974523070', '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 13:00:09 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 13:00:09 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 13:00:09 INFO - Using env: {'DISPLAY': ':0', 13:00:09 INFO - 'HOME': '/home/cltbld', 13:00:09 INFO - 'LANG': 'en_US.UTF-8', 13:00:09 INFO - 'LANGUAGE': 'en_US:en', 13:00:09 INFO - 'LOGNAME': 'cltbld', 13:00:09 INFO - 'MAIL': '/var/mail/cltbld', 13:00:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:00:09 INFO - 'MOZ_NO_REMOTE': '1', 13:00:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:00:09 INFO - 'NO_EM_RESTART': '1', 13:00:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:00:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:00:09 INFO - 'PWD': '/builds/slave/test', 13:00:09 INFO - 'SHELL': '/bin/bash', 13:00:09 INFO - 'SHLVL': '1', 13:00:09 INFO - 'TERM': 'linux', 13:00:09 INFO - 'TMOUT': '86400', 13:00:09 INFO - 'USER': 'cltbld', 13:00:09 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1463601238.620114-1974523070', 13:00:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:00:09 INFO - '_': '/tools/buildbot/bin/python'} 13:00:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:00:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:00:09 INFO - Running setup.py (path:/tmp/pip-3XsSBp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:00:09 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)) 13:00:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:00:09 INFO - Running setup.py (path:/tmp/pip-iykwZK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:00:09 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)) 13:00:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:00:09 INFO - Running setup.py (path:/tmp/pip-cV5wcD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:00:09 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)) 13:00:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:00:09 INFO - Running setup.py (path:/tmp/pip-MgN7xi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:00:09 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)) 13:00:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:00:09 INFO - Running setup.py (path:/tmp/pip-KSAAAj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:00:09 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)) 13:00:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:00:09 INFO - Running setup.py (path:/tmp/pip-NxZkeg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:00:10 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)) 13:00:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:00:10 INFO - Running setup.py (path:/tmp/pip-J7xSUw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:00:10 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)) 13:00:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:00:10 INFO - Running setup.py (path:/tmp/pip-amggMV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:00:10 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)) 13:00:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:00:10 INFO - Running setup.py (path:/tmp/pip-LKjCvA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:00:10 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)) 13:00:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:00:10 INFO - Running setup.py (path:/tmp/pip-HWmcwo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:00:10 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)) 13:00:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:00:10 INFO - Running setup.py (path:/tmp/pip-anObxJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:00:10 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)) 13:00:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:00:10 INFO - Running setup.py (path:/tmp/pip-JwySVB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:00:10 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)) 13:00:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:00:10 INFO - Running setup.py (path:/tmp/pip-BXxtpc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:00:10 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)) 13:00:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:00:10 INFO - Running setup.py (path:/tmp/pip-53cShx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:00:10 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)) 13:00:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:00:10 INFO - Running setup.py (path:/tmp/pip-vIRBSd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:00:10 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)) 13:00:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:00:10 INFO - Running setup.py (path:/tmp/pip-ZrkrJH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:00:10 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)) 13:00:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:00:10 INFO - Running setup.py (path:/tmp/pip-SBge5c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:00:10 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)) 13:00:10 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)) 13:00:10 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)) 13:00:10 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)) 13:00:10 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)) 13:00:10 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)) 13:00:10 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:00:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:00:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:00:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:00:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:00:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:00:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:00:12 INFO - Downloading blessings-1.6.tar.gz 13:00:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 13:00:12 INFO - Installing collected packages: blessings 13:00:12 INFO - Running setup.py install for blessings 13:00:13 INFO - Successfully installed blessings 13:00:13 INFO - Cleaning up... 13:00:13 INFO - Return code: 0 13:00:13 INFO - Done creating virtualenv /builds/slave/test/build/venv. 13:00:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:00:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:00:13 INFO - Reading from file tmpfile_stdout 13:00:13 INFO - Current package versions: 13:00:13 INFO - argparse == 1.2.1 13:00:13 INFO - blessings == 1.6 13:00:13 INFO - blobuploader == 1.2.4 13:00:13 INFO - docopt == 0.6.1 13:00:13 INFO - manifestparser == 1.1 13:00:13 INFO - mozInstall == 1.12 13:00:13 INFO - mozcrash == 0.17 13:00:13 INFO - mozdebug == 0.1 13:00:13 INFO - mozdevice == 0.48 13:00:13 INFO - mozfile == 1.2 13:00:13 INFO - mozhttpd == 0.7 13:00:13 INFO - mozinfo == 0.9 13:00:13 INFO - mozleak == 0.1 13:00:13 INFO - mozlog == 3.2 13:00:13 INFO - moznetwork == 0.27 13:00:13 INFO - mozprocess == 0.22 13:00:13 INFO - mozprofile == 0.28 13:00:13 INFO - mozrunner == 6.11 13:00:13 INFO - mozscreenshot == 0.1 13:00:13 INFO - mozsystemmonitor == 0.3 13:00:13 INFO - moztest == 0.7 13:00:13 INFO - mozversion == 1.4 13:00:13 INFO - psutil == 3.1.1 13:00:13 INFO - requests == 1.2.3 13:00:13 INFO - wsgiref == 0.1.2 13:00:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:00:13 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')]} 13:00:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:00:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:00:13 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')]} 13:00:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:00:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:00:13 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 0x7fbe7d57cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13ae8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1479770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15089c0>, '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 0x15098c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1509d50>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1463601238.620114-1974523070', '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 13:00:13 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 13:00:13 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 13:00:13 INFO - Using env: {'DISPLAY': ':0', 13:00:13 INFO - 'HOME': '/home/cltbld', 13:00:13 INFO - 'LANG': 'en_US.UTF-8', 13:00:13 INFO - 'LANGUAGE': 'en_US:en', 13:00:13 INFO - 'LOGNAME': 'cltbld', 13:00:13 INFO - 'MAIL': '/var/mail/cltbld', 13:00:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:00:13 INFO - 'MOZ_NO_REMOTE': '1', 13:00:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:00:13 INFO - 'NO_EM_RESTART': '1', 13:00:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:00:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:00:13 INFO - 'PWD': '/builds/slave/test', 13:00:13 INFO - 'SHELL': '/bin/bash', 13:00:13 INFO - 'SHLVL': '1', 13:00:13 INFO - 'TERM': 'linux', 13:00:13 INFO - 'TMOUT': '86400', 13:00:13 INFO - 'USER': 'cltbld', 13:00:13 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1463601238.620114-1974523070', 13:00:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:00:13 INFO - '_': '/tools/buildbot/bin/python'} 13:00:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:00:13 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)) 13:00:13 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)) 13:00:13 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)) 13:00:13 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)) 13:00:13 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)) 13:00:13 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)) 13:00:13 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)) 13:00:13 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)) 13:00:13 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)) 13:00:13 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)) 13:00:13 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)) 13:00:13 INFO - Cleaning up... 13:00:13 INFO - Return code: 0 13:00:13 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 13:00:13 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')]} 13:00:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:00:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:00:13 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')]} 13:00:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:00:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:00:13 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 0x7fbe7d57cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13ae8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1479770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15089c0>, '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 0x15098c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1509d50>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1463601238.620114-1974523070', '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 13:00:13 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 13:00:13 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 13:00:13 INFO - Using env: {'DISPLAY': ':0', 13:00:13 INFO - 'HOME': '/home/cltbld', 13:00:13 INFO - 'LANG': 'en_US.UTF-8', 13:00:13 INFO - 'LANGUAGE': 'en_US:en', 13:00:13 INFO - 'LOGNAME': 'cltbld', 13:00:13 INFO - 'MAIL': '/var/mail/cltbld', 13:00:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:00:13 INFO - 'MOZ_NO_REMOTE': '1', 13:00:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:00:13 INFO - 'NO_EM_RESTART': '1', 13:00:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:00:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:00:13 INFO - 'PWD': '/builds/slave/test', 13:00:13 INFO - 'SHELL': '/bin/bash', 13:00:13 INFO - 'SHLVL': '1', 13:00:13 INFO - 'TERM': 'linux', 13:00:13 INFO - 'TMOUT': '86400', 13:00:13 INFO - 'USER': 'cltbld', 13:00:13 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1463601238.620114-1974523070', 13:00:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:00:13 INFO - '_': '/tools/buildbot/bin/python'} 13:00:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:00:13 INFO - Downloading/unpacking jsonschema 13:00: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 13:00: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 13:00: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 13:00: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 13:00: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 13:00: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 13:00:15 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 13:00:15 INFO - Downloading/unpacking functools32 (from jsonschema) 13:00:15 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 13:00:15 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 13:00:15 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 13:00:15 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 13:00:15 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 13:00:15 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 13:00:15 INFO - Downloading functools32-3.2.3-2.tar.gz 13:00:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 13:00:15 INFO - warning: no files found matching '*.txt' 13:00:15 INFO - no previously-included directories found matching 'build' 13:00:15 INFO - no previously-included directories found matching 'dist' 13:00:15 INFO - no previously-included directories found matching '.git*' 13:00:15 INFO - Installing collected packages: jsonschema, functools32 13:00:15 INFO - Running setup.py install for functools32 13:00:15 INFO - warning: no files found matching '*.txt' 13:00:15 INFO - no previously-included directories found matching 'build' 13:00:15 INFO - no previously-included directories found matching 'dist' 13:00:15 INFO - no previously-included directories found matching '.git*' 13:00:16 INFO - Successfully installed jsonschema functools32 13:00:16 INFO - Cleaning up... 13:00:16 INFO - Return code: 0 13:00:16 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 13:00:16 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')]} 13:00:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:00:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:00:16 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')]} 13:00:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:00:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:00:16 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 0x7fbe7d57cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13ae8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1479770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15089c0>, '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 0x15098c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1509d50>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1463601238.620114-1974523070', '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 13:00:16 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 13:00:16 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 13:00:16 INFO - Using env: {'DISPLAY': ':0', 13:00:16 INFO - 'HOME': '/home/cltbld', 13:00:16 INFO - 'LANG': 'en_US.UTF-8', 13:00:16 INFO - 'LANGUAGE': 'en_US:en', 13:00:16 INFO - 'LOGNAME': 'cltbld', 13:00:16 INFO - 'MAIL': '/var/mail/cltbld', 13:00:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:00:16 INFO - 'MOZ_NO_REMOTE': '1', 13:00:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:00:16 INFO - 'NO_EM_RESTART': '1', 13:00:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:00:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:00:16 INFO - 'PWD': '/builds/slave/test', 13:00:16 INFO - 'SHELL': '/bin/bash', 13:00:16 INFO - 'SHLVL': '1', 13:00:16 INFO - 'TERM': 'linux', 13:00:16 INFO - 'TMOUT': '86400', 13:00:16 INFO - 'USER': 'cltbld', 13:00:16 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1463601238.620114-1974523070', 13:00:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:00:16 INFO - '_': '/tools/buildbot/bin/python'} 13:00:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:00:16 INFO - Downloading/unpacking flake8 13:00: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 13:00: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 13:00: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 13:00: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 13:00: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 13:00: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 13:00:18 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 13:00:18 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 13:00:18 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 13:00:18 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 13:00:18 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 13:00:18 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 13:00:18 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 13:00:18 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 13:00:18 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 13:00:18 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 13:00:18 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 13:00:18 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 13:00:18 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 13:00:18 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 13:00:18 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 13:00:18 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 13:00:18 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 13:00:18 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 13:00:18 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 13:00:18 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 13:00:18 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 13:00:18 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 13:00:18 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 13:00:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 13:00:18 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 13:00:18 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 13:00:18 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 13:00:18 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 13:00:18 INFO - no previously-included directories found matching 'docs/_build' 13:00:18 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 13:00:19 INFO - Running setup.py install for pep8 13:00:19 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 13:00:19 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 13:00:19 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 13:00:19 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 13:00:19 INFO - no previously-included directories found matching 'docs/_build' 13:00:19 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 13:00:19 INFO - Successfully installed flake8 mccabe pyflakes pep8 13:00:19 INFO - Cleaning up... 13:00:20 INFO - Return code: 0 13:00:20 INFO - Running post-action listener: _resource_record_post_action 13:00:20 INFO - Running post-action listener: _start_resource_monitoring 13:00:20 INFO - Starting resource monitoring. 13:00:20 INFO - ##### 13:00:20 INFO - ##### Finished create-virtualenv step (success) 13:00:20 INFO - ##### 13:00:20 INFO - ##### 13:00:20 INFO - ##### Running install step. 13:00:20 INFO - ##### 13:00:20 INFO - Running pre-action listener: _resource_record_pre_action 13:00:20 INFO - Running main action method: install 13:00:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:00:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:00:20 INFO - Reading from file tmpfile_stdout 13:00:20 INFO - Detecting whether we're running mozinstall >=1.0... 13:00:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 13:00:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 13:00:20 INFO - Reading from file tmpfile_stdout 13:00:20 INFO - Output received: 13:00:20 INFO - Usage: mozinstall [options] installer 13:00:20 INFO - Options: 13:00:20 INFO - -h, --help show this help message and exit 13:00:20 INFO - -d DEST, --destination=DEST 13:00:20 INFO - Directory to install application into. [default: 13:00:20 INFO - "/builds/slave/test"] 13:00:20 INFO - --app=APP Application being installed. [default: firefox] 13:00:20 INFO - mkdir: /builds/slave/test/build/application 13:00:20 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'] 13:00:20 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 13:00:37 INFO - Reading from file tmpfile_stdout 13:00:37 INFO - Output received: 13:00:37 INFO - /builds/slave/test/build/application/firefox/firefox 13:00:37 INFO - Running post-action listener: _resource_record_post_action 13:00:37 INFO - ##### 13:00:37 INFO - ##### Finished install step (success) 13:00:37 INFO - ##### 13:00:37 INFO - ##### 13:00:37 INFO - ##### Running run-tests step. 13:00:37 INFO - ##### 13:00:37 INFO - Running pre-action listener: _resource_record_pre_action 13:00:37 INFO - Running main action method: run_tests 13:00:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 13:00:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 13:00:37 INFO - Python 2.7.3 13:00:37 INFO - Return code: 0 13:00:37 INFO - grabbing minidump binary from tooltool 13:00:37 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')]} 13:00:37 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 0x15089c0>, '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 0x15098c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1509d50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 13:00:37 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 13:00:37 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 13:00:37 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 13:00:37 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 13:00:37 INFO - Return code: 0 13:00:37 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 13:00:37 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 13:00:37 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 13:00:37 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 13:00:37 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 13:00:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 13:00:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 13:00:38 INFO - Return code: 0 13:00:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ARDPPwsRRDOz2R2e9Cnw-Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-034', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 13:00:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/ARDPPwsRRDOz2R2e9Cnw-Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-034 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 13:00:38 INFO - Using env: {'DISPLAY': ':0', 13:00:38 INFO - 'HOME': '/home/cltbld', 13:00:38 INFO - 'LANG': 'en_US.UTF-8', 13:00:38 INFO - 'LANGUAGE': 'en_US:en', 13:00:38 INFO - 'LOGNAME': 'cltbld', 13:00:38 INFO - 'MAIL': '/var/mail/cltbld', 13:00:38 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 13:00:38 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 13:00:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:00:38 INFO - 'MOZ_NO_REMOTE': '1', 13:00:38 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 13:00:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:00:38 INFO - 'NO_EM_RESTART': '1', 13:00:38 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:00:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:00:38 INFO - 'PWD': '/builds/slave/test', 13:00:38 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 13:00:38 INFO - 'SHELL': '/bin/bash', 13:00:38 INFO - 'SHLVL': '1', 13:00:38 INFO - 'TERM': 'linux', 13:00:38 INFO - 'TMOUT': '86400', 13:00:38 INFO - 'USER': 'cltbld', 13:00:38 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1463601238.620114-1974523070', 13:00:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:00:38 INFO - '_': '/tools/buildbot/bin/python'} 13:00:38 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ARDPPwsRRDOz2R2e9Cnw-Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-034', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 13:00:38 INFO - mozversion application_buildid: 20160517073121 13:00:38 INFO - mozversion application_changeset: 3780a3a6b83aeda143f9562829c830410a0c961e 13:00:38 INFO - mozversion application_display_name: Nightly 13:00:38 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 13:00:38 INFO - mozversion application_name: Firefox 13:00:38 INFO - mozversion application_remotingname: firefox 13:00:38 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 13:00:38 INFO - mozversion application_vendor: Mozilla 13:00:38 INFO - mozversion application_version: 49.0a1 13:00:38 INFO - mozversion platform_buildid: 20160517073121 13:00:38 INFO - mozversion platform_changeset: 3780a3a6b83aeda143f9562829c830410a0c961e 13:00:38 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 13:00:38 INFO - mozversion platform_version: 49.0a1 13:00:38 INFO - using testdate: 1463601638 13:00:38 INFO - actual date: 1463601638 13:00:38 INFO - starting webserver on 'localhost:41236' 13:00:38 INFO - SUITE-START | Running 2 tests 13:00:38 INFO - TEST-START | dromaeo_css 13:00:38 INFO - operating with platform_type : linux_ 13:00:38 INFO - Initialising browser for dromaeo_css test... 13:00:38 INFO - TEST-INFO | started process 8284 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZ72lS8/profile http://localhost:41236/getInfo.html) 13:00:42 INFO - PROCESS | 8284 | __metrics Screen width/height:1600/1200 13:00:42 INFO - PROCESS | 8284 | colorDepth:24 13:00:42 INFO - PROCESS | 8284 | Browser inner width/height: 1024/697 13:00:42 INFO - PROCESS | 8284 | __metrics 13:00:43 ERROR - PROCESS | 8284 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 13:00:44 INFO - TEST-INFO | 8284: exit 0 13:00:44 INFO - Browser initialized. 13:00:44 INFO - Running cycle 1/1 for dromaeo_css test... 13:00:44 INFO - TEST-INFO | started process 8365 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZ72lS8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:00:55 INFO - PROCESS | 8365 | RSS: Main: 152764416 13:00:55 INFO - PROCESS | 8365 | 13:01:46 INFO - PROCESS | 8365 | 1463601706961 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] 13:03:54 INFO - PROCESS | 8365 | Cycle 1(1): loaded http://localhost:41236/tests/dromaeo/cssquery-dojo.html (next: http://localhost:41236/tests/dromaeo/cssquery-ext.html) 13:03:55 INFO - PROCESS | 8365 | RSS: Main: 260378624 13:03:55 INFO - PROCESS | 8365 | 13:06:57 INFO - PROCESS | 8365 | Cycle 1(1): loaded http://localhost:41236/tests/dromaeo/cssquery-ext.html (next: http://localhost:41236/tests/dromaeo/cssquery-jquery.html) 13:06:58 INFO - PROCESS | 8365 | RSS: Main: 242319360 13:06:58 INFO - PROCESS | 8365 | 13:10:00 INFO - PROCESS | 8365 | Cycle 1(1): loaded http://localhost:41236/tests/dromaeo/cssquery-jquery.html (next: http://localhost:41236/tests/dromaeo/cssquery-mootools.html) 13:10:01 INFO - PROCESS | 8365 | RSS: Main: 231575552 13:10:01 INFO - PROCESS | 8365 | 13:13:02 INFO - PROCESS | 8365 | Cycle 1(1): loaded http://localhost:41236/tests/dromaeo/cssquery-mootools.html (next: http://localhost:41236/tests/dromaeo/cssquery-prototype.html) 13:13:03 INFO - PROCESS | 8365 | RSS: Main: 236314624 13:13:03 INFO - PROCESS | 8365 | 13:16:06 INFO - PROCESS | 8365 | Cycle 1(1): loaded http://localhost:41236/tests/dromaeo/cssquery-prototype.html (next: http://localhost:41236/tests/dromaeo/cssquery-yui.html) 13:16:06 INFO - PROCESS | 8365 | RSS: Main: 221908992 13:16:06 INFO - PROCESS | 8365 | 13:19:09 INFO - PROCESS | 8365 | Cycle 1(1): loaded http://localhost:41236/tests/dromaeo/cssquery-yui.html (next: http://localhost:41236/tests/dromaeo/cssquery-dojo.html) 13:19:09 INFO - PROCESS | 8365 | RSS: Main: 266010624 13:19:09 INFO - PROCESS | 8365 | 13:19:09 INFO - PROCESS | 8365 | __start_tp_report 13:19:09 INFO - PROCESS | 8365 | _x_x_mozilla_page_load 13:19:09 INFO - PROCESS | 8365 | _x_x_mozilla_page_load_details 13:19:09 INFO - PROCESS | 8365 | |i|pagename|runs| 13:19:09 INFO - PROCESS | 8365 | |0;dojo.html;3146.4493713600114;3163.7310828579557;3191.3617276544696;3200.439923013475;3224.25842056327;5246.554042906358;5270.578353731702;5277.393099793525;5386.434424385442;5428.022955867942;5354.303940487731;5364.678119312844;5365.7853685852615;5429.185622156679;5450.863728406792;4805.063012712522;4806.086843499741;4957.281194226835;4999.7500124993785;5011.574016208609;4919.286703428001;4946.901061978759;4983.526564976321;4994.500549945013;5006.799728010875;4744.501827308135;4822.348982887301;4824.372831531896;4878.170710979142;4880.9755951220195;4889.853304400874;4900.681455705368;4901.093297739894;4920.286558449041;4938.555530002317;4738.620910327166;4828.299896515021;4870.172070747946;4886.975565122151;4904.779284932187;5274;5343.946560534423;5361.758720857571;5374.973125134349;5378.811741589065;5807.622504537234;5812.970935145339;5818.476337129637;5819.73811178498;5820.447057529528;5587.161925711176;5762.971185144047;5798.82603521895;5826.096954971944;5843.912341314884;5424.159255315432;5452.5365343946205;5455.318085239345;5457.426970168154;5460.863478413032;5980.73086711099;5981.132735753378;5991.5206783457215;5996.340402555716;5998.520118390518;5460.726963651801;5492.80775172867;5508.669479831223;5523.530499907472;5752.683602401824;5615.606907516516;5620.213170156181;5626.35296940857;5627.549796016309;5631.549476041907;5608.298962629671;5608.831735047955;5609.607327487037;5613.354464236583;5613.691246981455;5532.7786888524815;5686.402927692616;5710.2862142232225;5743.282089738783;5774.393688662714;4355.651547876162;4355.73865568067;4387.978060109743;4401.207782599099;4404.581564751344;3854.479645247912;3859.9192226176715;3866.516685414323;3936.15372694872;3982.5220973482787;5313.521783039468;5370.838874833761;5406.135018397038;5420.891582168335;5426.972865135728;5644.407337229532;5680.488756011979;5688.232088667977;5689.431056894277;5718.285214348207;5342.973285133549;5381.838544843661;5387.461253874581;5408.242846001563;5460.890782184334;4949.208126699783;4955.82654607087;4967.155583550805;4967.4784147664695;4997.775100120431;5964.880702385928;5987.2815262168815;5989.550783691154;5996.880062398727;6041.154238406627;5512.062949298549;5601;5659.292588426447;5683.573731970201;5698.943010569841;5922.289325280994;5926.762929482772;5933.199018132497;5934.851628709317;5968.612040217372;5560.4161563035295;5560.777568897361;5563.721813909207;5574.86062848432;5605.523530499953;5586.273784408001;5633.718314084197;5679.261695979496;5690.231818704588;5731.996900542472;5562.638428502134;5588.720563971704;5590.468905453975;5592.608517403743;5597.916031259453;5538.086215774513;5567.69377684215;5586.050371436784;5592.217089607377;5598.244237027949;5402.108652072521;5472.835814925558;5482.479164479368;5514.80698175562;5550.7779688812;5666.263385759825;5680.943190568041;5726.770929162787;5728.312602487728;5732.16883551891;5412.539934105645;5549.1676248563035;5549.972250138723;5555.777768889199;5588.357338905916 13:19:09 INFO - PROCESS | 8365 | |1;ext.html;612.7947137708867;633.7243299164863;649.6524359467686;655.4068567946008;662.2450406536547;43317.56682433175;44696.776516117374;44719;44768;48118;45331.54668453315;45884.54115458844;45931;46002.539974600244;46019.53980460195;8841.911580884173;9028.819423611523;9037.728868133967;9039.548022598876;9040.683576074838;8850.513221772831;8942.64229430822;8944.642214311452;8945.194932456077;8948.8657670135;8746.825063498727;8781.95609021954;9011.864822027675;9026.819463610756;9028.232600228988;8956.865647015267;8976.057513961037;8979.146981036856;8980.955095224548;8991.865122023142;8818.735437936872;8868.37921345506;8886.111388861062;8887.911120888773;8889;41371.37942930858;41487.37768933469;41532.79233603843;41578;41810.58189418097;3230.224746060952;3798.639129282714;3822.8853134406013;3880.59253778352;3999.1201935574127;29458.558121628193;29708.405831883243;29764.107076787514;29799.851000744857;33041.834790825895;4249.702520823544;4253.851115210953;4375.2124617568825;4445.9110817783785;4449.777511124431;335757;335887;336943.31528342445;339001;339051;56991.715041424526;58558.707206463696;58668.41331586715;58700.41299587035;58717;60998.695006524686;61055.08417373743;61127.38872611306;61170;61222.38777612167;14554.781678274834;14730.55808325752;14731.852681473049;14760.852391475948;14776.556703298918;11976.221545599683;12153.69615759613;12154.696132596755;12181.025517958542;12186.57346992851;14908.552743417715;16244;16465.753013704805;16518;16545.338186472647;5449.945500545023;6349.841253968688;6357.841053973595;6362.618242905441;6449.516286278548;962.3215632978734;1004.9598016079422;1021.5505177721778;1054.2725519391595;1175.4651633506735;32588.511172332433;32733;32995;33080.17299567482;33510;1140.8916152965621;1159.8550181227347;1213.520659339556;1224.1981502116128;1246.4079562207878;17069.573260668334;17475.21361538734;17480.650386992187;17522.386716464862;17527.474175774747;514366;520193;520699;522679;524286;58206;58388.4161158383;58393.4160658388;58420.70789646025;58444.70777646085;1223.6451429085466;1393.4426229508279;1411.6265063239277;1415.950441734555;1434.3617090394673;32231.516527252577;32945.34109317848;33691.64527118184;33828.3234335307;34144;48368.51631483781;48841.75579122224;48859;48883.26675099947;49054.509454905;370687;371360;372247;372719;373467;29762;31192.37615247637;37363.43954840734;37613;41649;45431.54568454273;45674.77162614299;46208.53791462042;46682.53317466917;46688.29967550557;39693.404598931615;39729.205415890945;39820.80089599534;39975;40965.1806963865;39126.2174756509;39154.41268381033;39183.608163918;39532.80233598928;39893;39854.2029159421;40139.598604014755;40815;40873.59126408698;40959.59040409558;33102.668973309956;34674.30651386908;34878.08170550644;34933;35761.64238357583;27282.317942051606;27681.307967300174;27783.015338570895;27853.582196266667;27862.60686965684 13:19:09 INFO - PROCESS | 8365 | |2;jquery.html;403.1533779063965;410.5114913253228;412.4699760807361;414.3846388113653;417.5824175824176;18260.269589216434;18905;19932.60134797303;20131.496712582193;20148.39554813356;12265.63203103906;12566.748665026695;16071;19550.315738949103;19566.31517896874;4826.889815342473;5159.871003224931;5395.568354531628;5905.675187864687;5917.142014407908;5847.502962248214;5861.238039054917;5869.266341707286;5882.235309409771;5909.20225769522;5251.186066159751;5526.06056970316;5540.584456165783;5563.499285064343;5822.796202132926;5346.4118946915805;5512.503874651281;5523.861903452405;5664.716764161775;5694.743736531866;5500.889982200333;5503.091989821674;5510.256115424407;5544.91682624761;5545.667259964374;13837.723245535033;21256.893715531478;22379.216727414463;23606;23641.527169456516;2145.03473436954;2184.541246338271;2271.205078222625;2285.794278514925;2371.6561098640773;10334.638287659896;10339.896601033968;10344.586216551328;10595.25833191677;10600.151987840954;1967;2070.109852763311;2070.523779530701;2081.5732774781286;2104.400245929918;52877;76942.23057769406;82406;83279.16720832835;87641.68537471943;10470.37177769321;10583.31208471447;10854.185936054828;11116.888831111748;11221.326720396803;15636.2181890905;15676.451324203597;15722.13528255934;15854.9207253963;15868;6826.965865170642;6905.620190889549;7556.811079723051;7581.658825352873;7590.1650818409935;6958.721651133999;7031.156261248683;7036.859262814817;7070.469714771412;7147.714091436388;4477.8208871644765;4487.195502810743;4559.680822342404;4646.628269738413;4660.743659098782;1949.0644490644286;1961.4213806926991;2026.3313106674761;2059;2077.7298951136254;919.935604507678;1021.6628512590827;1030.5929157982555;1058.0266155137292;1075.349413604758;18761.624767504574;19915.502112447306;19944.20223191085;20057;20106.798932010784;960.4765402855461;963.5808423335872;990.0841721407669;993.3592832622918;1007.1741172238694;10235.846462303072;10593.788124237471;10645.254832161674;10891.0742586881;11087.733227471155;475396;477415;493451;496539;497150;64375.35624643787;73034.63482682552;96587;101201.49399252956;101931;1052.9947350263353;1054.383185836279;1069.1286601419818;1074.9193810464246;1086.8261078227445;17991.730124048412;18242.635147296714;18358.31777007645;18478.81521184771;18623.44129676112;42570.361444577735;42896.142077158904;43705.78147109244;44039.55960440355;44134.77932610316;58650.70674646599;61017;62236.68881655563;63031.6848415755;63568;8624.956875215585;8833.293336532934;8888;9018.413803102778;9060.909390906007;13108.475660973836;13467.730645386842;13682;13757.862421375658;13787.51743688966;11189.328640281608;12157.452914618687;12222.266664000188;12324.137310388187;12511.249325040526;11326.943365283121;11461.140414468782;11484.655460336204;11486.425678715863;11773.705657358634;12193.390330483619;12490.375481225721;12496.562620308245;12502.624921252376;12715.10994230395;8518.48889066658;8752.518611476175;8786.16531429513;8808.559572021246;8815.426997245158;8987.865182022144;11167.274127181707;11774.352410617155;12056.336901470504;12241 13:19:09 INFO - PROCESS | 8365 | |3;mootools.html;1209.842720446342;1355.8372995240572;1459.1099429348099;1524.7560390337549;1815.664102141104;8430.704925327615;8695.304375649948;8695.652173913037;8909.777255568613;9034.59344329505;8739.91260087399;8898.065703101178;8899.199072083511;8900.19898209161;8923.196912277894;2047.1811275489774;2100.453881990678;2343.0510643189536;2367.1714899785065;2399;2381.880905954695;2400.519896020794;2402.411409204747;2402.6396040593877;2405;2277.6697378880053;2282.577723121228;2283.668868014137;2308.8960996755104;2346.5189636124637;1985.821276085152;2219.533897881447;2235.508188198594;2289;2293.8050265727434;2253.43664083979;2277.7266727992583;2295;2295.116380193621;2296.977030229693;8631.611577478965;8656.134386561356;8692.174243446863;8693.869591956125;8696.347773916981;5069.03688299222;5174.922376164361;5175.120229560984;5178.792848286064;5180;3278.278778668689;3281.8359082045804;3319.2697606526526;3405.063607507955;3434.793912365241;1019.0675531888387;1034.5396298647106;1042.5412818359973;1073.6134991403167;1083.1334932054403;12053.819192712117;12414;12415.379231038412;12441.502339906392;12473.438695258736;4650.720956742539;4657.3246879202325;4740.502247264057;4756.6432517561325;4785.234362501983;10476.952304769462;10480;10484.895151048544;10489.895101048893;10522.579096836194;5330;5337.092694241988;5338.065838478251;5341.172118321666;5342.1185504392115;3995.5005624296964;4666.0901124280435;4759;4777.617790576745;4829.95170048295;3923.9215215695526;4018.1963607278662;4026.57720939303;4100.2414553307735;4169.145325208325;1161.2393882007298;1298.7921932490756;1311.3508813137366;1326.8805807477374;1332.4203971272527;866.6013633728555;909.5179554835947;949.4192170036812;952;1000.9699709008742;5673.8014169504695;5906.793262235887;5906.8818623627285;5948.613340132964;6043.486303664238;435.84745339131683;436.3629101511765;446.16789687233074;448.73076154307154;449.51676947281805;4131.876043718693;4159.584041595816;4195.769232692171;4199.4330765346895;4213.073123912734;10532.578696852193;10765.46172691378;10844.891551084545;10873.401962892116;10907.672769816918;10627.468626568641;10664.573417063231;10668.466576671135;10713.732156696145;10721.731956701145;7856.371490280764;8133.0646975598565;8134.715284965117;8208.220219079178;8250.422470427013;5988.507408385677;5997.880042399215;6067.150598916243;6109.908351374822;6116.66358350295;10103.444310562989;10301.587936482456;10367.792644147226;10371.481425928523;10383.480825958823;10085.647002354883;10115.443650599287;10495.580176792842;10541.262111652111;10590.523426445674;6639.900401494078;6993.055937448583;7209.819754506179;7485;7649.732259370895;8283.875741863998;8289.83420331602;8314.62584183701;8351.624176911931;8356.498610083414;7436.442266829901;7639.57982310956;7658.69365225407;7710.575918324322;7724.0731112266885;7382.114146302478;7392.149902761252;7403.925960740324;7529.322360987538;7566.35685966677;6513.185851768529;6523.869522609616;6533.281339052795;6571.3100124488155;6583.736650533925;4829.468758436499;4914.9017019658695;5012.423571289349;5020.47285035066;5082.695038297714;5125.923111153261;5158.38099428071;5175.042617115846;5185.9222111669105;5272.68363898152 13:19:09 INFO - PROCESS | 8365 | |4;prototype.html;310.96268447786264;311.7973317343726;326.1357402882363;338.8932486266826;341.28500790843174;5095.987085101383;5236.266922630825;5261.447548007462;5301.409577126861;5330.706811125391;5232.319798426209;5233.842984710464;5243.344581927259;5248.5538729207965;5254.605904557155;4090.2228576570433;4090.68876085011;4095.5904409559103;4100.482821356088;4109.897252568695;3832.9425058624142;3890.3580909150096;4101.343784994401;4113.56807535209;4123.154753275571;4063.0045638818533;4093.7339072960162;4104.220198162355;4129;4129.834806607732;4102.753834769908;4110.486189226347;4115.58844115589;4116.320807066831;4148.751074935513;3447.1382154461385;3678.70570354371;3692.6861216796606;3704.833282502267;3712.554493460802;5025.974870125662;5053.216751403538;5055.281204390488;5057.140286151363;5093.923591146136;4695.859124226278;4696.9530304696855;4703.8353657622165;4706.74112923789;4707.7881495332795;5122.974385128051;5375.5162035417;5405.59454541818;5416.187571864212;5446.319210098738;3889.2804831106337;3894.2990261753157;3896.980515097435;3899.8245078971513;3914.256291304643;5645.350784659776;5678.403767604384;5679.829605111812;5698.7435565399655;5713.685747283897;4062.979685101556;4063.6342729154517;4075.7962101895378;4086.8773936781945;4090.795460226977;3901.96098039016;4104.856330028435;4110.547839737626;4124.618252885257;4135.359019352005;4670.602998745145;4672.08894265615;4673.252279635242;4680.578747912704;4688.976555117202;5457.372402173722;5486.807961721321;5495.917561236584;5497.697626630494;5547.278853748987;1292.3667402972476;1302.6192236229626;1399.9020068595305;1414.9953532991485;1423.1034448297507;886.796036911514;903.9198158200887;904.2268860124499;910.7358865928938;918.5912269040347;4797.18447863864;4828.903421931542;4856.198727210041;4876.366072410564;4901.264810278487;5078.42568803672;5095.286659867622;5118.97440512795;5131.2559678846355;5141.691498510101;4556.954430455719;4828.5412885775795;4847.369841920598;4847.612191024709;4853.878653033702;2662.3876508402786;2680.7855371570226;2683.127983405401;2706.188143556925;2715.6469658944607;5139.58883289336;5185.403678576937;5209.244659524347;5291.656042357235;5297.11307337502;4732.13080833069;5065.138926382524;5074.771635276422;5109.54378002275;5133.897322053538;5232.869178270574;5238.842834714965;5274.7098909560345;5281.01961764346;5340.118880384693;4909.386326709161;4949.975250123726;4964.7836280111605;4990.951900101019;5150.965368679425;4676.8129274828625;4803.2314829628485;4944.381952255968;4956.653034287565;4973.925391119208;5153.484651534816;5172.456892026434;5216.634835561475;5236.764345604533;5243.606729495227;3670.6145854684873;3689.9815500922323;3699.389600715959;3700.7964561948274;3824.8661296854475;5490.5607551395;5568.582356323373;5606.495415412633;5616.3260408749575;5628.579710724428;5382.273393091882;5404.0272750905215;5440.945590544202;5446.727663616724;5465.802505534307;5344.786208551614;5443.265159203457;5447.400785913467;5453.2365468833605;5470.753816078366;5310.6813591184655;5329.7068661224;5355.3305836770405;5369.248305237192;5369.328833895763;5493.340799104133;5504.954058728828;5526.364468086222;5538.556915446835;5606.579506536945;5117.181250999822;5365.651232669864;5499.642523235977;5501.422350653123;5526.143447765603 13:19:09 INFO - PROCESS | 8365 | |5;yui.html;670.755174361358;767.0948281028386;815.3151352863594;866.4757821517985;872.5506364222431;14207.218602976845;15033;15033.473828416008;15043.323050462728;15064.096154230754;14322.498712545063;14361.569152925404;14364.78452823206;14371.64070898228;14391.352389142488;678.3589507915023;784.9607519624025;805.730080423059;812.8333691593209;812.910579836219;822.3667775812621;825.405707890318;829.4194064155085;829.7261903571837;830.2403300979596;863.4171933944593;867.0159369116067;867.1848462445281;867.319154463746;867.5315329721942;715.4848509073458;850.0309647002422;866.4151697604092;867.249828898005;879.705298724928;640.9711562979677;642.5116911147561;642.6208536963214;642.881067002606;643.3373625166087;14285.285735713172;14322.069065510708;14324.068935519157;14342.42630294787;14415.495457659037;13248.86751132486;13702;13763.38064787087;13773;13816.101953372998;11951.342676152895;12159.756804863942;12218.61736961293;12471.189372690746;12560.309182994932;4432;4509.368688383629;4536.637069034469;4651.953480465186;4667;57687.13469297964;58270.70864645692;59774;60316.09525857116;60342.69828650873;10069.295149339476;10164.542595583069;10632.30889992148;11094.55621775136;11252.212345135926;19525.90237048834;19526.902365488364;19721.60556788856;19744;19982.20071197165;7213.386862116674;7289.198188199294;7339.88990165148;7351.932652838068;7388.852222955511;4392.3191905254735;4484.439445069366;4522.276435770326;4547.658925580595;4608.6082682971655;3243.1567792374153;3306.206510437478;3306.3552607241836;3308.7849289796086;3311.6357200708385;620.1442010026225;625.2434554189482;626.9905951410733;634.2515831319088;644.8226737647091;658.3975662269066;678.4063944048958;703.5637904499141;724.3697982755037;745.6793578761185;9476.763080923032;9738.95130524357;9786.217102631772;9878.604855805832;9887.209023278265;592.8903152916724;632.7437387857925;667.739581563181;707.5330282013846;708.6385943169021;4463.4420697412825;4463.5759602838325;4569.13186494565;4572.497025327211;4665.136949664323;66555.66722166455;67726.66136669285;68300.6584967072;69138.30861691419;69144.3085569148;60903.39096609066;61553;62476.68761656163;63114.36885631177;64211;55683;55913;56335.43664563302;56638.15042774362;56725.14912276319;8834.381593288408;8851.203391694778;8956.820863582821;9004.32467564922;9008.459492430475;37511.2497750038;37664.058398540255;37675.2464950694;37720.62279377281;38051;45771.31343029924;49779.25331119964;50155.74922125366;50533.74733126311;51332.48667513277;9903.356281841658;10196.745081372728;10203.591856325664;10432.895671043192;10515.526801294112;11317.830232546683;12049.277043377426;12618.747625047632;12715.17351372195;13554.86445135536;13487.32563371847;13529.86470135286;13695.86304136946;13724.65688357799;13987.790183147465;12437;12580;12678;12766.553170638983;12892.871071289543;13006.674833129247;13094.803577946528;13203.273819939996;13278.800817987545;13530.458781648624;11052.613158539412;11203;11269.267497612629;11471.94264028708;11483.36841473727;11276.605318813803;11333.206675532634;11459.656210313704;11517.481713322753;11700.47347869331 13:19:09 INFO - PROCESS | 8365 | __end_tp_report 13:19:09 INFO - PROCESS | 8365 | __start_cc_report 13:19:09 INFO - PROCESS | 8365 | _x_x_mozilla_cycle_collect,816 13:19:09 INFO - PROCESS | 8365 | __end_cc_report 13:19:09 INFO - PROCESS | 8365 | __startTimestamp1463602749713__endTimestamp 13:19:09 INFO - PROCESS | 8365 | 13:19:09 INFO - PROCESS | 8365 | ------- Summary: start ------- 13:19:09 INFO - PROCESS | 8365 | Number of tests: 6 13:19:09 INFO - PROCESS | 8365 | 13:19:09 INFO - PROCESS | 8365 | [#0] dojo.html Cycles:165 Average:5321.63 Median:5512.06 stddev:591.36 (10.7%) stddev-sans-first:568.03 13:19:09 INFO - PROCESS | 8365 | Values: 3146.4 3163.7 3191.4 3200.4 3224.3 5246.6 5270.6 5277.4 5386.4 5428.0 5354.3 5364.7 5365.8 5429.2 5450.9 4805.1 4806.1 4957.3 4999.8 5011.6 4919.3 4946.9 4983.5 4994.5 5006.8 4744.5 4822.3 4824.4 4878.2 4881.0 4889.9 4900.7 4901.1 4920.3 4938.6 4738.6 4828.3 4870.2 4887.0 4904.8 5274.0 5343.9 5361.8 5375.0 5378.8 5807.6 5813.0 5818.5 5819.7 5820.4 5587.2 5763.0 5798.8 5826.1 5843.9 5424.2 5452.5 5455.3 5457.4 5460.9 5980.7 5981.1 5991.5 5996.3 5998.5 5460.7 5492.8 5508.7 5523.5 5752.7 5615.6 5620.2 5626.4 5627.5 5631.5 5608.3 5608.8 5609.6 5613.4 5613.7 5532.8 5686.4 5710.3 5743.3 5774.4 4355.7 4355.7 4388.0 4401.2 4404.6 3854.5 3859.9 3866.5 3936.2 3982.5 5313.5 5370.8 5406.1 5420.9 5427.0 5644.4 5680.5 5688.2 5689.4 5718.3 5343.0 5381.8 5387.5 5408.2 5460.9 4949.2 4955.8 4967.2 4967.5 4997.8 5964.9 5987.3 5989.6 5996.9 6041.2 5512.1 5601.0 5659.3 5683.6 5698.9 5922.3 5926.8 5933.2 5934.9 5968.6 5560.4 5560.8 5563.7 5574.9 5605.5 5586.3 5633.7 5679.3 5690.2 5732.0 5562.6 5588.7 5590.5 5592.6 5597.9 5538.1 5567.7 5586.1 5592.2 5598.2 5402.1 5472.8 5482.5 5514.8 5550.8 5666.3 5680.9 5726.8 5728.3 5732.2 5412.5 5549.2 5550.0 5555.8 5588.4 13:19:09 INFO - PROCESS | 8365 | 13:19:09 INFO - PROCESS | 8365 | [#1] ext.html Cycles:180 Average:57591.57 Median:40916.59 stddev:110511.19 (270.1%) stddev-sans-first:110738.40 13:19:09 INFO - PROCESS | 8365 | Values: 612.8 633.7 649.7 655.4 662.2 43317.6 44696.8 44719.0 44768.0 48118.0 45331.5 45884.5 45931.0 46002.5 46019.5 8841.9 9028.8 9037.7 9039.5 9040.7 8850.5 8942.6 8944.6 8945.2 8948.9 8746.8 8782.0 9011.9 9026.8 9028.2 8956.9 8976.1 8979.1 8981.0 8991.9 8818.7 8868.4 8886.1 8887.9 8889.0 41371.4 41487.4 41532.8 41578.0 41810.6 3230.2 3798.6 3822.9 3880.6 3999.1 29458.6 29708.4 29764.1 29799.9 33041.8 4249.7 4253.9 4375.2 4445.9 4449.8 335757.0 335887.0 336943.3 339001.0 339051.0 56991.7 58558.7 58668.4 58700.4 58717.0 60998.7 61055.1 61127.4 61170.0 61222.4 14554.8 14730.6 14731.9 14760.9 14776.6 11976.2 12153.7 12154.7 12181.0 12186.6 14908.6 16244.0 16465.8 16518.0 16545.3 5449.9 6349.8 6357.8 6362.6 6449.5 962.3 1005.0 1021.6 1054.3 1175.5 32588.5 32733.0 32995.0 33080.2 33510.0 1140.9 1159.9 1213.5 1224.2 1246.4 17069.6 17475.2 17480.7 17522.4 17527.5 514366.0 520193.0 520699.0 522679.0 524286.0 58206.0 58388.4 58393.4 58420.7 58444.7 1223.6 1393.4 1411.6 1416.0 1434.4 32231.5 32945.3 33691.6 33828.3 34144.0 48368.5 48841.8 48859.0 48883.3 49054.5 370687.0 371360.0 372247.0 372719.0 373467.0 29762.0 31192.4 37363.4 37613.0 41649.0 45431.5 45674.8 46208.5 46682.5 46688.3 39693.4 39729.2 39820.8 39975.0 40965.2 39126.2 39154.4 39183.6 39532.8 39893.0 39854.2 40139.6 40815.0 40873.6 40959.6 33102.7 34674.3 34878.1 34933.0 35761.6 27282.3 27681.3 27783.0 27853.6 27862.6 13:19:09 INFO - PROCESS | 8365 | 13:19:09 INFO - PROCESS | 8365 | [#2] jquery.html Cycles:180 Average:28739.04 Median:12325.21 stddev:80452.24 (652.7%) stddev-sans-first:80649.79 13:19:09 INFO - PROCESS | 8365 | Values: 403.2 410.5 412.5 414.4 417.6 18260.3 18905.0 19932.6 20131.5 20148.4 12265.6 12566.7 16071.0 19550.3 19566.3 4826.9 5159.9 5395.6 5905.7 5917.1 5847.5 5861.2 5869.3 5882.2 5909.2 5251.2 5526.1 5540.6 5563.5 5822.8 5346.4 5512.5 5523.9 5664.7 5694.7 5500.9 5503.1 5510.3 5544.9 5545.7 13837.7 21256.9 22379.2 23606.0 23641.5 2145.0 2184.5 2271.2 2285.8 2371.7 10334.6 10339.9 10344.6 10595.3 10600.2 1967.0 2070.1 2070.5 2081.6 2104.4 52877.0 76942.2 82406.0 83279.2 87641.7 10470.4 10583.3 10854.2 11116.9 11221.3 15636.2 15676.5 15722.1 15854.9 15868.0 6827.0 6905.6 7556.8 7581.7 7590.2 6958.7 7031.2 7036.9 7070.5 7147.7 4477.8 4487.2 4559.7 4646.6 4660.7 1949.1 1961.4 2026.3 2059.0 2077.7 919.9 1021.7 1030.6 1058.0 1075.3 18761.6 19915.5 19944.2 20057.0 20106.8 960.5 963.6 990.1 993.4 1007.2 10235.8 10593.8 10645.3 10891.1 11087.7 475396.0 477415.0 493451.0 496539.0 497150.0 64375.4 73034.6 96587.0 101201.5 101931.0 1053.0 1054.4 1069.1 1074.9 1086.8 17991.7 18242.6 18358.3 18478.8 18623.4 42570.4 42896.1 43705.8 44039.6 44134.8 58650.7 61017.0 62236.7 63031.7 63568.0 8625.0 8833.3 8888.0 9018.4 9060.9 13108.5 13467.7 13682.0 13757.9 13787.5 11189.3 12157.5 12222.3 12324.1 12511.2 11326.9 11461.1 11484.7 11486.4 11773.7 12193.4 12490.4 12496.6 12502.6 12715.1 8518.5 8752.5 8786.2 8808.6 8815.4 8987.9 11167.3 11774.4 12056.3 12241.0 13:19:09 INFO - PROCESS | 8365 | 13:19:09 INFO - PROCESS | 8365 | [#3] mootools.html Cycles:180 Average:5740.92 Median:449.12 stddev:3316.59 (738.5%) stddev-sans-first:3308.41 13:19:09 INFO - PROCESS | 8365 | Values: 1209.8 1355.8 1459.1 1524.8 1815.7 8430.7 8695.3 8695.7 8909.8 9034.6 8739.9 8898.1 8899.2 8900.2 8923.2 2047.2 2100.5 2343.1 2367.2 2399.0 2381.9 2400.5 2402.4 2402.6 2405.0 2277.7 2282.6 2283.7 2308.9 2346.5 1985.8 2219.5 2235.5 2289.0 2293.8 2253.4 2277.7 2295.0 2295.1 2297.0 8631.6 8656.1 8692.2 8693.9 8696.3 5069.0 5174.9 5175.1 5178.8 5180.0 3278.3 3281.8 3319.3 3405.1 3434.8 1019.1 1034.5 1042.5 1073.6 1083.1 12053.8 12414.0 12415.4 12441.5 12473.4 4650.7 4657.3 4740.5 4756.6 4785.2 10477.0 10480.0 10484.9 10489.9 10522.6 5330.0 5337.1 5338.1 5341.2 5342.1 3995.5 4666.1 4759.0 4777.6 4830.0 3923.9 4018.2 4026.6 4100.2 4169.1 1161.2 1298.8 1311.4 1326.9 1332.4 866.6 909.5 949.4 952.0 1001.0 5673.8 5906.8 5906.9 5948.6 6043.5 435.8 436.4 446.2 448.7 449.5 4131.9 4159.6 4195.8 4199.4 4213.1 10532.6 10765.5 10844.9 10873.4 10907.7 10627.5 10664.6 10668.5 10713.7 10721.7 7856.4 8133.1 8134.7 8208.2 8250.4 5988.5 5997.9 6067.2 6109.9 6116.7 10103.4 10301.6 10367.8 10371.5 10383.5 10085.6 10115.4 10495.6 10541.3 10590.5 6639.9 6993.1 7209.8 7485.0 7649.7 8283.9 8289.8 8314.6 8351.6 8356.5 7436.4 7639.6 7658.7 7710.6 7724.1 7382.1 7392.1 7403.9 7529.3 7566.4 6513.2 6523.9 6533.3 6571.3 6583.7 4829.5 4914.9 5012.4 5020.5 5082.7 5125.9 5158.4 5175.0 5185.9 5272.7 13:19:09 INFO - PROCESS | 8365 | 13:19:09 INFO - PROCESS | 8365 | [#4] prototype.html Cycles:180 Average:4459.96 Median:5039.60 stddev:1286.77 (25.5%) stddev-sans-first:1252.13 13:19:09 INFO - PROCESS | 8365 | Values: 311.0 311.8 326.1 338.9 341.3 5096.0 5236.3 5261.4 5301.4 5330.7 5232.3 5233.8 5243.3 5248.6 5254.6 4090.2 4090.7 4095.6 4100.5 4109.9 3832.9 3890.4 4101.3 4113.6 4123.2 4063.0 4093.7 4104.2 4129.0 4129.8 4102.8 4110.5 4115.6 4116.3 4148.8 3447.1 3678.7 3692.7 3704.8 3712.6 5026.0 5053.2 5055.3 5057.1 5093.9 4695.9 4697.0 4703.8 4706.7 4707.8 5123.0 5375.5 5405.6 5416.2 5446.3 3889.3 3894.3 3897.0 3899.8 3914.3 5645.4 5678.4 5679.8 5698.7 5713.7 4063.0 4063.6 4075.8 4086.9 4090.8 3902.0 4104.9 4110.5 4124.6 4135.4 4670.6 4672.1 4673.3 4680.6 4689.0 5457.4 5486.8 5495.9 5497.7 5547.3 1292.4 1302.6 1399.9 1415.0 1423.1 886.8 903.9 904.2 910.7 918.6 4797.2 4828.9 4856.2 4876.4 4901.3 5078.4 5095.3 5119.0 5131.3 5141.7 4557.0 4828.5 4847.4 4847.6 4853.9 2662.4 2680.8 2683.1 2706.2 2715.6 5139.6 5185.4 5209.2 5291.7 5297.1 4732.1 5065.1 5074.8 5109.5 5133.9 5232.9 5238.8 5274.7 5281.0 5340.1 4909.4 4950.0 4964.8 4991.0 5151.0 4676.8 4803.2 4944.4 4956.7 4973.9 5153.5 5172.5 5216.6 5236.8 5243.6 3670.6 3690.0 3699.4 3700.8 3824.9 5490.6 5568.6 5606.5 5616.3 5628.6 5382.3 5404.0 5440.9 5446.7 5465.8 5344.8 5443.3 5447.4 5453.2 5470.8 5310.7 5329.7 5355.3 5369.2 5369.3 5493.3 5505.0 5526.4 5538.6 5606.6 5117.2 5365.7 5499.6 5501.4 5526.1 13:19:09 INFO - PROCESS | 8365 | 13:19:09 INFO - PROCESS | 8365 | [#5] yui.html Cycles:180 Average:15776.23 Median:25189.96 stddev:19033.84 (75.6%) stddev-sans-first:19053.43 13:19:09 INFO - PROCESS | 8365 | Values: 670.8 767.1 815.3 866.5 872.6 14207.2 15033.0 15033.5 15043.3 15064.1 14322.5 14361.6 14364.8 14371.6 14391.4 678.4 785.0 805.7 812.8 812.9 822.4 825.4 829.4 829.7 830.2 863.4 867.0 867.2 867.3 867.5 715.5 850.0 866.4 867.2 879.7 641.0 642.5 642.6 642.9 643.3 14285.3 14322.1 14324.1 14342.4 14415.5 13248.9 13702.0 13763.4 13773.0 13816.1 11951.3 12159.8 12218.6 12471.2 12560.3 4432.0 4509.4 4536.6 4652.0 4667.0 57687.1 58270.7 59774.0 60316.1 60342.7 10069.3 10164.5 10632.3 11094.6 11252.2 19525.9 19526.9 19721.6 19744.0 19982.2 7213.4 7289.2 7339.9 7351.9 7388.9 4392.3 4484.4 4522.3 4547.7 4608.6 3243.2 3306.2 3306.4 3308.8 3311.6 620.1 625.2 627.0 634.3 644.8 658.4 678.4 703.6 724.4 745.7 9476.8 9739.0 9786.2 9878.6 9887.2 592.9 632.7 667.7 707.5 708.6 4463.4 4463.6 4569.1 4572.5 4665.1 66555.7 67726.7 68300.7 69138.3 69144.3 60903.4 61553.0 62476.7 63114.4 64211.0 55683.0 55913.0 56335.4 56638.2 56725.1 8834.4 8851.2 8956.8 9004.3 9008.5 37511.2 37664.1 37675.2 37720.6 38051.0 45771.3 49779.3 50155.7 50533.7 51332.5 9903.4 10196.7 10203.6 10432.9 10515.5 11317.8 12049.3 12618.7 12715.2 13554.9 13487.3 13529.9 13695.9 13724.7 13987.8 12437.0 12580.0 12678.0 12766.6 12892.9 13006.7 13094.8 13203.3 13278.8 13530.5 11052.6 11203.0 11269.3 11471.9 11483.4 11276.6 11333.2 11459.7 11517.5 11700.5 13:19:09 INFO - PROCESS | 8365 | -------- Summary: end -------- 13:19:09 INFO - PROCESS | 8365 | 13:19:10 INFO - TEST-INFO | 8365: exit 0 13:19:10 INFO - TEST-OK | dromaeo_css | took 1112175ms 13:19:10 INFO - TEST-START | kraken 13:19:10 INFO - operating with platform_type : linux_ 13:19:10 INFO - Initialising browser for kraken test... 13:19:10 INFO - TEST-INFO | started process 8551 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp7aWxyJ/profile http://localhost:41236/getInfo.html) 13:19:14 INFO - PROCESS | 8551 | __metrics Screen width/height:1600/1200 13:19:14 INFO - PROCESS | 8551 | colorDepth:24 13:19:14 INFO - PROCESS | 8551 | Browser inner width/height: 1024/697 13:19:14 INFO - PROCESS | 8551 | __metrics 13:19:15 ERROR - PROCESS | 8551 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 13:19:16 INFO - TEST-INFO | 8551: exit 0 13:19:16 INFO - Browser initialized. 13:19:16 INFO - Running cycle 1/1 for kraken test... 13:19:16 INFO - TEST-INFO | started process 8634 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp7aWxyJ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:19:28 INFO - PROCESS | 8634 | RSS: Main: 152662016 13:19:28 INFO - PROCESS | 8634 | 13:19:30 INFO - PROCESS | 8634 | Cycle 1(1): loaded http://localhost:41236/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:41236/tests/kraken/driver.html?testName=audio-beat-detection) 13:19:31 INFO - PROCESS | 8634 | RSS: Main: 208490496 13:19:31 INFO - PROCESS | 8634 | 13:19:34 INFO - PROCESS | 8634 | Cycle 1(1): loaded http://localhost:41236/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:41236/tests/kraken/driver.html?testName=audio-dft) 13:19:34 INFO - PROCESS | 8634 | RSS: Main: 198520832 13:19:34 INFO - PROCESS | 8634 | 13:19:37 INFO - PROCESS | 8634 | Cycle 1(1): loaded http://localhost:41236/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:41236/tests/kraken/driver.html?testName=audio-fft) 13:19:38 INFO - PROCESS | 8634 | RSS: Main: 265601024 13:19:38 INFO - PROCESS | 8634 | 13:19:40 INFO - PROCESS | 8634 | Cycle 1(1): loaded http://localhost:41236/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:41236/tests/kraken/driver.html?testName=audio-oscillator) 13:19:41 INFO - PROCESS | 8634 | RSS: Main: 174153728 13:19:41 INFO - PROCESS | 8634 | 13:19:43 INFO - PROCESS | 8634 | Cycle 1(1): loaded http://localhost:41236/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:41236/tests/kraken/driver.html?testName=imaging-gaussian-blur) 13:19:44 INFO - PROCESS | 8634 | RSS: Main: 178655232 13:19:44 INFO - PROCESS | 8634 | 13:19:47 INFO - PROCESS | 8634 | Cycle 1(1): loaded http://localhost:41236/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:41236/tests/kraken/driver.html?testName=imaging-darkroom) 13:19:48 INFO - PROCESS | 8634 | RSS: Main: 214835200 13:19:48 INFO - PROCESS | 8634 | 13:19:51 INFO - PROCESS | 8634 | Cycle 1(1): loaded http://localhost:41236/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:41236/tests/kraken/driver.html?testName=imaging-desaturate) 13:19:52 INFO - PROCESS | 8634 | RSS: Main: 213217280 13:19:52 INFO - PROCESS | 8634 | 13:19:55 INFO - PROCESS | 8634 | Cycle 1(1): loaded http://localhost:41236/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:41236/tests/kraken/driver.html?testName=json-parse-financial) 13:19:55 INFO - PROCESS | 8634 | RSS: Main: 213401600 13:19:55 INFO - PROCESS | 8634 | 13:19:57 INFO - PROCESS | 8634 | Cycle 1(1): loaded http://localhost:41236/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:41236/tests/kraken/driver.html?testName=json-stringify-tinderbox) 13:19:57 INFO - PROCESS | 8634 | RSS: Main: 204042240 13:19:57 INFO - PROCESS | 8634 | 13:19:59 INFO - PROCESS | 8634 | Cycle 1(1): loaded http://localhost:41236/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:41236/tests/kraken/driver.html?testName=stanford-crypto-aes) 13:20:00 INFO - PROCESS | 8634 | RSS: Main: 209850368 13:20:00 INFO - PROCESS | 8634 | 13:20:02 INFO - PROCESS | 8634 | Cycle 1(1): loaded http://localhost:41236/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:41236/tests/kraken/driver.html?testName=stanford-crypto-ccm) 13:20:03 INFO - PROCESS | 8634 | RSS: Main: 216276992 13:20:03 INFO - PROCESS | 8634 | 13:20:05 INFO - PROCESS | 8634 | Cycle 1(1): loaded http://localhost:41236/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:41236/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 13:20:06 INFO - PROCESS | 8634 | RSS: Main: 223641600 13:20:06 INFO - PROCESS | 8634 | 13:20:09 INFO - PROCESS | 8634 | Cycle 1(1): loaded http://localhost:41236/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:41236/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 13:20:09 INFO - PROCESS | 8634 | RSS: Main: 217817088 13:20:09 INFO - PROCESS | 8634 | 13:20:11 INFO - PROCESS | 8634 | Cycle 1(1): loaded http://localhost:41236/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:41236/tests/kraken/driver.html?testName=ai-astar) 13:20:11 INFO - PROCESS | 8634 | RSS: Main: 263581696 13:20:11 INFO - PROCESS | 8634 | 13:20:11 INFO - PROCESS | 8634 | __start_tp_report 13:20:11 INFO - PROCESS | 8634 | _x_x_mozilla_page_load 13:20:11 INFO - PROCESS | 8634 | _x_x_mozilla_page_load_details 13:20:11 INFO - PROCESS | 8634 | |i|pagename|runs| 13:20:11 INFO - PROCESS | 8634 | |0;ai-astar;109;105;109;108;110;111;109;110;108;108 13:20:11 INFO - PROCESS | 8634 | |1;audio-beat-detection;150;123;154;205;125;155;157;124;180;130 13:20:11 INFO - PROCESS | 8634 | |2;audio-dft;162;161;160;161;161;160;161;162;160;162 13:20:11 INFO - PROCESS | 8634 | |3;audio-fft;74;76;77;146;75;75;75;76;139;74 13:20:11 INFO - PROCESS | 8634 | |4;audio-oscillator;90;91;147;93;91;91;116;91;91;124 13:20:11 INFO - PROCESS | 8634 | |5;imaging-gaussian-blur;116;116;116;117;116;116;116;116;117;116 13:20:11 INFO - PROCESS | 8634 | |6;imaging-darkroom;146;145;146;145;146;145;145;145;146;145 13:20:11 INFO - PROCESS | 8634 | |7;imaging-desaturate;94;95;95;95;94;95;94;95;95;95 13:20:11 INFO - PROCESS | 8634 | |8;json-parse-financial;55;54;54;53;54;54;54;120;53;52 13:20:11 INFO - PROCESS | 8634 | |9;json-stringify-tinderbox;59;59;59;60;59;60;59;60;59;60 13:20:11 INFO - PROCESS | 8634 | |10;stanford-crypto-aes;78;78;78;78;78;78;79;78;79;80 13:20:11 INFO - PROCESS | 8634 | |11;stanford-crypto-ccm;121;125;122;127;122;119;120;117;206;121 13:20:11 INFO - PROCESS | 8634 | |12;stanford-crypto-pbkdf2;162;161;169;161;158;167;165;159;160;160 13:20:11 INFO - PROCESS | 8634 | |13;stanford-crypto-sha256-iterative;64;63;64;62;63;64;64;62;62;63 13:20:11 INFO - PROCESS | 8634 | __end_tp_report 13:20:11 INFO - PROCESS | 8634 | __start_cc_report 13:20:11 INFO - PROCESS | 8634 | _x_x_mozilla_cycle_collect,1891 13:20:11 INFO - PROCESS | 8634 | __end_cc_report 13:20:11 INFO - PROCESS | 8634 | __startTimestamp1463602811713__endTimestamp 13:20:11 INFO - PROCESS | 8634 | 13:20:11 INFO - PROCESS | 8634 | ------- Summary: start ------- 13:20:11 INFO - PROCESS | 8634 | Number of tests: 14 13:20:11 INFO - PROCESS | 8634 | 13:20:11 INFO - PROCESS | 8634 | [#0] ai-astar Cycles:10 Average:108.70 Median:109.00 stddev:1.64 (1.5%) stddev-sans-first:1.73 13:20:11 INFO - PROCESS | 8634 | Values: 109.0 105.0 109.0 108.0 110.0 111.0 109.0 110.0 108.0 108.0 13:20:11 INFO - PROCESS | 8634 | 13:20:11 INFO - PROCESS | 8634 | [#1] audio-beat-detection Cycles:10 Average:150.30 Median:154.50 stddev:26.76 (17.3%) stddev-sans-first:28.38 13:20:11 INFO - PROCESS | 8634 | Values: 150.0 123.0 154.0 205.0 125.0 155.0 157.0 124.0 180.0 130.0 13:20:11 INFO - PROCESS | 8634 | 13:20:11 INFO - PROCESS | 8634 | [#2] audio-dft Cycles:10 Average:161.00 Median:161.00 stddev:0.82 (0.5%) stddev-sans-first:0.78 13:20:11 INFO - PROCESS | 8634 | Values: 162.0 161.0 160.0 161.0 161.0 160.0 161.0 162.0 160.0 162.0 13:20:11 INFO - PROCESS | 8634 | 13:20:11 INFO - PROCESS | 8634 | [#3] audio-fft Cycles:10 Average:88.70 Median:75.00 stddev:28.42 (37.9%) stddev-sans-first:29.64 13:20:11 INFO - PROCESS | 8634 | Values: 74.0 76.0 77.0 146.0 75.0 75.0 75.0 76.0 139.0 74.0 13:20:11 INFO - PROCESS | 8634 | 13:20:11 INFO - PROCESS | 8634 | [#4] audio-oscillator Cycles:10 Average:102.50 Median:91.00 stddev:19.81 (21.8%) stddev-sans-first:20.49 13:20:11 INFO - PROCESS | 8634 | Values: 90.0 91.0 147.0 93.0 91.0 91.0 116.0 91.0 91.0 124.0 13:20:11 INFO - PROCESS | 8634 | 13:20:11 INFO - PROCESS | 8634 | [#5] imaging-gaussian-blur Cycles:10 Average:116.20 Median:116.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 13:20:11 INFO - PROCESS | 8634 | Values: 116.0 116.0 116.0 117.0 116.0 116.0 116.0 116.0 117.0 116.0 13:20:11 INFO - PROCESS | 8634 | 13:20:11 INFO - PROCESS | 8634 | [#6] imaging-darkroom Cycles:10 Average:145.40 Median:145.50 stddev:0.52 (0.4%) stddev-sans-first:0.50 13:20:11 INFO - PROCESS | 8634 | Values: 146.0 145.0 146.0 145.0 146.0 145.0 145.0 145.0 146.0 145.0 13:20:11 INFO - PROCESS | 8634 | 13:20:11 INFO - PROCESS | 8634 | [#7] imaging-desaturate Cycles:10 Average:94.70 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.44 13:20:11 INFO - PROCESS | 8634 | Values: 94.0 95.0 95.0 95.0 94.0 95.0 94.0 95.0 95.0 95.0 13:20:11 INFO - PROCESS | 8634 | 13:20:11 INFO - PROCESS | 8634 | [#8] json-parse-financial Cycles:10 Average:60.30 Median:54.00 stddev:20.99 (38.9%) stddev-sans-first:22.18 13:20:11 INFO - PROCESS | 8634 | Values: 55.0 54.0 54.0 53.0 54.0 54.0 54.0 120.0 53.0 52.0 13:20:11 INFO - PROCESS | 8634 | 13:20:11 INFO - PROCESS | 8634 | [#9] json-stringify-tinderbox Cycles:10 Average:59.40 Median:59.50 stddev:0.52 (0.9%) stddev-sans-first:0.53 13:20:11 INFO - PROCESS | 8634 | Values: 59.0 59.0 59.0 60.0 59.0 60.0 59.0 60.0 59.0 60.0 13:20:11 INFO - PROCESS | 8634 | 13:20:11 INFO - PROCESS | 8634 | [#10] stanford-crypto-aes Cycles:10 Average:78.40 Median:78.00 stddev:0.70 (0.9%) stddev-sans-first:0.73 13:20:11 INFO - PROCESS | 8634 | Values: 78.0 78.0 78.0 78.0 78.0 78.0 79.0 78.0 79.0 80.0 13:20:11 INFO - PROCESS | 8634 | 13:20:11 INFO - PROCESS | 8634 | [#11] stanford-crypto-ccm Cycles:10 Average:130.00 Median:122.00 stddev:26.85 (22.0%) stddev-sans-first:28.28 13:20:11 INFO - PROCESS | 8634 | Values: 121.0 125.0 122.0 127.0 122.0 119.0 120.0 117.0 206.0 121.0 13:20:11 INFO - PROCESS | 8634 | 13:20:11 INFO - PROCESS | 8634 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:162.20 Median:161.50 stddev:3.61 (2.2%) stddev-sans-first:3.83 13:20:11 INFO - PROCESS | 8634 | Values: 162.0 161.0 169.0 161.0 158.0 167.0 165.0 159.0 160.0 160.0 13:20:11 INFO - PROCESS | 8634 | 13:20:11 INFO - PROCESS | 8634 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.10 Median:63.50 stddev:0.88 (1.4%) stddev-sans-first:0.87 13:20:11 INFO - PROCESS | 8634 | Values: 64.0 63.0 64.0 62.0 63.0 64.0 64.0 62.0 62.0 63.0 13:20:11 INFO - PROCESS | 8634 | -------- Summary: end -------- 13:20:11 INFO - PROCESS | 8634 | 13:20:12 INFO - TEST-INFO | 8634: exit 0 13:20:12 INFO - TEST-OK | kraken | took 62337ms 13:20:12 INFO - SUITE-END | took 1174s 13:20:12 INFO - Completed test suite (00:19:34) 13:20:12 INFO - javascript benchmark 13:20:12 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3146.4493713600114, 3163.7310828579557, 3191.3617276544696, 3200.439923013475, 3224.25842056327, 5246.554042906358, 5270.578353731702, 5277.393099793525, 5386.434424385442, 5428.022955867942, 5354.303940487731, 5364.678119312844, 5365.7853685852615, 5429.185622156679, 5450.863728406792, 4805.063012712522, 4806.086843499741, 4957.281194226835, 4999.7500124993785, 5011.574016208609, 4919.286703428001, 4946.901061978759, 4983.526564976321, 4994.500549945013, 5006.799728010875, 4744.501827308135, 4822.348982887301, 4824.372831531896, 4878.170710979142, 4880.9755951220195, 4889.853304400874, 4900.681455705368, 4901.093297739894, 4920.286558449041, 4938.555530002317, 4738.620910327166, 4828.299896515021, 4870.172070747946, 4886.975565122151, 4904.779284932187, 5274.0, 5343.946560534423, 5361.758720857571, 5374.973125134349, 5378.811741589065, 5807.622504537234, 5812.970935145339, 5818.476337129637, 5819.73811178498, 5820.447057529528, 5587.161925711176, 5762.971185144047, 5798.82603521895, 5826.096954971944, 5843.912341314884, 5424.159255315432, 5452.5365343946205, 5455.318085239345, 5457.426970168154, 5460.863478413032, 5980.73086711099, 5981.132735753378, 5991.5206783457215, 5996.340402555716, 5998.520118390518, 5460.726963651801, 5492.80775172867, 5508.669479831223, 5523.530499907472, 5752.683602401824, 5615.606907516516, 5620.213170156181, 5626.35296940857, 5627.549796016309, 5631.549476041907, 5608.298962629671, 5608.831735047955, 5609.607327487037, 5613.354464236583, 5613.691246981455, 5532.7786888524815, 5686.402927692616, 5710.2862142232225, 5743.282089738783, 5774.393688662714, 4355.651547876162, 4355.73865568067, 4387.978060109743, 4401.207782599099, 4404.581564751344, 3854.479645247912, 3859.9192226176715, 3866.516685414323, 3936.15372694872, 3982.5220973482787, 5313.521783039468, 5370.838874833761, 5406.135018397038, 5420.891582168335, 5426.972865135728, 5644.407337229532, 5680.488756011979, 5688.232088667977, 5689.431056894277, 5718.285214348207, 5342.973285133549, 5381.838544843661, 5387.461253874581, 5408.242846001563, 5460.890782184334, 4949.208126699783, 4955.82654607087, 4967.155583550805, 4967.4784147664695, 4997.775100120431, 5964.880702385928, 5987.2815262168815, 5989.550783691154, 5996.880062398727, 6041.154238406627, 5512.062949298549, 5601.0, 5659.292588426447, 5683.573731970201, 5698.943010569841, 5922.289325280994, 5926.762929482772, 5933.199018132497, 5934.851628709317, 5968.612040217372, 5560.4161563035295, 5560.777568897361, 5563.721813909207, 5574.86062848432, 5605.523530499953, 5586.273784408001, 5633.718314084197, 5679.261695979496, 5690.231818704588, 5731.996900542472, 5562.638428502134, 5588.720563971704, 5590.468905453975, 5592.608517403743, 5597.916031259453, 5538.086215774513, 5567.69377684215, 5586.050371436784, 5592.217089607377, 5598.244237027949, 5402.108652072521, 5472.835814925558, 5482.479164479368, 5514.80698175562, 5550.7779688812, 5666.263385759825, 5680.943190568041, 5726.770929162787, 5728.312602487728, 5732.16883551891, 5412.539934105645, 5549.1676248563035, 5549.972250138723, 5555.777768889199, 5588.357338905916], "value": 5282.965073702712, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [612.7947137708867, 633.7243299164863, 649.6524359467686, 655.4068567946008, 662.2450406536547, 43317.56682433175, 44696.776516117374, 44719.0, 44768.0, 48118.0, 45331.54668453315, 45884.54115458844, 45931.0, 46002.539974600244, 46019.53980460195, 8841.911580884173, 9028.819423611523, 9037.728868133967, 9039.548022598876, 9040.683576074838, 8850.513221772831, 8942.64229430822, 8944.642214311452, 8945.194932456077, 8948.8657670135, 8746.825063498727, 8781.95609021954, 9011.864822027675, 9026.819463610756, 9028.232600228988, 8956.865647015267, 8976.057513961037, 8979.146981036856, 8980.955095224548, 8991.865122023142, 8818.735437936872, 8868.37921345506, 8886.111388861062, 8887.911120888773, 8889.0, 41371.37942930858, 41487.37768933469, 41532.79233603843, 41578.0, 41810.58189418097, 3230.224746060952, 3798.639129282714, 3822.8853134406013, 3880.59253778352, 3999.1201935574127, 29458.558121628193, 29708.405831883243, 29764.107076787514, 29799.851000744857, 33041.834790825895, 4249.702520823544, 4253.851115210953, 4375.2124617568825, 4445.9110817783785, 4449.777511124431, 335757.0, 335887.0, 336943.31528342445, 339001.0, 339051.0, 56991.715041424526, 58558.707206463696, 58668.41331586715, 58700.41299587035, 58717.0, 60998.695006524686, 61055.08417373743, 61127.38872611306, 61170.0, 61222.38777612167, 14554.781678274834, 14730.55808325752, 14731.852681473049, 14760.852391475948, 14776.556703298918, 11976.221545599683, 12153.69615759613, 12154.696132596755, 12181.025517958542, 12186.57346992851, 14908.552743417715, 16244.0, 16465.753013704805, 16518.0, 16545.338186472647, 5449.945500545023, 6349.841253968688, 6357.841053973595, 6362.618242905441, 6449.516286278548, 962.3215632978734, 1004.9598016079422, 1021.5505177721778, 1054.2725519391595, 1175.4651633506735, 32588.511172332433, 32733.0, 32995.0, 33080.17299567482, 33510.0, 1140.8916152965621, 1159.8550181227347, 1213.520659339556, 1224.1981502116128, 1246.4079562207878, 17069.573260668334, 17475.21361538734, 17480.650386992187, 17522.386716464862, 17527.474175774747, 514366.0, 520193.0, 520699.0, 522679.0, 524286.0, 58206.0, 58388.4161158383, 58393.4160658388, 58420.70789646025, 58444.70777646085, 1223.6451429085466, 1393.4426229508279, 1411.6265063239277, 1415.950441734555, 1434.3617090394673, 32231.516527252577, 32945.34109317848, 33691.64527118184, 33828.3234335307, 34144.0, 48368.51631483781, 48841.75579122224, 48859.0, 48883.26675099947, 49054.509454905, 370687.0, 371360.0, 372247.0, 372719.0, 373467.0, 29762.0, 31192.37615247637, 37363.43954840734, 37613.0, 41649.0, 45431.54568454273, 45674.77162614299, 46208.53791462042, 46682.53317466917, 46688.29967550557, 39693.404598931615, 39729.205415890945, 39820.80089599534, 39975.0, 40965.1806963865, 39126.2174756509, 39154.41268381033, 39183.608163918, 39532.80233598928, 39893.0, 39854.2029159421, 40139.598604014755, 40815.0, 40873.59126408698, 40959.59040409558, 33102.668973309956, 34674.30651386908, 34878.08170550644, 34933.0, 35761.64238357583, 27282.317942051606, 27681.307967300174, 27783.015338570895, 27853.582196266667, 27862.60686965684], "value": 19937.45691797218, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [403.1533779063965, 410.5114913253228, 412.4699760807361, 414.3846388113653, 417.5824175824176, 18260.269589216434, 18905.0, 19932.60134797303, 20131.496712582193, 20148.39554813356, 12265.63203103906, 12566.748665026695, 16071.0, 19550.315738949103, 19566.31517896874, 4826.889815342473, 5159.871003224931, 5395.568354531628, 5905.675187864687, 5917.142014407908, 5847.502962248214, 5861.238039054917, 5869.266341707286, 5882.235309409771, 5909.20225769522, 5251.186066159751, 5526.06056970316, 5540.584456165783, 5563.499285064343, 5822.796202132926, 5346.4118946915805, 5512.503874651281, 5523.861903452405, 5664.716764161775, 5694.743736531866, 5500.889982200333, 5503.091989821674, 5510.256115424407, 5544.91682624761, 5545.667259964374, 13837.723245535033, 21256.893715531478, 22379.216727414463, 23606.0, 23641.527169456516, 2145.03473436954, 2184.541246338271, 2271.205078222625, 2285.794278514925, 2371.6561098640773, 10334.638287659896, 10339.896601033968, 10344.586216551328, 10595.25833191677, 10600.151987840954, 1967.0, 2070.109852763311, 2070.523779530701, 2081.5732774781286, 2104.400245929918, 52877.0, 76942.23057769406, 82406.0, 83279.16720832835, 87641.68537471943, 10470.37177769321, 10583.31208471447, 10854.185936054828, 11116.888831111748, 11221.326720396803, 15636.2181890905, 15676.451324203597, 15722.13528255934, 15854.9207253963, 15868.0, 6826.965865170642, 6905.620190889549, 7556.811079723051, 7581.658825352873, 7590.1650818409935, 6958.721651133999, 7031.156261248683, 7036.859262814817, 7070.469714771412, 7147.714091436388, 4477.8208871644765, 4487.195502810743, 4559.680822342404, 4646.628269738413, 4660.743659098782, 1949.0644490644286, 1961.4213806926991, 2026.3313106674761, 2059.0, 2077.7298951136254, 919.935604507678, 1021.6628512590827, 1030.5929157982555, 1058.0266155137292, 1075.349413604758, 18761.624767504574, 19915.502112447306, 19944.20223191085, 20057.0, 20106.798932010784, 960.4765402855461, 963.5808423335872, 990.0841721407669, 993.3592832622918, 1007.1741172238694, 10235.846462303072, 10593.788124237471, 10645.254832161674, 10891.0742586881, 11087.733227471155, 475396.0, 477415.0, 493451.0, 496539.0, 497150.0, 64375.35624643787, 73034.63482682552, 96587.0, 101201.49399252956, 101931.0, 1052.9947350263353, 1054.383185836279, 1069.1286601419818, 1074.9193810464246, 1086.8261078227445, 17991.730124048412, 18242.635147296714, 18358.31777007645, 18478.81521184771, 18623.44129676112, 42570.361444577735, 42896.142077158904, 43705.78147109244, 44039.55960440355, 44134.77932610316, 58650.70674646599, 61017.0, 62236.68881655563, 63031.6848415755, 63568.0, 8624.956875215585, 8833.293336532934, 8888.0, 9018.413803102778, 9060.909390906007, 13108.475660973836, 13467.730645386842, 13682.0, 13757.862421375658, 13787.51743688966, 11189.328640281608, 12157.452914618687, 12222.266664000188, 12324.137310388187, 12511.249325040526, 11326.943365283121, 11461.140414468782, 11484.655460336204, 11486.425678715863, 11773.705657358634, 12193.390330483619, 12490.375481225721, 12496.562620308245, 12502.624921252376, 12715.10994230395, 8518.48889066658, 8752.518611476175, 8786.16531429513, 8808.559572021246, 8815.426997245158, 8987.865182022144, 11167.274127181707, 11774.352410617155, 12056.336901470504, 12241.0], "value": 9162.21832660204, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1209.842720446342, 1355.8372995240572, 1459.1099429348099, 1524.7560390337549, 1815.664102141104, 8430.704925327615, 8695.304375649948, 8695.652173913037, 8909.777255568613, 9034.59344329505, 8739.91260087399, 8898.065703101178, 8899.199072083511, 8900.19898209161, 8923.196912277894, 2047.1811275489774, 2100.453881990678, 2343.0510643189536, 2367.1714899785065, 2399.0, 2381.880905954695, 2400.519896020794, 2402.411409204747, 2402.6396040593877, 2405.0, 2277.6697378880053, 2282.577723121228, 2283.668868014137, 2308.8960996755104, 2346.5189636124637, 1985.821276085152, 2219.533897881447, 2235.508188198594, 2289.0, 2293.8050265727434, 2253.43664083979, 2277.7266727992583, 2295.0, 2295.116380193621, 2296.977030229693, 8631.611577478965, 8656.134386561356, 8692.174243446863, 8693.869591956125, 8696.347773916981, 5069.03688299222, 5174.922376164361, 5175.120229560984, 5178.792848286064, 5180.0, 3278.278778668689, 3281.8359082045804, 3319.2697606526526, 3405.063607507955, 3434.793912365241, 1019.0675531888387, 1034.5396298647106, 1042.5412818359973, 1073.6134991403167, 1083.1334932054403, 12053.819192712117, 12414.0, 12415.379231038412, 12441.502339906392, 12473.438695258736, 4650.720956742539, 4657.3246879202325, 4740.502247264057, 4756.6432517561325, 4785.234362501983, 10476.952304769462, 10480.0, 10484.895151048544, 10489.895101048893, 10522.579096836194, 5330.0, 5337.092694241988, 5338.065838478251, 5341.172118321666, 5342.1185504392115, 3995.5005624296964, 4666.0901124280435, 4759.0, 4777.617790576745, 4829.95170048295, 3923.9215215695526, 4018.1963607278662, 4026.57720939303, 4100.2414553307735, 4169.145325208325, 1161.2393882007298, 1298.7921932490756, 1311.3508813137366, 1326.8805807477374, 1332.4203971272527, 866.6013633728555, 909.5179554835947, 949.4192170036812, 952.0, 1000.9699709008742, 5673.8014169504695, 5906.793262235887, 5906.8818623627285, 5948.613340132964, 6043.486303664238, 435.84745339131683, 436.3629101511765, 446.16789687233074, 448.73076154307154, 449.51676947281805, 4131.876043718693, 4159.584041595816, 4195.769232692171, 4199.4330765346895, 4213.073123912734, 10532.578696852193, 10765.46172691378, 10844.891551084545, 10873.401962892116, 10907.672769816918, 10627.468626568641, 10664.573417063231, 10668.466576671135, 10713.732156696145, 10721.731956701145, 7856.371490280764, 8133.0646975598565, 8134.715284965117, 8208.220219079178, 8250.422470427013, 5988.507408385677, 5997.880042399215, 6067.150598916243, 6109.908351374822, 6116.66358350295, 10103.444310562989, 10301.587936482456, 10367.792644147226, 10371.481425928523, 10383.480825958823, 10085.647002354883, 10115.443650599287, 10495.580176792842, 10541.262111652111, 10590.523426445674, 6639.900401494078, 6993.055937448583, 7209.819754506179, 7485.0, 7649.732259370895, 8283.875741863998, 8289.83420331602, 8314.62584183701, 8351.624176911931, 8356.498610083414, 7436.442266829901, 7639.57982310956, 7658.69365225407, 7710.575918324322, 7724.0731112266885, 7382.114146302478, 7392.149902761252, 7403.925960740324, 7529.322360987538, 7566.35685966677, 6513.185851768529, 6523.869522609616, 6533.281339052795, 6571.3100124488155, 6583.736650533925, 4829.468758436499, 4914.9017019658695, 5012.423571289349, 5020.47285035066, 5082.695038297714, 5125.923111153261, 5158.38099428071, 5175.042617115846, 5185.9222111669105, 5272.68363898152], "value": 4494.531270461907, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [310.96268447786264, 311.7973317343726, 326.1357402882363, 338.8932486266826, 341.28500790843174, 5095.987085101383, 5236.266922630825, 5261.447548007462, 5301.409577126861, 5330.706811125391, 5232.319798426209, 5233.842984710464, 5243.344581927259, 5248.5538729207965, 5254.605904557155, 4090.2228576570433, 4090.68876085011, 4095.5904409559103, 4100.482821356088, 4109.897252568695, 3832.9425058624142, 3890.3580909150096, 4101.343784994401, 4113.56807535209, 4123.154753275571, 4063.0045638818533, 4093.7339072960162, 4104.220198162355, 4129.0, 4129.834806607732, 4102.753834769908, 4110.486189226347, 4115.58844115589, 4116.320807066831, 4148.751074935513, 3447.1382154461385, 3678.70570354371, 3692.6861216796606, 3704.833282502267, 3712.554493460802, 5025.974870125662, 5053.216751403538, 5055.281204390488, 5057.140286151363, 5093.923591146136, 4695.859124226278, 4696.9530304696855, 4703.8353657622165, 4706.74112923789, 4707.7881495332795, 5122.974385128051, 5375.5162035417, 5405.59454541818, 5416.187571864212, 5446.319210098738, 3889.2804831106337, 3894.2990261753157, 3896.980515097435, 3899.8245078971513, 3914.256291304643, 5645.350784659776, 5678.403767604384, 5679.829605111812, 5698.7435565399655, 5713.685747283897, 4062.979685101556, 4063.6342729154517, 4075.7962101895378, 4086.8773936781945, 4090.795460226977, 3901.96098039016, 4104.856330028435, 4110.547839737626, 4124.618252885257, 4135.359019352005, 4670.602998745145, 4672.08894265615, 4673.252279635242, 4680.578747912704, 4688.976555117202, 5457.372402173722, 5486.807961721321, 5495.917561236584, 5497.697626630494, 5547.278853748987, 1292.3667402972476, 1302.6192236229626, 1399.9020068595305, 1414.9953532991485, 1423.1034448297507, 886.796036911514, 903.9198158200887, 904.2268860124499, 910.7358865928938, 918.5912269040347, 4797.18447863864, 4828.903421931542, 4856.198727210041, 4876.366072410564, 4901.264810278487, 5078.42568803672, 5095.286659867622, 5118.97440512795, 5131.2559678846355, 5141.691498510101, 4556.954430455719, 4828.5412885775795, 4847.369841920598, 4847.612191024709, 4853.878653033702, 2662.3876508402786, 2680.7855371570226, 2683.127983405401, 2706.188143556925, 2715.6469658944607, 5139.58883289336, 5185.403678576937, 5209.244659524347, 5291.656042357235, 5297.11307337502, 4732.13080833069, 5065.138926382524, 5074.771635276422, 5109.54378002275, 5133.897322053538, 5232.869178270574, 5238.842834714965, 5274.7098909560345, 5281.01961764346, 5340.118880384693, 4909.386326709161, 4949.975250123726, 4964.7836280111605, 4990.951900101019, 5150.965368679425, 4676.8129274828625, 4803.2314829628485, 4944.381952255968, 4956.653034287565, 4973.925391119208, 5153.484651534816, 5172.456892026434, 5216.634835561475, 5236.764345604533, 5243.606729495227, 3670.6145854684873, 3689.9815500922323, 3699.389600715959, 3700.7964561948274, 3824.8661296854475, 5490.5607551395, 5568.582356323373, 5606.495415412633, 5616.3260408749575, 5628.579710724428, 5382.273393091882, 5404.0272750905215, 5440.945590544202, 5446.727663616724, 5465.802505534307, 5344.786208551614, 5443.265159203457, 5447.400785913467, 5453.2365468833605, 5470.753816078366, 5310.6813591184655, 5329.7068661224, 5355.3305836770405, 5369.248305237192, 5369.328833895763, 5493.340799104133, 5504.954058728828, 5526.364468086222, 5538.556915446835, 5606.579506536945, 5117.181250999822, 5365.651232669864, 5499.642523235977, 5501.422350653123, 5526.143447765603], "value": 4050.5973574565196, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [670.755174361358, 767.0948281028386, 815.3151352863594, 866.4757821517985, 872.5506364222431, 14207.218602976845, 15033.0, 15033.473828416008, 15043.323050462728, 15064.096154230754, 14322.498712545063, 14361.569152925404, 14364.78452823206, 14371.64070898228, 14391.352389142488, 678.3589507915023, 784.9607519624025, 805.730080423059, 812.8333691593209, 812.910579836219, 822.3667775812621, 825.405707890318, 829.4194064155085, 829.7261903571837, 830.2403300979596, 863.4171933944593, 867.0159369116067, 867.1848462445281, 867.319154463746, 867.5315329721942, 715.4848509073458, 850.0309647002422, 866.4151697604092, 867.249828898005, 879.705298724928, 640.9711562979677, 642.5116911147561, 642.6208536963214, 642.881067002606, 643.3373625166087, 14285.285735713172, 14322.069065510708, 14324.068935519157, 14342.42630294787, 14415.495457659037, 13248.86751132486, 13702.0, 13763.38064787087, 13773.0, 13816.101953372998, 11951.342676152895, 12159.756804863942, 12218.61736961293, 12471.189372690746, 12560.309182994932, 4432.0, 4509.368688383629, 4536.637069034469, 4651.953480465186, 4667.0, 57687.13469297964, 58270.70864645692, 59774.0, 60316.09525857116, 60342.69828650873, 10069.295149339476, 10164.542595583069, 10632.30889992148, 11094.55621775136, 11252.212345135926, 19525.90237048834, 19526.902365488364, 19721.60556788856, 19744.0, 19982.20071197165, 7213.386862116674, 7289.198188199294, 7339.88990165148, 7351.932652838068, 7388.852222955511, 4392.3191905254735, 4484.439445069366, 4522.276435770326, 4547.658925580595, 4608.6082682971655, 3243.1567792374153, 3306.206510437478, 3306.3552607241836, 3308.7849289796086, 3311.6357200708385, 620.1442010026225, 625.2434554189482, 626.9905951410733, 634.2515831319088, 644.8226737647091, 658.3975662269066, 678.4063944048958, 703.5637904499141, 724.3697982755037, 745.6793578761185, 9476.763080923032, 9738.95130524357, 9786.217102631772, 9878.604855805832, 9887.209023278265, 592.8903152916724, 632.7437387857925, 667.739581563181, 707.5330282013846, 708.6385943169021, 4463.4420697412825, 4463.5759602838325, 4569.13186494565, 4572.497025327211, 4665.136949664323, 66555.66722166455, 67726.66136669285, 68300.6584967072, 69138.30861691419, 69144.3085569148, 60903.39096609066, 61553.0, 62476.68761656163, 63114.36885631177, 64211.0, 55683.0, 55913.0, 56335.43664563302, 56638.15042774362, 56725.14912276319, 8834.381593288408, 8851.203391694778, 8956.820863582821, 9004.32467564922, 9008.459492430475, 37511.2497750038, 37664.058398540255, 37675.2464950694, 37720.62279377281, 38051.0, 45771.31343029924, 49779.25331119964, 50155.74922125366, 50533.74733126311, 51332.48667513277, 9903.356281841658, 10196.745081372728, 10203.591856325664, 10432.895671043192, 10515.526801294112, 11317.830232546683, 12049.277043377426, 12618.747625047632, 12715.17351372195, 13554.86445135536, 13487.32563371847, 13529.86470135286, 13695.86304136946, 13724.65688357799, 13987.790183147465, 12437.0, 12580.0, 12678.0, 12766.553170638983, 12892.871071289543, 13006.674833129247, 13094.803577946528, 13203.273819939996, 13278.800817987545, 13530.458781648624, 11052.613158539412, 11203.0, 11269.267497612629, 11471.94264028708, 11483.36841473727, 11276.605318813803, 11333.206675532634, 11459.656210313704, 11517.481713322753, 11700.47347869331], "value": 6890.592848544278, "unit": "score"}], "name": "dromaeo_css", "value": 7033.572961867585, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 105.0, 109.0, 108.0, 110.0, 111.0, 109.0, 110.0, 108.0, 108.0], "value": 108.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 123.0, 154.0, 205.0, 125.0, 155.0, 157.0, 124.0, 180.0, 130.0], "value": 150.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 161.0, 160.0, 161.0, 161.0, 160.0, 161.0, 162.0, 160.0, 162.0], "value": 161.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 76.0, 77.0, 146.0, 75.0, 75.0, 75.0, 76.0, 139.0, 74.0], "value": 88.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 91.0, 147.0, 93.0, 91.0, 91.0, 116.0, 91.0, 91.0, 124.0], "value": 102.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 116.0, 116.0, 117.0, 116.0, 116.0, 116.0, 116.0, 117.0, 116.0], "value": 116.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 145.0, 146.0, 145.0, 146.0, 145.0, 145.0, 145.0, 146.0, 145.0], "value": 145.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 95.0, 95.0, 94.0, 95.0, 94.0, 95.0, 95.0, 95.0], "value": 94.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 54.0, 54.0, 53.0, 54.0, 54.0, 54.0, 120.0, 53.0, 52.0], "value": 60.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 59.0, 59.0, 60.0, 59.0, 60.0, 59.0, 60.0, 59.0, 60.0], "value": 59.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 78.0, 78.0, 78.0, 78.0, 78.0, 79.0, 78.0, 79.0, 80.0], "value": 78.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [121.0, 125.0, 122.0, 127.0, 122.0, 119.0, 120.0, 117.0, 206.0, 121.0], "value": 130.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 161.0, 169.0, 161.0, 158.0, 167.0, 165.0, 159.0, 160.0, 160.0], "value": 162.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 63.0, 64.0, 62.0, 63.0, 64.0, 64.0, 62.0, 62.0, 63.0], "value": 63.1, "unit": "score"}], "name": "kraken", "value": 1520.9000000000003, "alertThreshold": 2.0}]} 13:20:12 INFO - Return code: 0 13:20:13 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 13:20:13 ERROR - # TBPL SUCCESS # 13:20:13 INFO - Running post-action listener: _resource_record_post_action 13:20:13 INFO - ##### 13:20:13 INFO - ##### Finished run-tests step (success) 13:20:13 INFO - ##### 13:20:13 INFO - Running post-run listener: _resource_record_post_run 13:20:13 INFO - Total resource usage - Wall time: 1192s; CPU: 13.0%; Read bytes: 4096; Write bytes: 407703552; Read time: 8; Write time: 310560 13:20:13 INFO - TinderboxPrint: CPU usage
13.1% 13:20:13 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 13:20:13 INFO - TinderboxPrint: I/O write bytes / time
407,703,552 / 310,560 13:20:13 INFO - TinderboxPrint: CPU idle
8,261.6 (86.8%) 13:20:13 INFO - TinderboxPrint: CPU user
1,207.8 (12.7%) 13:20:13 INFO - TinderboxPrint: Swap in / out
0 / 0 13:20:13 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 72040448; Read time: 0; Write time: 49884 13:20:13 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 4096; Write bytes: 289546240; Read time: 8; Write time: 184860 13:20:13 INFO - Running post-run listener: _upload_blobber_files 13:20:13 INFO - Blob upload gear active. 13:20:13 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 13:20:13 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 13:20:13 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 13:20:13 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 13:20:14 INFO - (blobuploader) - INFO - Open directory for files ... 13:20:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 13:20:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:20:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:20:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 13:20:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:20:14 INFO - (blobuploader) - INFO - Done attempting. 13:20:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 13:20:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:20:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:20:15 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 13:20:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:20:15 INFO - (blobuploader) - INFO - Done attempting. 13:20:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 13:20:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:20:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:20:20 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 13:20:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:20:20 INFO - (blobuploader) - INFO - Done attempting. 13:20:20 INFO - (blobuploader) - INFO - Iteration through files over. 13:20:20 INFO - Return code: 0 13:20:20 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 13:20:20 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 13:20:20 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/28a81e3438aa96f335eb11289c1ed90d9fcaf6172f604aae08137de8e8a3bccc8284f34f9a4f7df18cabdb44b54acfed2a6a04d95beff12747fb19103e3d884b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/76073992a16416fe40943619088b1ca42a395e787e5fa0c0658101e7bddafcb869bcef5f87a7ecdf90ba215fb06f2d2ef090fb712e82cc3caf2567a7018f8d0d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d7d2b06069700448a35804eb2111ff88d4e32384f10d2a12b49365f6a43db0f3882cd52935f7e244607da75af78a77518d80291407aa67dc145fdc043a70ca54"} 13:20:20 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 13:20:20 INFO - Writing to file /builds/slave/test/properties/blobber_files 13:20:20 INFO - Contents: 13:20:20 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/28a81e3438aa96f335eb11289c1ed90d9fcaf6172f604aae08137de8e8a3bccc8284f34f9a4f7df18cabdb44b54acfed2a6a04d95beff12747fb19103e3d884b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/76073992a16416fe40943619088b1ca42a395e787e5fa0c0658101e7bddafcb869bcef5f87a7ecdf90ba215fb06f2d2ef090fb712e82cc3caf2567a7018f8d0d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d7d2b06069700448a35804eb2111ff88d4e32384f10d2a12b49365f6a43db0f3882cd52935f7e244607da75af78a77518d80291407aa67dc145fdc043a70ca54"} 13:20:20 INFO - Running post-run listener: copy_logs_to_upload_dir 13:20:20 INFO - Copying logs to upload dir... 13:20:20 INFO - mkdir: /builds/slave/test/build/upload/logs 13:20:20 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1245.130529 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 45 secs) (at 2016-05-18 13:20:20.195386) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-18 13:20:20.199032) ========= 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=d6bbd613908ccac0c6c9cb47000001f7-1463601238.620114-1974523070 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/28a81e3438aa96f335eb11289c1ed90d9fcaf6172f604aae08137de8e8a3bccc8284f34f9a4f7df18cabdb44b54acfed2a6a04d95beff12747fb19103e3d884b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/76073992a16416fe40943619088b1ca42a395e787e5fa0c0658101e7bddafcb869bcef5f87a7ecdf90ba215fb06f2d2ef090fb712e82cc3caf2567a7018f8d0d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d7d2b06069700448a35804eb2111ff88d4e32384f10d2a12b49365f6a43db0f3882cd52935f7e244607da75af78a77518d80291407aa67dc145fdc043a70ca54"} build_url:https://queue.taskcluster.net/v1/task/ARDPPwsRRDOz2R2e9Cnw-Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011111 build_url: 'https://queue.taskcluster.net/v1/task/ARDPPwsRRDOz2R2e9Cnw-Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/28a81e3438aa96f335eb11289c1ed90d9fcaf6172f604aae08137de8e8a3bccc8284f34f9a4f7df18cabdb44b54acfed2a6a04d95beff12747fb19103e3d884b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/76073992a16416fe40943619088b1ca42a395e787e5fa0c0658101e7bddafcb869bcef5f87a7ecdf90ba215fb06f2d2ef090fb712e82cc3caf2567a7018f8d0d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d7d2b06069700448a35804eb2111ff88d4e32384f10d2a12b49365f6a43db0f3882cd52935f7e244607da75af78a77518d80291407aa67dc145fdc043a70ca54"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-18 13:20:20.224703) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 13:20:20.225315) ========= 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=d6bbd613908ccac0c6c9cb47000001f7-1463601238.620114-1974523070 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004473 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 13:20:20.309868) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-18 13:20:20.310188) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-18 13:20:20.310577) ========= ========= Total master_lag: 0.18 =========