builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-029 starttime: 1463006972.91 results: success (0) buildid: 20160511133016 builduid: 46a5a9b0eb66406fa30856d2c9f2b2e7 revision: 1532576c0435896ff83448c0e1eb0d38e0c07f33 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-11 15:49:32.907509) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-11 15:49:32.907952) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-11 15:49:32.908233) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-11 15:49:32.917916) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 15:49:32.918206) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=0274524eba3bdaad075c2247000001d4-1463006419.130737-286939692 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-11 15:49:33-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 42.3M=0s 2016-05-11 15:49:33 (42.3 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.083597 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 15:49:33.009967) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 15:49:33.010294) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=0274524eba3bdaad075c2247000001d4-1463006419.130737-286939692 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.009601 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 15:49:33.057604) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 15:49:33.057943) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 1532576c0435896ff83448c0e1eb0d38e0c07f33 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 1532576c0435896ff83448c0e1eb0d38e0c07f33 --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=0274524eba3bdaad075c2247000001d4-1463006419.130737-286939692 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-11 15:49:33,408 truncating revision to first 12 chars 2016-05-11 15:49:33,408 Setting DEBUG logging. 2016-05-11 15:49:33,408 attempt 1/10 2016-05-11 15:49:33,408 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/1532576c0435?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-11 15:49:33,677 unpacking tar archive at: mozilla-inbound-1532576c0435/testing/mozharness/ program finished with exit code 0 elapsedTime=0.527985 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 15:49:33.599901) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-11 15:49:33.600233) ========= script_repo_revision: 1532576c0435896ff83448c0e1eb0d38e0c07f33 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-11 15:49:33.600646) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-11 15:49:33.600927) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-11 15:49:33.607242) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2016-05-11 15:49:33.607523) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 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', 'Mozilla-Inbound', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Inbound'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=0274524eba3bdaad075c2247000001d4-1463006419.130737-286939692 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:49:34 INFO - MultiFileLogger online at 20160511 15:49:34 in /builds/slave/test 15:49:34 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 15:49:34 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:49:34 INFO - {'append_to_log': False, 15:49:34 INFO - 'base_work_dir': '/builds/slave/test', 15:49:34 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 15:49:34 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:49:34 INFO - 'branch': 'Mozilla-Inbound', 15:49:34 INFO - 'buildbot_json_path': 'buildprops.json', 15:49:34 INFO - 'config_files': ('talos/linux_config.py',), 15:49:34 INFO - 'default_actions': ('clobber', 15:49:34 INFO - 'read-buildbot-config', 15:49:34 INFO - 'download-and-extract', 15:49:34 INFO - 'populate-webroot', 15:49:34 INFO - 'create-virtualenv', 15:49:34 INFO - 'install', 15:49:34 INFO - 'run-tests'), 15:49:34 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:49:34 INFO - 'download_minidump_stackwalk': True, 15:49:34 INFO - 'download_symbols': 'ondemand', 15:49:34 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:49:34 INFO - 'tooltool.py': '/tools/tooltool.py', 15:49:34 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:49:34 INFO - '/tools/misc-python/virtualenv.py')}, 15:49:34 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:49:34 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:49:34 INFO - 'installer_path': 'installer.exe', 15:49:34 INFO - 'log_level': 'info', 15:49:34 INFO - 'log_name': 'talos', 15:49:34 INFO - 'log_to_console': True, 15:49:34 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:49:34 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:49:34 INFO - 'opt_config_files': (), 15:49:34 INFO - 'pip_index': False, 15:49:34 INFO - 'sps_profile': False, 15:49:34 INFO - 'sps_profile_interval': 0, 15:49:34 INFO - 'suite': 'dromaeojs', 15:49:34 INFO - 'system_bits': '32', 15:49:34 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:49:34 INFO - 'title': 'talos-linux64-ix-029', 15:49:34 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:49:34 INFO - 'use_talos_json': True, 15:49:34 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:49:34 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:49:34 INFO - 'work_dir': 'build'} 15:49:34 INFO - ##### 15:49:34 INFO - ##### Running clobber step. 15:49:34 INFO - ##### 15:49:34 INFO - Running pre-action listener: _resource_record_pre_action 15:49:34 INFO - Running main action method: clobber 15:49:34 INFO - rmtree: /builds/slave/test/build 15:49:34 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:49:34 INFO - Running post-action listener: _resource_record_post_action 15:49:34 INFO - ##### 15:49:34 INFO - ##### Finished clobber step (success) 15:49:34 INFO - ##### 15:49:34 INFO - ##### 15:49:34 INFO - ##### Running read-buildbot-config step. 15:49:34 INFO - ##### 15:49:34 INFO - Running pre-action listener: _resource_record_pre_action 15:49:34 INFO - Running main action method: read_buildbot_config 15:49:34 INFO - Using buildbot properties: 15:49:34 INFO - { 15:49:34 INFO - "project": "", 15:49:34 INFO - "product": "firefox", 15:49:34 INFO - "script_repo_revision": "production", 15:49:34 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs", 15:49:34 INFO - "repository": "", 15:49:34 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs", 15:49:34 INFO - "buildid": "20160511133016", 15:49:34 INFO - "pgo_build": "True", 15:49:34 INFO - "basedir": "/builds/slave/test", 15:49:34 INFO - "buildnumber": 977, 15:49:34 INFO - "slavename": "talos-linux64-ix-029", 15:49:34 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 15:49:34 INFO - "platform": "ubuntu64_hw", 15:49:34 INFO - "scheduler": "tests-mozilla-inbound-linux64-pgo-talos", 15:49:34 INFO - "branch": "mozilla-inbound", 15:49:34 INFO - "repo_path": "integration/mozilla-inbound", 15:49:34 INFO - "revision": "1532576c0435896ff83448c0e1eb0d38e0c07f33", 15:49:34 INFO - "stage_platform": "linux64-pgo", 15:49:34 INFO - "builduid": "46a5a9b0eb66406fa30856d2c9f2b2e7", 15:49:34 INFO - "slavebuilddir": "test" 15:49:34 INFO - } 15:49:34 INFO - Found installer url https://queue.taskcluster.net/v1/task/E7u2GtFMRdK3hzvXNP-dBA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 15:49:34 INFO - Running post-action listener: _resource_record_post_action 15:49:34 INFO - ##### 15:49:34 INFO - ##### Finished read-buildbot-config step (success) 15:49:34 INFO - ##### 15:49:34 INFO - ##### 15:49:34 INFO - ##### Running download-and-extract step. 15:49:34 INFO - ##### 15:49:34 INFO - Running pre-action listener: _resource_record_pre_action 15:49:34 INFO - Running main action method: download_and_extract 15:49:34 INFO - mkdir: /builds/slave/test/build/tests 15:49:34 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')]} 15:49:34 INFO - https://queue.taskcluster.net/v1/task/E7u2GtFMRdK3hzvXNP-dBA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 15:49:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E7u2GtFMRdK3hzvXNP-dBA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 15:49:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E7u2GtFMRdK3hzvXNP-dBA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 15:49:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E7u2GtFMRdK3hzvXNP-dBA/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 15:49:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E7u2GtFMRdK3hzvXNP-dBA/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 15:49:35 INFO - Downloaded 1361 bytes. 15:49:35 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 15:49:35 INFO - Using the following test package requirements: 15:49:35 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 15:49:35 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 15:49:35 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 15:49:35 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 15:49:35 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 15:49:35 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 15:49:35 INFO - u'jsshell-linux-x86_64.zip'], 15:49:35 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 15:49:35 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 15:49:35 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 15:49:35 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 15:49:35 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 15:49:35 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 15:49:35 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 15:49:35 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 15:49:35 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 15:49:35 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 15:49:35 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 15:49:35 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 15:49:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:49:35 INFO - https://queue.taskcluster.net/v1/task/E7u2GtFMRdK3hzvXNP-dBA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 15:49:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E7u2GtFMRdK3hzvXNP-dBA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 15:49:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E7u2GtFMRdK3hzvXNP-dBA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 15:49:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E7u2GtFMRdK3hzvXNP-dBA/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 15:49:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E7u2GtFMRdK3hzvXNP-dBA/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 15:49:37 INFO - Downloaded 23902591 bytes. 15:49:37 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'] 15:49:37 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 15:49:38 INFO - Return code: 0 15:49:38 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 15:49:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:49:38 INFO - https://queue.taskcluster.net/v1/task/E7u2GtFMRdK3hzvXNP-dBA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 15:49:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E7u2GtFMRdK3hzvXNP-dBA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 15:49:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E7u2GtFMRdK3hzvXNP-dBA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 15:49:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E7u2GtFMRdK3hzvXNP-dBA/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 15:49:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E7u2GtFMRdK3hzvXNP-dBA/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 15:49:51 INFO - Downloaded 23902591 bytes. 15:49:51 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'] 15:49:51 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 15:49:53 INFO - Return code: 0 15:49:53 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')]} 15:49:53 INFO - https://queue.taskcluster.net/v1/task/E7u2GtFMRdK3hzvXNP-dBA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 15:49:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E7u2GtFMRdK3hzvXNP-dBA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 15:49:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E7u2GtFMRdK3hzvXNP-dBA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 15:49:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E7u2GtFMRdK3hzvXNP-dBA/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 15:49:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E7u2GtFMRdK3hzvXNP-dBA/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 15:49:54 INFO - Downloaded 10840239 bytes. 15:49:54 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'] 15:49:54 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 15:49:54 INFO - Return code: 0 15:49:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:49:54 INFO - https://queue.taskcluster.net/v1/task/E7u2GtFMRdK3hzvXNP-dBA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 15:49:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E7u2GtFMRdK3hzvXNP-dBA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 15:49:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E7u2GtFMRdK3hzvXNP-dBA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 15:49:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E7u2GtFMRdK3hzvXNP-dBA/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 15:49:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E7u2GtFMRdK3hzvXNP-dBA/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 15:49:55 INFO - Downloaded 58330979 bytes. 15:49:55 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/E7u2GtFMRdK3hzvXNP-dBA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 15:49:55 INFO - mkdir: /builds/slave/test/properties 15:49:55 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:49:55 INFO - Writing to file /builds/slave/test/properties/build_url 15:49:55 INFO - Contents: 15:49:55 INFO - build_url:https://queue.taskcluster.net/v1/task/E7u2GtFMRdK3hzvXNP-dBA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 15:49:57 INFO - Running post-action listener: _resource_record_post_action 15:49:57 INFO - Running post-action listener: set_extra_try_arguments 15:49:57 INFO - ##### 15:49:57 INFO - ##### Finished download-and-extract step (success) 15:49:57 INFO - ##### 15:49:57 INFO - ##### 15:49:57 INFO - ##### Running populate-webroot step. 15:49:57 INFO - ##### 15:49:57 INFO - Running pre-action listener: _resource_record_pre_action 15:49:57 INFO - Running main action method: populate_webroot 15:49:57 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:49:57 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:49:57 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:49:57 INFO - '--tppagecycles', 15:49:57 INFO - '7'], 15:49:57 INFO - 'tests': ['tsvgm']}}, 15:49:57 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:49:57 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:49:57 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:49:57 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:49:57 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:49:57 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:49:57 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:49:57 INFO - 'talos_options': ['--disable-e10s'], 15:49:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:49:57 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:49:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:49:57 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:49:57 INFO - 'talos_options': ['--disable-e10s'], 15:49:57 INFO - 'tests': ['damp', 'tps']}, 15:49:57 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:49:57 INFO - 'tests': ['damp', 'tps']}, 15:49:57 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:49:57 INFO - 'tests': ['dromaeo_dom']}, 15:49:57 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:49:57 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:49:57 INFO - 'tests': ['a11yr', 15:49:57 INFO - 'ts_paint', 15:49:57 INFO - 'tpaint', 15:49:57 INFO - 'sessionrestore', 15:49:57 INFO - 'sessionrestore_no_auto_restore', 15:49:57 INFO - 'tabpaint']}, 15:49:57 INFO - 'other-e10s': {'tests': ['a11yr', 15:49:57 INFO - 'ts_paint', 15:49:57 INFO - 'tpaint', 15:49:57 INFO - 'sessionrestore', 15:49:57 INFO - 'sessionrestore_no_auto_restore', 15:49:57 INFO - 'tabpaint']}, 15:49:57 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:49:57 INFO - 'ts_paint', 15:49:57 INFO - 'tpaint', 15:49:57 INFO - 'sessionrestore', 15:49:57 INFO - 'sessionrestore_no_auto_restore', 15:49:57 INFO - 'tabpaint']}, 15:49:57 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:49:57 INFO - 'ts_paint', 15:49:57 INFO - 'tpaint', 15:49:57 INFO - 'sessionrestore', 15:49:57 INFO - 'sessionrestore_no_auto_restore', 15:49:57 INFO - 'tabpaint']}, 15:49:57 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:49:57 INFO - 'tests': ['a11yr', 15:49:57 INFO - 'ts_paint', 15:49:57 INFO - 'tpaint', 15:49:57 INFO - 'sessionrestore', 15:49:57 INFO - 'sessionrestore_no_auto_restore', 15:49:57 INFO - 'tabpaint']}, 15:49:57 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:49:57 INFO - 'tests': ['a11yr', 15:49:57 INFO - 'ts_paint', 15:49:57 INFO - 'tpaint', 15:49:57 INFO - 'sessionrestore', 15:49:57 INFO - 'sessionrestore_no_auto_restore', 15:49:57 INFO - 'tabpaint']}, 15:49:57 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:49:57 INFO - 'tests': ['tsvgx', 15:49:57 INFO - 'tsvgr_opacity', 15:49:57 INFO - 'tart', 15:49:57 INFO - 'tscrollx', 15:49:57 INFO - 'cart']}, 15:49:57 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:49:57 INFO - 'tsvgr_opacity', 15:49:57 INFO - 'tart', 15:49:57 INFO - 'tscrollx', 15:49:57 INFO - 'cart']}, 15:49:57 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:49:57 INFO - 'talos_options': ['--disable-e10s'], 15:49:57 INFO - 'tests': ['tp5o']}, 15:49:57 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:49:57 INFO - 'tests': ['tp5o']}, 15:49:57 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:49:57 INFO - 'talos_options': ['--disable-e10s', 15:49:57 INFO - '--xperf_path', 15:49:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:49:57 INFO - 'tests': ['tp5n']}, 15:49:57 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:49:57 INFO - 'talos_options': ['--xperf_path', 15:49:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:49:57 INFO - 'tests': ['tp5n']}}, 15:49:57 INFO - 'talos.zip': {'path': '', 15:49:57 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:49:57 INFO - Running post-action listener: _resource_record_post_action 15:49:57 INFO - ##### 15:49:57 INFO - ##### Finished populate-webroot step (success) 15:49:57 INFO - ##### 15:49:57 INFO - ##### 15:49:57 INFO - ##### Running create-virtualenv step. 15:49:57 INFO - ##### 15:49:57 INFO - Running pre-action listener: _resource_record_pre_action 15:49:57 INFO - Running main action method: create_virtualenv 15:49:57 INFO - Creating virtualenv /builds/slave/test/build/venv 15:49:57 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 15:49:57 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:49:57 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:49:57 INFO - Using real prefix '/usr' 15:49:57 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:49:58 INFO - Installing distribute.............................................................................................................................................................................................done. 15:49:59 INFO - Installing pip.................done. 15:49:59 INFO - Return code: 0 15:49:59 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:49:59 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')]} 15:49:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:49:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:49:59 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')]} 15:49:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:49:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:49:59 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 0x7f5175c3df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26028a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26cd770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x275c9c0>, '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 0x275d8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x275dd50>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '0274524eba3bdaad075c2247000001d4-1463006419.130737-286939692', '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 15:49:59 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 15:49:59 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 15:49:59 INFO - Using env: {'DISPLAY': ':0', 15:49:59 INFO - 'HOME': '/home/cltbld', 15:49:59 INFO - 'LANG': 'en_US.UTF-8', 15:49:59 INFO - 'LANGUAGE': 'en_US:en', 15:49:59 INFO - 'LOGNAME': 'cltbld', 15:49:59 INFO - 'MAIL': '/var/mail/cltbld', 15:49:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:49:59 INFO - 'MOZ_NO_REMOTE': '1', 15:49:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:49:59 INFO - 'NO_EM_RESTART': '1', 15:49:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:49:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:49:59 INFO - 'PWD': '/builds/slave/test', 15:49:59 INFO - 'SHELL': '/bin/bash', 15:49:59 INFO - 'SHLVL': '1', 15:49:59 INFO - 'TERM': 'linux', 15:49:59 INFO - 'TMOUT': '86400', 15:49:59 INFO - 'USER': 'cltbld', 15:49:59 INFO - 'XDG_SESSION_COOKIE': '0274524eba3bdaad075c2247000001d4-1463006419.130737-286939692', 15:49:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:49:59 INFO - '_': '/tools/buildbot/bin/python'} 15:50:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:50:00 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 15:50:00 INFO - Cleaning up... 15:50:00 INFO - Return code: 0 15:50:00 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 15:50: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')]} 15:50:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:50:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:50: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')]} 15:50:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:50:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:50: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', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f5175c3df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26028a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26cd770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x275c9c0>, '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 0x275d8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x275dd50>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '0274524eba3bdaad075c2247000001d4-1463006419.130737-286939692', '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 15:50: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', 'psutil>=0.7.1'] in /builds/slave/test/build 15:50: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 psutil>=0.7.1 15:50:00 INFO - Using env: {'DISPLAY': ':0', 15:50:00 INFO - 'HOME': '/home/cltbld', 15:50:00 INFO - 'LANG': 'en_US.UTF-8', 15:50:00 INFO - 'LANGUAGE': 'en_US:en', 15:50:00 INFO - 'LOGNAME': 'cltbld', 15:50:00 INFO - 'MAIL': '/var/mail/cltbld', 15:50:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:50:00 INFO - 'MOZ_NO_REMOTE': '1', 15:50:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:50:00 INFO - 'NO_EM_RESTART': '1', 15:50:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:50:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:50:00 INFO - 'PWD': '/builds/slave/test', 15:50:00 INFO - 'SHELL': '/bin/bash', 15:50:00 INFO - 'SHLVL': '1', 15:50:00 INFO - 'TERM': 'linux', 15:50:00 INFO - 'TMOUT': '86400', 15:50:00 INFO - 'USER': 'cltbld', 15:50:00 INFO - 'XDG_SESSION_COOKIE': '0274524eba3bdaad075c2247000001d4-1463006419.130737-286939692', 15:50:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:50:00 INFO - '_': '/tools/buildbot/bin/python'} 15:50:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:50:00 INFO - Downloading/unpacking psutil>=0.7.1 15:50: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 15:50: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 15:50: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 15:50: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 15:50: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 15:50: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 15:50:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:50:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:50:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:50:02 INFO - Installing collected packages: psutil 15:50:02 INFO - Running setup.py install for psutil 15:50:02 INFO - building 'psutil._psutil_linux' extension 15:50:02 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 15:50:02 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 15:50:02 INFO - building 'psutil._psutil_posix' extension 15:50:02 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 15:50:02 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 15:50:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:50:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:50:02 INFO - Successfully installed psutil 15:50:02 INFO - Cleaning up... 15:50:02 INFO - Return code: 0 15:50:02 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 15:50: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')]} 15:50:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:50:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:50: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')]} 15:50:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:50:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:50: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', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f5175c3df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26028a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26cd770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x275c9c0>, '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 0x275d8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x275dd50>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '0274524eba3bdaad075c2247000001d4-1463006419.130737-286939692', '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 15:50: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', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 15:50: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 mozsystemmonitor==0.0.0 15:50:02 INFO - Using env: {'DISPLAY': ':0', 15:50:02 INFO - 'HOME': '/home/cltbld', 15:50:02 INFO - 'LANG': 'en_US.UTF-8', 15:50:02 INFO - 'LANGUAGE': 'en_US:en', 15:50:02 INFO - 'LOGNAME': 'cltbld', 15:50:02 INFO - 'MAIL': '/var/mail/cltbld', 15:50:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:50:02 INFO - 'MOZ_NO_REMOTE': '1', 15:50:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:50:02 INFO - 'NO_EM_RESTART': '1', 15:50:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:50:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:50:02 INFO - 'PWD': '/builds/slave/test', 15:50:02 INFO - 'SHELL': '/bin/bash', 15:50:02 INFO - 'SHLVL': '1', 15:50:02 INFO - 'TERM': 'linux', 15:50:02 INFO - 'TMOUT': '86400', 15:50:02 INFO - 'USER': 'cltbld', 15:50:02 INFO - 'XDG_SESSION_COOKIE': '0274524eba3bdaad075c2247000001d4-1463006419.130737-286939692', 15:50:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:50:02 INFO - '_': '/tools/buildbot/bin/python'} 15:50:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:50:02 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 15:50: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 15:50: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 15:50: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 15:50: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 15:50: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 15:50: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 15:50:04 INFO - Downloading mozsystemmonitor-0.0.tar.gz 15:50:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:50:04 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 15:50:04 INFO - Installing collected packages: mozsystemmonitor 15:50:04 INFO - Running setup.py install for mozsystemmonitor 15:50:05 INFO - Successfully installed mozsystemmonitor 15:50:05 INFO - Cleaning up... 15:50:05 INFO - Return code: 0 15:50:05 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:50:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:50:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:50:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:50:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:50:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:50:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:50:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f5175c3df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26028a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26cd770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x275c9c0>, '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 0x275d8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x275dd50>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '0274524eba3bdaad075c2247000001d4-1463006419.130737-286939692', '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 15:50:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 15:50:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 15:50:05 INFO - Using env: {'DISPLAY': ':0', 15:50:05 INFO - 'HOME': '/home/cltbld', 15:50:05 INFO - 'LANG': 'en_US.UTF-8', 15:50:05 INFO - 'LANGUAGE': 'en_US:en', 15:50:05 INFO - 'LOGNAME': 'cltbld', 15:50:05 INFO - 'MAIL': '/var/mail/cltbld', 15:50:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:50:05 INFO - 'MOZ_NO_REMOTE': '1', 15:50:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:50:05 INFO - 'NO_EM_RESTART': '1', 15:50:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:50:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:50:05 INFO - 'PWD': '/builds/slave/test', 15:50:05 INFO - 'SHELL': '/bin/bash', 15:50:05 INFO - 'SHLVL': '1', 15:50:05 INFO - 'TERM': 'linux', 15:50:05 INFO - 'TMOUT': '86400', 15:50:05 INFO - 'USER': 'cltbld', 15:50:05 INFO - 'XDG_SESSION_COOKIE': '0274524eba3bdaad075c2247000001d4-1463006419.130737-286939692', 15:50:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:50:05 INFO - '_': '/tools/buildbot/bin/python'} 15:50:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:50:05 INFO - Downloading/unpacking blobuploader==1.2.4 15:50: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 15:50: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 15:50: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 15:50: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 15:50: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 15:50: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 15:50:07 INFO - Downloading blobuploader-1.2.4.tar.gz 15:50:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:50:07 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:50:07 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 15:50:07 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 15:50:07 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 15:50:07 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 15:50:07 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 15:50:07 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 15:50:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:50:07 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:50:07 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 15:50:07 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 15:50:07 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 15:50:07 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 15:50:07 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 15:50:07 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 15:50:07 INFO - Downloading docopt-0.6.1.tar.gz 15:50:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:50:07 INFO - Installing collected packages: blobuploader, requests, docopt 15:50:07 INFO - Running setup.py install for blobuploader 15:50:07 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:50:07 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:50:07 INFO - Running setup.py install for requests 15:50:08 INFO - Running setup.py install for docopt 15:50:08 INFO - Successfully installed blobuploader requests docopt 15:50:08 INFO - Cleaning up... 15:50:08 INFO - Return code: 0 15:50:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:50:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:50:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:50:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:50:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:50:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:50:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:50:08 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 0x7f5175c3df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26028a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26cd770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x275c9c0>, '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 0x275d8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x275dd50>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '0274524eba3bdaad075c2247000001d4-1463006419.130737-286939692', '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 15:50:08 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 15:50:08 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 15:50:08 INFO - Using env: {'DISPLAY': ':0', 15:50:08 INFO - 'HOME': '/home/cltbld', 15:50:08 INFO - 'LANG': 'en_US.UTF-8', 15:50:08 INFO - 'LANGUAGE': 'en_US:en', 15:50:08 INFO - 'LOGNAME': 'cltbld', 15:50:08 INFO - 'MAIL': '/var/mail/cltbld', 15:50:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:50:08 INFO - 'MOZ_NO_REMOTE': '1', 15:50:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:50:08 INFO - 'NO_EM_RESTART': '1', 15:50:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:50:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:50:08 INFO - 'PWD': '/builds/slave/test', 15:50:08 INFO - 'SHELL': '/bin/bash', 15:50:08 INFO - 'SHLVL': '1', 15:50:08 INFO - 'TERM': 'linux', 15:50:08 INFO - 'TMOUT': '86400', 15:50:08 INFO - 'USER': 'cltbld', 15:50:08 INFO - 'XDG_SESSION_COOKIE': '0274524eba3bdaad075c2247000001d4-1463006419.130737-286939692', 15:50:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:50:08 INFO - '_': '/tools/buildbot/bin/python'} 15:50:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:50:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:50:08 INFO - Running setup.py (path:/tmp/pip-Mh4IfI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:50:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:50:08 INFO - Running setup.py (path:/tmp/pip-0Ilwxx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:50:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:50:08 INFO - Running setup.py (path:/tmp/pip-l_OsWV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:50:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:50:08 INFO - Running setup.py (path:/tmp/pip-zz_6Sx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:50:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:50:08 INFO - Running setup.py (path:/tmp/pip-wcvwLT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:50:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:50:08 INFO - Running setup.py (path:/tmp/pip-anzmUL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:50:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:50:08 INFO - Running setup.py (path:/tmp/pip-d4RAtS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:50:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:50:08 INFO - Running setup.py (path:/tmp/pip-fOPx0K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:50:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:50:09 INFO - Running setup.py (path:/tmp/pip-ZqIXDN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:50:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:50:09 INFO - Running setup.py (path:/tmp/pip-6jNweX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:50:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:50:09 INFO - Running setup.py (path:/tmp/pip-coBwB4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:50:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:50:09 INFO - Running setup.py (path:/tmp/pip-Rj5xn5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:50:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:50:09 INFO - Running setup.py (path:/tmp/pip-nx1B1J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:50:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:50:09 INFO - Running setup.py (path:/tmp/pip-lIl4Dn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:50:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:50:09 INFO - Running setup.py (path:/tmp/pip-aK8aA4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:50:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:50:09 INFO - Running setup.py (path:/tmp/pip-uTKZ6z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:50:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:50:09 INFO - Running setup.py (path:/tmp/pip-kg2PNW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:50:09 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:50:09 INFO - Running setup.py install for manifestparser 15:50:09 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:50:09 INFO - Running setup.py install for mozcrash 15:50:09 INFO - Running setup.py install for mozdebug 15:50:09 INFO - Running setup.py install for mozdevice 15:50:09 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:50:09 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:50:09 INFO - Running setup.py install for mozfile 15:50:09 INFO - Running setup.py install for mozhttpd 15:50:10 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:50:10 INFO - Running setup.py install for mozinfo 15:50:10 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:50:10 INFO - Running setup.py install for mozInstall 15:50:10 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:50:10 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:50:10 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:50:10 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:50:10 INFO - Running setup.py install for mozleak 15:50:10 INFO - Running setup.py install for mozlog 15:50:10 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:50:10 INFO - Running setup.py install for moznetwork 15:50:10 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:50:10 INFO - Running setup.py install for mozprocess 15:50:10 INFO - Running setup.py install for mozprofile 15:50:10 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:50:10 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:50:10 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:50:10 INFO - Running setup.py install for mozrunner 15:50:10 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:50:10 INFO - Running setup.py install for mozscreenshot 15:50:10 INFO - Running setup.py install for moztest 15:50:11 INFO - Running setup.py install for mozversion 15:50:11 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:50:11 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:50:11 INFO - Cleaning up... 15:50:11 INFO - Return code: 0 15:50:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:50:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:50:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:50:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:50:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:50:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:50:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:50:11 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 0x7f5175c3df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26028a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26cd770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x275c9c0>, '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 0x275d8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x275dd50>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '0274524eba3bdaad075c2247000001d4-1463006419.130737-286939692', '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 15:50:11 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 15:50:11 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 15:50:11 INFO - Using env: {'DISPLAY': ':0', 15:50:11 INFO - 'HOME': '/home/cltbld', 15:50:11 INFO - 'LANG': 'en_US.UTF-8', 15:50:11 INFO - 'LANGUAGE': 'en_US:en', 15:50:11 INFO - 'LOGNAME': 'cltbld', 15:50:11 INFO - 'MAIL': '/var/mail/cltbld', 15:50:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:50:11 INFO - 'MOZ_NO_REMOTE': '1', 15:50:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:50:11 INFO - 'NO_EM_RESTART': '1', 15:50:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:50:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:50:11 INFO - 'PWD': '/builds/slave/test', 15:50:11 INFO - 'SHELL': '/bin/bash', 15:50:11 INFO - 'SHLVL': '1', 15:50:11 INFO - 'TERM': 'linux', 15:50:11 INFO - 'TMOUT': '86400', 15:50:11 INFO - 'USER': 'cltbld', 15:50:11 INFO - 'XDG_SESSION_COOKIE': '0274524eba3bdaad075c2247000001d4-1463006419.130737-286939692', 15:50:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:50:11 INFO - '_': '/tools/buildbot/bin/python'} 15:50:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:50:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:50:11 INFO - Running setup.py (path:/tmp/pip-pZQYT_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:50:11 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)) 15:50:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:50:11 INFO - Running setup.py (path:/tmp/pip-P_xJG6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:50:11 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)) 15:50:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:50:11 INFO - Running setup.py (path:/tmp/pip-KtM4qE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:50:11 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)) 15:50:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:50:11 INFO - Running setup.py (path:/tmp/pip-ZFWKxY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:50:11 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)) 15:50:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:50:11 INFO - Running setup.py (path:/tmp/pip-k3sqhm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:50:11 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)) 15:50:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:50:11 INFO - Running setup.py (path:/tmp/pip-pRZ_cD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:50:11 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)) 15:50:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:50:11 INFO - Running setup.py (path:/tmp/pip-dJh1_u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:50:11 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)) 15:50:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:50:11 INFO - Running setup.py (path:/tmp/pip-KSOuKx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:50:11 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)) 15:50:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:50:11 INFO - Running setup.py (path:/tmp/pip-nupfdX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:50:11 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)) 15:50:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:50:11 INFO - Running setup.py (path:/tmp/pip-AunOqm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:50:11 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)) 15:50:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:50:11 INFO - Running setup.py (path:/tmp/pip-BRBUoH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:50:11 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)) 15:50:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:50:11 INFO - Running setup.py (path:/tmp/pip-tEJ9qf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:50:12 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)) 15:50:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:50:12 INFO - Running setup.py (path:/tmp/pip-vCkAoO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:50:12 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)) 15:50:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:50:12 INFO - Running setup.py (path:/tmp/pip-Nva6cI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:50:12 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)) 15:50:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:50:12 INFO - Running setup.py (path:/tmp/pip-8fBwJX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:50:12 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)) 15:50:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:50:12 INFO - Running setup.py (path:/tmp/pip-ct08EP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:50:12 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)) 15:50:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:50:12 INFO - Running setup.py (path:/tmp/pip-n2CP0e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:50:12 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)) 15:50:12 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)) 15:50:12 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)) 15:50:12 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)) 15:50:12 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)) 15:50:12 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)) 15:50:12 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:50:12 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 15:50:12 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 15:50:12 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 15:50:12 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 15:50:12 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 15:50:12 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 15:50:14 INFO - Downloading blessings-1.6.tar.gz 15:50:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:50:14 INFO - Installing collected packages: blessings 15:50:14 INFO - Running setup.py install for blessings 15:50:14 INFO - Successfully installed blessings 15:50:14 INFO - Cleaning up... 15:50:14 INFO - Return code: 0 15:50:14 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:50:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:50:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:50:14 INFO - Reading from file tmpfile_stdout 15:50:14 INFO - Current package versions: 15:50:14 INFO - argparse == 1.2.1 15:50:14 INFO - blessings == 1.6 15:50:14 INFO - blobuploader == 1.2.4 15:50:14 INFO - docopt == 0.6.1 15:50:14 INFO - manifestparser == 1.1 15:50:14 INFO - mozInstall == 1.12 15:50:14 INFO - mozcrash == 0.17 15:50:14 INFO - mozdebug == 0.1 15:50:14 INFO - mozdevice == 0.48 15:50:14 INFO - mozfile == 1.2 15:50:14 INFO - mozhttpd == 0.7 15:50:14 INFO - mozinfo == 0.9 15:50:14 INFO - mozleak == 0.1 15:50:14 INFO - mozlog == 3.2 15:50:14 INFO - moznetwork == 0.27 15:50:14 INFO - mozprocess == 0.22 15:50:14 INFO - mozprofile == 0.28 15:50:14 INFO - mozrunner == 6.11 15:50:14 INFO - mozscreenshot == 0.1 15:50:14 INFO - mozsystemmonitor == 0.0 15:50:14 INFO - moztest == 0.7 15:50:14 INFO - mozversion == 1.4 15:50:14 INFO - psutil == 3.1.1 15:50:14 INFO - requests == 1.2.3 15:50:14 INFO - wsgiref == 0.1.2 15:50:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:50:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:50:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:50:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:50:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:50:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:50:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:50:14 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 0x7f5175c3df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26028a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26cd770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x275c9c0>, '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 0x275d8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x275dd50>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '0274524eba3bdaad075c2247000001d4-1463006419.130737-286939692', '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 15:50:14 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 15:50:14 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 15:50:14 INFO - Using env: {'DISPLAY': ':0', 15:50:14 INFO - 'HOME': '/home/cltbld', 15:50:14 INFO - 'LANG': 'en_US.UTF-8', 15:50:14 INFO - 'LANGUAGE': 'en_US:en', 15:50:14 INFO - 'LOGNAME': 'cltbld', 15:50:14 INFO - 'MAIL': '/var/mail/cltbld', 15:50:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:50:14 INFO - 'MOZ_NO_REMOTE': '1', 15:50:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:50:14 INFO - 'NO_EM_RESTART': '1', 15:50:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:50:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:50:14 INFO - 'PWD': '/builds/slave/test', 15:50:14 INFO - 'SHELL': '/bin/bash', 15:50:14 INFO - 'SHLVL': '1', 15:50:14 INFO - 'TERM': 'linux', 15:50:14 INFO - 'TMOUT': '86400', 15:50:14 INFO - 'USER': 'cltbld', 15:50:14 INFO - 'XDG_SESSION_COOKIE': '0274524eba3bdaad075c2247000001d4-1463006419.130737-286939692', 15:50:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:50:14 INFO - '_': '/tools/buildbot/bin/python'} 15:50:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:50:14 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)) 15:50:14 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)) 15:50:14 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)) 15:50:14 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)) 15:50:14 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)) 15:50:14 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)) 15:50:14 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)) 15:50:14 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)) 15:50:14 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)) 15:50:14 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)) 15:50:14 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)) 15:50:14 INFO - Cleaning up... 15:50:14 INFO - Return code: 0 15:50:14 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:50:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:50:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:50:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:50:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:50:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:50:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:50:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f5175c3df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26028a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26cd770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x275c9c0>, '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 0x275d8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x275dd50>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '0274524eba3bdaad075c2247000001d4-1463006419.130737-286939692', '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 15:50:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 15:50:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 15:50:14 INFO - Using env: {'DISPLAY': ':0', 15:50:14 INFO - 'HOME': '/home/cltbld', 15:50:14 INFO - 'LANG': 'en_US.UTF-8', 15:50:14 INFO - 'LANGUAGE': 'en_US:en', 15:50:14 INFO - 'LOGNAME': 'cltbld', 15:50:14 INFO - 'MAIL': '/var/mail/cltbld', 15:50:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:50:14 INFO - 'MOZ_NO_REMOTE': '1', 15:50:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:50:14 INFO - 'NO_EM_RESTART': '1', 15:50:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:50:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:50:14 INFO - 'PWD': '/builds/slave/test', 15:50:14 INFO - 'SHELL': '/bin/bash', 15:50:14 INFO - 'SHLVL': '1', 15:50:14 INFO - 'TERM': 'linux', 15:50:14 INFO - 'TMOUT': '86400', 15:50:14 INFO - 'USER': 'cltbld', 15:50:14 INFO - 'XDG_SESSION_COOKIE': '0274524eba3bdaad075c2247000001d4-1463006419.130737-286939692', 15:50:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:50:14 INFO - '_': '/tools/buildbot/bin/python'} 15:50:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:50:14 INFO - Downloading/unpacking jsonschema 15:50:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:50:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:50:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:50:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:50:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:50:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:50:16 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:50:16 INFO - Downloading/unpacking functools32 (from jsonschema) 15:50: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 15:50: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 15:50: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 15:50: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 15:50: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 15:50: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 15:50:17 INFO - Downloading functools32-3.2.3-2.tar.gz 15:50:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:50:17 INFO - warning: no files found matching '*.txt' 15:50:17 INFO - no previously-included directories found matching 'build' 15:50:17 INFO - no previously-included directories found matching 'dist' 15:50:17 INFO - no previously-included directories found matching '.git*' 15:50:17 INFO - Installing collected packages: jsonschema, functools32 15:50:17 INFO - Running setup.py install for functools32 15:50:17 INFO - warning: no files found matching '*.txt' 15:50:17 INFO - no previously-included directories found matching 'build' 15:50:17 INFO - no previously-included directories found matching 'dist' 15:50:17 INFO - no previously-included directories found matching '.git*' 15:50:17 INFO - Successfully installed jsonschema functools32 15:50:17 INFO - Cleaning up... 15:50:17 INFO - Return code: 0 15:50:17 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 15:50:17 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')]} 15:50:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:50:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:50:17 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')]} 15:50:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:50:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:50:17 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 0x7f5175c3df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26028a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26cd770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x275c9c0>, '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 0x275d8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x275dd50>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '0274524eba3bdaad075c2247000001d4-1463006419.130737-286939692', '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 15:50:17 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 15:50:17 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 15:50:17 INFO - Using env: {'DISPLAY': ':0', 15:50:17 INFO - 'HOME': '/home/cltbld', 15:50:17 INFO - 'LANG': 'en_US.UTF-8', 15:50:17 INFO - 'LANGUAGE': 'en_US:en', 15:50:17 INFO - 'LOGNAME': 'cltbld', 15:50:17 INFO - 'MAIL': '/var/mail/cltbld', 15:50:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:50:17 INFO - 'MOZ_NO_REMOTE': '1', 15:50:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:50:17 INFO - 'NO_EM_RESTART': '1', 15:50:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:50:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:50:17 INFO - 'PWD': '/builds/slave/test', 15:50:17 INFO - 'SHELL': '/bin/bash', 15:50:17 INFO - 'SHLVL': '1', 15:50:17 INFO - 'TERM': 'linux', 15:50:17 INFO - 'TMOUT': '86400', 15:50:17 INFO - 'USER': 'cltbld', 15:50:17 INFO - 'XDG_SESSION_COOKIE': '0274524eba3bdaad075c2247000001d4-1463006419.130737-286939692', 15:50:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:50:17 INFO - '_': '/tools/buildbot/bin/python'} 15:50:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:50:17 INFO - Downloading/unpacking flake8 15:50:17 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 15:50:17 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 15:50:17 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 15:50:17 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 15:50:17 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 15:50:17 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 15:50:19 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 15:50:19 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 15:50:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:50:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:50:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:50:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:50:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:50:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:50:19 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 15:50:19 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 15:50:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:50:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:50:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:50:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:50:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:50:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:50:19 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 15:50:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:50:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:50:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:50:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:50:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:50:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:50:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 15:50:20 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:50:20 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:50:20 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:50:20 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:50:20 INFO - no previously-included directories found matching 'docs/_build' 15:50:20 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 15:50:20 INFO - Running setup.py install for pep8 15:50:20 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:50:20 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:50:20 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:50:20 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:50:20 INFO - no previously-included directories found matching 'docs/_build' 15:50:20 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 15:50:20 INFO - Successfully installed flake8 mccabe pyflakes pep8 15:50:20 INFO - Cleaning up... 15:50:20 INFO - Return code: 0 15:50:20 INFO - Running post-action listener: _resource_record_post_action 15:50:20 INFO - Running post-action listener: _start_resource_monitoring 15:50:20 INFO - Starting resource monitoring. 15:50:20 INFO - ##### 15:50:20 INFO - ##### Finished create-virtualenv step (success) 15:50:20 INFO - ##### 15:50:20 INFO - ##### 15:50:20 INFO - ##### Running install step. 15:50:20 INFO - ##### 15:50:20 INFO - Running pre-action listener: _resource_record_pre_action 15:50:20 INFO - Running main action method: install 15:50:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:50:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:50:20 INFO - Reading from file tmpfile_stdout 15:50:20 INFO - Detecting whether we're running mozinstall >=1.0... 15:50:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:50:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:50:20 INFO - Reading from file tmpfile_stdout 15:50:20 INFO - Output received: 15:50:20 INFO - Usage: mozinstall [options] installer 15:50:20 INFO - Options: 15:50:20 INFO - -h, --help show this help message and exit 15:50:20 INFO - -d DEST, --destination=DEST 15:50:20 INFO - Directory to install application into. [default: 15:50:20 INFO - "/builds/slave/test"] 15:50:20 INFO - --app=APP Application being installed. [default: firefox] 15:50:20 INFO - mkdir: /builds/slave/test/build/application 15:50: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'] 15:50: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 15:50:37 INFO - Reading from file tmpfile_stdout 15:50:37 INFO - Output received: 15:50:37 INFO - /builds/slave/test/build/application/firefox/firefox 15:50:37 INFO - Running post-action listener: _resource_record_post_action 15:50:37 INFO - ##### 15:50:37 INFO - ##### Finished install step (success) 15:50:37 INFO - ##### 15:50:37 INFO - ##### 15:50:37 INFO - ##### Running run-tests step. 15:50:37 INFO - ##### 15:50:37 INFO - Running pre-action listener: _resource_record_pre_action 15:50:37 INFO - Running main action method: run_tests 15:50:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:50:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:50:37 INFO - Python 2.7.3 15:50:37 INFO - Return code: 0 15:50:37 INFO - grabbing minidump binary from tooltool 15:50: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')]} 15:50: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 0x275c9c0>, '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 0x275d8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x275dd50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:50: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 15:50: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 15:50: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 15:50:37 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:50:37 INFO - Return code: 0 15:50:37 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 15:50:37 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:50:37 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:50:37 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 15:50:37 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:50:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 15:50:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 15:50:38 INFO - Return code: 0 15:50:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/E7u2GtFMRdK3hzvXNP-dBA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-029', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 15:50:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Inbound --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/E7u2GtFMRdK3hzvXNP-dBA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-029 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 15:50:38 INFO - Using env: {'DISPLAY': ':0', 15:50:38 INFO - 'HOME': '/home/cltbld', 15:50:38 INFO - 'LANG': 'en_US.UTF-8', 15:50:38 INFO - 'LANGUAGE': 'en_US:en', 15:50:38 INFO - 'LOGNAME': 'cltbld', 15:50:38 INFO - 'MAIL': '/var/mail/cltbld', 15:50:38 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:50:38 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:50:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:50:38 INFO - 'MOZ_NO_REMOTE': '1', 15:50:38 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:50:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:50:38 INFO - 'NO_EM_RESTART': '1', 15:50:38 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:50:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:50:38 INFO - 'PWD': '/builds/slave/test', 15:50:38 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:50:38 INFO - 'SHELL': '/bin/bash', 15:50:38 INFO - 'SHLVL': '1', 15:50:38 INFO - 'TERM': 'linux', 15:50:38 INFO - 'TMOUT': '86400', 15:50:38 INFO - 'USER': 'cltbld', 15:50:38 INFO - 'XDG_SESSION_COOKIE': '0274524eba3bdaad075c2247000001d4-1463006419.130737-286939692', 15:50:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:50:38 INFO - '_': '/tools/buildbot/bin/python'} 15:50:38 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/E7u2GtFMRdK3hzvXNP-dBA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-029', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 15:50:38 INFO - mozversion application_buildid: 20160511133016 15:50:38 INFO - mozversion application_changeset: 1532576c0435896ff83448c0e1eb0d38e0c07f33 15:50:38 INFO - mozversion application_display_name: Nightly 15:50:38 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:50:38 INFO - mozversion application_name: Firefox 15:50:38 INFO - mozversion application_remotingname: firefox 15:50:38 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 15:50:38 INFO - mozversion application_vendor: Mozilla 15:50:38 INFO - mozversion application_version: 49.0a1 15:50:38 INFO - mozversion platform_buildid: 20160511133016 15:50:38 INFO - mozversion platform_changeset: 1532576c0435896ff83448c0e1eb0d38e0c07f33 15:50:38 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 15:50:38 INFO - mozversion platform_version: 49.0a1 15:50:38 INFO - using testdate: 1463007038 15:50:38 INFO - actual date: 1463007038 15:50:38 INFO - starting webserver on 'localhost:58776' 15:50:38 INFO - SUITE-START | Running 2 tests 15:50:38 INFO - TEST-START | dromaeo_css 15:50:38 INFO - operating with platform_type : linux_ 15:50:38 INFO - Initialising browser for dromaeo_css test... 15:50:38 INFO - TEST-INFO | started process 11775 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp9nErGe/profile http://localhost:58776/getInfo.html) 15:50:41 INFO - PROCESS | 11775 | __metrics Screen width/height:1600/1200 15:50:41 INFO - PROCESS | 11775 | colorDepth:24 15:50:41 INFO - PROCESS | 11775 | Browser inner width/height: 1024/697 15:50:41 INFO - PROCESS | 11775 | __metrics 15:50:42 ERROR - PROCESS | 11775 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 15:50:42 INFO - TEST-INFO | 11775: exit 0 15:50:42 INFO - Browser initialized. 15:50:42 INFO - Running cycle 1/1 for dromaeo_css test... 15:50:42 INFO - TEST-INFO | started process 11855 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp9nErGe/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:50:54 INFO - PROCESS | 11855 | RSS: Main: 151187456 15:50:54 INFO - PROCESS | 11855 | 15:51:45 INFO - PROCESS | 11855 | 1463007105170 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] 15:53:52 INFO - PROCESS | 11855 | Cycle 1(1): loaded http://localhost:58776/tests/dromaeo/cssquery-dojo.html (next: http://localhost:58776/tests/dromaeo/cssquery-ext.html) 15:53:53 INFO - PROCESS | 11855 | RSS: Main: 252338176 15:53:53 INFO - PROCESS | 11855 | 15:56:56 INFO - PROCESS | 11855 | Cycle 1(1): loaded http://localhost:58776/tests/dromaeo/cssquery-ext.html (next: http://localhost:58776/tests/dromaeo/cssquery-jquery.html) 15:56:56 INFO - PROCESS | 11855 | RSS: Main: 234876928 15:56:56 INFO - PROCESS | 11855 | 15:59:58 INFO - PROCESS | 11855 | Cycle 1(1): loaded http://localhost:58776/tests/dromaeo/cssquery-jquery.html (next: http://localhost:58776/tests/dromaeo/cssquery-mootools.html) 15:59:59 INFO - PROCESS | 11855 | RSS: Main: 237625344 15:59:59 INFO - PROCESS | 11855 | 16:03:01 INFO - PROCESS | 11855 | Cycle 1(1): loaded http://localhost:58776/tests/dromaeo/cssquery-mootools.html (next: http://localhost:58776/tests/dromaeo/cssquery-prototype.html) 16:03:01 INFO - PROCESS | 11855 | RSS: Main: 235597824 16:03:01 INFO - PROCESS | 11855 | 16:06:04 INFO - PROCESS | 11855 | Cycle 1(1): loaded http://localhost:58776/tests/dromaeo/cssquery-prototype.html (next: http://localhost:58776/tests/dromaeo/cssquery-yui.html) 16:06:04 INFO - PROCESS | 11855 | RSS: Main: 219713536 16:06:04 INFO - PROCESS | 11855 | 16:09:07 INFO - PROCESS | 11855 | Cycle 1(1): loaded http://localhost:58776/tests/dromaeo/cssquery-yui.html (next: http://localhost:58776/tests/dromaeo/cssquery-dojo.html) 16:09:07 INFO - PROCESS | 11855 | RSS: Main: 269398016 16:09:07 INFO - PROCESS | 11855 | 16:09:07 INFO - PROCESS | 11855 | __start_tp_report 16:09:07 INFO - PROCESS | 11855 | _x_x_mozilla_page_load 16:09:07 INFO - PROCESS | 11855 | _x_x_mozilla_page_load_details 16:09:07 INFO - PROCESS | 11855 | |i|pagename|runs| 16:09:07 INFO - PROCESS | 11855 | |0;dojo.html;3074.0470454159204;3080.2453398917255;3107.5494053362254;3114.019083988545;3128.0459459864733;5101.923471147936;5183.429822719498;5206.062908676437;5278.0763366410865;5302.416734159239;5158.716270605115;5167.509086636764;5246.1868410396355;5262.605304602161;5363.954028964354;4669.276262179367;4676.766161691919;4680.344751734769;4742.644301677371;4853.563179313861;4759.952400475986;4765.785539650707;4779.283107533863;4812.109759694467;4821.373221481203;4632.907341853161;4721.976390118062;4769.90460190796;4788.928166077511;4791.808327666924;4753.524647535253;4769.356136921506;4785.473597904228;4791.089692958361;4816;4526.841560545365;4760.357351757503;4782.402199725035;4786.16242157621;4808.3027960945465;5064.164412871872;5113.105206588869;5142.280080788655;5249.86875328116;5279.049771041211;5675.233843431125;5681.914771278434;5684.772609095631;5704.201411802351;5704.914426283609;5454.236406903036;5563.499285064323;5601.411851755588;5613.887722245532;5674.602777805555;5297.761600727977;5384.59615528833;5387.488188622114;5388.5419739321815;5418.431064738224;5874.942510348178;5876.294844618673;5882.02946513829;5882.02946513829;5892.381299963527;5374.677519348774;5431.83704488866;5501.779928802883;5592.965301419169;5628.802991895264;5599.832005039855;5609;5626.2967129108865;5629.4652008059165;5663.915041274384;5584.022796010682;5614.157876318585;5617.21359009739;5625.240592519957;5628.971855140698;5365.839024829262;5527.032769265363;5574.386817450159;5581.665100094007;5593.916091258634;4274.786260686953;4285.185814695198;4291.377750226262;4293.935590966138;4326.069894972596;3797.0886987123445;3823.904402389907;3831.0039389758863;3858.9035274118373;3862.208247309295;5183;5276.709780962007;5327.733613319396;5338.839834804962;5368;5676.205331253628;5703.800366987219;5708.486236238676;5717.771289148471;5721.799737009184;5105.259737338065;5347.545458635982;5347.545458635982;5354.330708661418;5415.268938693304;4749.62003039756;4778.546038126373;4780.235162374003;4780.784864681098;4785.808567657325;5524.861878453071;5857.062869940874;5878.382769809194;5879.323877754027;5881.147233651097;5437.972810135924;5628.240187574625;5676.631018983753;5690.943090569207;5716.08542633185;5820.156077368673;5830.067189249699;5830.2129212555765;5832.416758324133;5905.143754155538;5449.99175152598;5452.7546260417585;5567.610267281251;5568.359638641446;5570.610057296112;5431.8098866539485;5602.243697100839;5622.847316300067;5646.407127251579;5657.462541058593;5529.4470552944385;5550.972245138749;5551.777928882961;5562.805301814417;5579.2746942897165;5522;5524.4199359066715;5527.751251193787;5539.7230138492105;5550.639208451437;5176.559992400537;5286.920696189637;5288.497592728685;5326.97336513315;5509.00837849191;5490.396056433869;5493.587980901369;5515.669059856261;5549.833504994856;5556.194351819045;5328.600354973315;5332.253484512249;5339.652922560022;5372.946270537401;5379.946200538101 16:09:07 INFO - PROCESS | 11855 | |1;ext.html;571.788438277837;598.7156100852094;608.42503833877;615.8183335915905;623.5946634687452;32390.352192956158;43577.12845743083;43999.78000110003;44034.7798261009;44246.77876610612;44417.77791111048;45125.323120153225;45167.54832451674;45208.321875171896;45384;7710.845783084335;8594.441361311494;8759.474431534096;10193.694189174337;10471.162307015458;10107.797844043114;10265.897341026568;10302;10321.277510574262;10332.638357657483;10406.531706073245;10425.635102771404;10484.633037843678;10489.580416783358;10498.21263405244;7862.764117076496;10201.897981020169;10406.947965260126;10506.527206275736;10883.727906802314;10312.535935882903;10326.845097323547;10386.428746418944;10436.895631043668;10451.320664156805;40501.39247911284;40664.79667601643;40774.388384174265;40925.181496370205;41142.38286425676;3410.931781364359;3564.7326450516057;3927.371620540728;4157;4199.853005144805;31107.84446077778;31916.840415798004;31938.840305798556;31971.52042719361;32315.030549083564;4306.784660766981;4328.696991210617;4331.198728235287;4335.241332766753;4427.335899615051;311202;315377;315553;316283.9999999977;316669;57269.42730572641;57640.71179644075;57700.42299577034;57779.71110144423;58069.12896306559;36557.96793529091;37300.81349593234;37327.25345493129;38920.22159556793;39140.608593914265;14085.014049016676;14119.223442710749;14182.716345673029;14228.644283892985;14288;10044.748881278027;10061.094501494901;10071.395716257048;10093.132770437345;10118;19601.411957641292;19736.210551577777;19817.405477835688;19824.90087549553;19979.700304495444;6163.164944125663;7230.349268565749;7311.707531698778;7338.192798792129;7342.412606991428;1008.2790804574764;1098.818694915346;1109.2401704832216;1117.8211486162174;1165.621173118723;31741.365172696416;31755.52366714501;31783;32151.196220094684;32355.35289294201;919.839028170081;955.2262667239559;988.4365911430559;1013.1034034879185;1100.5927806711409;16407.67184656324;17183.914080429517;17301.913490432467;17324.480265592054;17414.216360263566;537834;542203;542840;545236;545436;57561.13658295129;58498;59077.40922590805;59092.40907590955;59140;1354.4717560151548;1368.4799776085224;1378.7311474262422;1385.49429458247;1402.1166665000985;29905.401891961603;32430.513542296412;33255.667443325256;33653.49519757255;35780.82109589435;55984;56501.717491413925;56737;56820.431795681514;56865;416000;426813;427803.8609806931;428016;432127;37585.81207093947;40020.79989600033;42878;43583;43677.344839826794;42444.36333454939;46422.53577464182;47192.7640361796;47841.760791197215;47992.28011579899;38328.61671383362;39310;40189.79905100456;40269.59730402658;40610;38994.80502597469;39970.82931653669;40287.79856100701;40751.59248407478;40955.38566921558;40453.595464044985;40463.797681012584;42314.788426057676;42796.786016069724;42857.35713964355;34201;35457.82271088731;36246.09384765296;36415.86268008713;37114.44328335023;27557.724422755517;28150.85924570364;28158.71841281561;28182.43635127245;28404.57393139063 16:09:07 INFO - PROCESS | 11855 | |2;jquery.html;389.4430963721878;393.2567447524179;397.2373043755992;398.4362127589461;411.1961116018183;14148.71702565948;17919.372821951234;17999.190036448374;19688.803111968875;19774;17691.380801671945;18416.631667366644;18787.15457804399;19011.61976760464;19047.523811904673;5544.77820887165;5635.802746903859;5758.7408566614395;5771.884562308751;5773.364929857712;4047.9797601011956;5582.553395728332;5722.656640601577;5769.509591684712;6062.302835173968;6300.369963003709;6391.872162556746;6401.359864013608;6411.9679401602925;6441.162648855642;6119.326874043852;6261.96869015652;6309.116723658692;6337.6197428154455;6400.839979000516;5520.558355331604;5524.502794748451;5525.944740552583;5533.197686335499;5537.224788529569;21559.676604850938;21838.454038649;22065.779342206533;22441.663375049386;22551.872406379778;2596.974195192888;2666.6666666666665;2682.2623778460884;3021.0159792969566;3084.737797287233;13544.45822167122;14039.368228429745;14189.43242270308;14417.495387661382;14499.927500362535;1852.5183452302363;2009.1963214714085;2015.7177995080701;2034.7965203479534;2035.5725297687504;79995.60002199952;80418.59790701067;80501;81521.18478815195;81569.9999999994;10030.247731420173;10351.378917264989;10417.114545263737;10492.370457772558;10667.466626668636;12404;13412.865871341357;14500.057496262709;14532.491362802251;14601.634959126106;7491.3632341251605;7573.697052117854;7608.961955190189;7708.344790692854;7721.150673426031;6727.831804204836;6751.156105486814;6991.720331186797;7124.679389427488;7158.427325813923;6434.131392261985;6487.967560162263;6543.574667646588;6561.540692151503;6575.671216439159;1892.8012558681417;1898.7246849206788;1911.2259534888392;1922.5097600111883;1943.9308380390728;944.4899754132691;1056.4664844253757;1089.841972913923;1133.877461313294;1134.3817619397448;18294.268229270947;18565.81434185668;18631.81368186301;18648;19017.619647607247;1004.1063453526222;1043;1043.796459690353;1064.5309472927465;1075.3762817565794;10532.526036328383;10982.231243812856;11017.889821101686;11042.779144417067;11050.668479945614;373289;376561;378315;464184;464594;89185;98297;98418;98441;99280;987.0474991633108;987.6345752071779;989.0850962859327;1019.3323373190572;1023.7594165371132;16466.176691165634;16771.832281677027;17028.659426811148;17104.144792760562;17446.825531744522;24947;36593.45109823302;37320.81339593285;37659.81170094132;39269;48456;49598.75200624118;51447.22829157641;51602;51689.741551292005;7883.448158628841;8158.796030099295;8262.256396924306;8304.128066552925;8533.27467165273;12436.129470936947;12469.750604987668;12795.93602031984;12832;13021.804672930102;10746.623868164577;11097.889021109686;11125.165612578927;11243.943780281046;11249;10860.67417977462;10866.293690910066;11079.224454288122;11095;11174.217804753589;9885.950570247103;10965.945170274097;11329.830052549383;11507.252028618113;11575.073994080622;8177.223163799429;8308.6676532938;8314.376421768271;8320.667173312999;8484.660613575636;9955.950220249142;11256.887431125584;11286.887131128584;11479.25384849982;11486.483108259981 16:09:07 INFO - PROCESS | 11855 | |3;mootools.html;1361.9591412257637;1498.6777842763806;1531.4946067797625;1677.949661510155;1820.2627935686044;7819.491733037348;8123.431359804808;8124.9187508124905;8328.292095171902;8379.162083791618;8151.062627797805;8204.220599043096;8264.586770661457;8304.792380190498;8322.833543329145;1881.6613009658256;2217.6451767717167;2248.85382450141;2262.8302877284186;2318.3856278086237;2353.752855950126;2359.6106642403984;2367.419982104378;2368.786809187173;2369.07606033647;2202.8678279303294;2236.6756820261053;2237.1834280487587;2238.6977758002704;2242.9439264018365;1962.5976674781707;2200.7799220078023;2230.475838178043;2253.785890340415;2267.9319620411416;2197.615417301966;2210.237468073512;2212.4026512841606;2214.247155966963;2251.4596496840804;8134.552599607019;8199.42604017713;8203.58982050895;8225.383096267804;8303.750887473385;4987.326710894019;5144.4083930348115;5185.988732197223;5192.714400707997;5218.530332270114;3433.4678124890434;3452.8446219920165;3538.327717733622;3562.536870206882;3575.8927232183078;1107.0755918807806;1127.4982632728438;1136.352279200856;1159.6868845411775;1159.8898104680043;11553.884461155365;11583.594574189863;11583.884161158448;11677.941610291979;11760.823587646191;4556.270996640522;4635.327877457749;4656.790444430008;4683;4727.361806156194;9690.903090969001;9736.318457707892;9754.365966212174;9762.75593110178;9780.070893265129;4439.578240067189;4993.550580447777;5095.235714642759;5156.432792392834;5185.092608793446;4584.518625544337;4699.835505757282;4713.858584242478;4724.362211101526;4729.668923175344;3904.7461914976;3915.9216815663526;3924.6467817895955;3978.1844721832535;4003.4395184674167;1247.289045244202;1268.4101892619976;1270.637868207575;1275.1647670775658;1277.2783377391745;768.2739810878666;885;918.3112665500875;948.0803620488116;961.7499450143014;5320.441353657888;5427.240186373911;5539.169124631338;5559.943610713952;5601.80393686219;424.16651280234487;433.6010869999608;447.5837471151795;454.18700526057984;461.0985523301937;3712.1833196696684;3725.1432170600915;3808.5239345081864;3822.4266360046217;3866.6906647468136;9808.950955245178;10218.642347517802;10236.181105511541;10248.48757562119;10279.845802312972;9933.850992235122;10191.7452063699;10217.897821021843;10309.587616495257;10313.48432578368;7635.732749353857;7724.150343462215;7755.573443460551;7840.490368126054;7868.44920855546;5557.749901254372;5566.666000039849;5607.822357305012;5623.212750215055;5715.714214289352;9432.151106400457;9568.330216884751;9592.232621390132;9727.854082188915;9753.853692194765;8652.437591556529;8912.197902188835;9225.031371705874;9491.85762213581;9546.856797148188;6535.3137920517665;6955.547889387173;7088.432925366062;7112.466565007541;7280.199178090512;8031.277185053374;8118.147594502729;8128.431009829255;8133.349332053303;8160.387970902088;7169.7849064528145;7279.4540409468445;7362.226966168475;7375.225601311785;7465.104187497535;7058.576485410892;7114;7147.3210045045635;7181.928180718126;7208.423326134001;6716.899246511404;6776.322367763184;6821.658917054227;6841.418479429304;6841.62371069596;4753.049390121921;4757.857264282077;4759.452662943667;4783.689060211075;4849.248366503198;4969.82605608802;4980;4990.47600002009;5002.87492812668;5081.034603425337 16:09:07 INFO - PROCESS | 11855 | |4;prototype.html;335.8354406340893;335.8572606642176;342.03204930047957;345.1474857102955;346.04491603175245;5283.577313814895;5314.760835762395;5332.813351532697;5338.412774594792;5378.193271009351;5273.657212281209;5288.0745869472785;5302.416734159239;5306.92039619406;5310.575153987681;3927.17529318842;3939.330313846653;4083.448734420845;4084.305668036441;4092.386142078682;4046.615571520701;4058.2143856703105;4079.2453396121814;4091.856785012525;4094.4472496213075;3894.3768996960493;4038.990252436891;4059.817308221137;4068.511778586559;4087.386891966214;3933.704972127088;4018.738781979162;4023.97988010061;4024.2152780208016;4047.736897101679;3575.3385623659706;3576.213233088716;3588.3899737044767;3599.2441587266703;3623.5289412376487;5034.672746271481;5050.141475949097;5096.923546146811;5111.744412779383;5128.692278463267;4523.389342438762;4622.745748983804;4635.745034023127;4648.814047438098;4649.535046495357;5359.651622644516;5398.24424580559;5403.78384864605;5406.45876950598;5419.295491586108;3901.3562762144497;3938.2714197873197;3958.188571342868;3987.9601203987877;3999.8000099994883;5382.381026182;5695.715214239312;5699.344575373802;5732.684702341411;5746.79886203985;3982.3429134192534;3988.202359528106;3995.5604883462797;4067.7559346439307;4074.0018695419813;4033.4353190553347;4065.329220678614;4083.5508094109623;4098.549159592442;4108.281050816155;4431.7148027072435;4678.6491013174145;4690.9914368410955;4704.929426058612;4725;5515.972420137874;5539.9169012464035;5551.583631227674;5572.247746554244;5586.608937374344;1387.1607677355257;1399.9720005599831;1402.901796874209;1411.640031791907;1415.2782081138694;895.785011597212;918.5040078357746;942.9481378524247;961.7355227312405;964.5948701545368;5169.534741873174;5198.92201616976;5220.765065572058;5222.373315202124;5248.186531087611;5574.303212098488;5576;5597.692126932976;5629.746661400247;5636.408177141423;5068.4424713281505;5282.841514754564;5283.260343551906;5290.894182116336;5314.176302673091;2906.6076079729387;2975.137210209015;2986.805857619291;2992.5785252005126;3005.2637103909246;5541.722913854372;5695.430456954271;5826.242588463472;5842.328132264844;5844.970775146097;5691.231683722727;5697.03150464422;5701.942980570224;5715.8571035724435;5724.370319264877;5797.565182611237;5831.950248955144;5836.474717275466;5858.50202732764;5858.5646516602;5337.279467272024;5524.309461317335;5619.213310136666;5723.284589426322;5759.51044161251;5637.830865074055;5642.266505354278;5650.773969041356;5702.971485142548;5721.799737009184;5671.546276297804;5877.823665290048;5892.558058145571;5929.733162007807;5932.872754176693;4103.630673239303;4116.135611521494;4150.273702102059;4152.169566086734;4175.540690524101;5645.943540564542;5702.315722113373;5712.314522257356;5754;5755.539556835527;5457.426970168075;5563.499285064364;5598.804041858515;5605.1795455908705;5606.215129881902;5498.862528436661;5524.392316845224;5539.8892022160135;5543.72281385921;5544.223808666709;5209.6092793041435;5250.842474725611;5428.8328009478155;5429.850686601682;5443.945560544502;5562.638428502134;5585.245991791218;5595.076812326083;5596.992541342597;5598.692071936082;5382.757775900172;5382.923415317031;5391.272178255886;5411.350637923474;5431.2667789847865 16:09:07 INFO - PROCESS | 11855 | |5;yui.html;764.2243123229919;776.1307335783922;780.7891869195314;840.3739214285358;884.8982367027794;14446.0610060346;14723.926380368097;15324.233788310568;15328.310226039826;15341.616459588502;13637.795433068512;14255.50105746299;14307.71384572308;14334.283285835692;14355.641108972255;690.5028379566704;809.8137428391474;825.2078005115096;839.3620848155415;840.3991146330403;822.0710597025383;826.6858593734403;826.7726375246795;827.5076329583887;828.6312590897053;862.6420035685184;868.2706526517724;872.733816186063;873.6068769053919;874.038557586656;719.1406269507941;848.4866655673321;863.2360361080426;872.4416373520952;872.5549969515529;639.9648019358933;643.6170478565293;644.1336402538578;648.2286079565303;648.24803228255;14471;14557.78163327551;14613.6346591335;14625.414983400651;14674.70650586993;13705;13975;13987.370568324348;13994.020418570704;14026.298685065705;11847.703807404798;12023;12136.453859576251;12269.754604907941;12504.687382815411;4363.3454981752675;4449.7997590108525;4458.1306645204195;4554.43069616298;4575.4051973243595;55667.721661391435;55870.720646396505;56096.15855762126;57217;57351.71324143394;11260.94369528147;11349.716257093638;11361.261518001304;11418.885811141783;11467.197296189186;19961.20155193776;19991.700124498144;20042.599148016958;20090.69863952042;20184.192632294544;8285.461445006054;8355.791105222417;8407.62165702545;8571.142885711379;8664.263537599374;4812.230043193073;4931.408231012301;4971.1797553404;4978.427480839678;5078.035173317058;3250.756193285513;3318.7013168814924;3338.8831390901687;3354.664533546626;3357.9160520986684;615.6644628677382;618.8391018335266;621.2700077409027;622.0451606783565;624.4255285137684;685.3009929871603;761.5240474703311;762.6110683551428;774.8876412920094;780.4224873593613;9038.86441703375;9601.231901447867;9680.483074400707;9778.14215299091;9975.152112070411;671.8656268746173;744.2185705009719;757.4319260554585;777.6694904665494;799.9560024198724;4609.9308510372375;4798.760061996956;4865.732384718804;4880.121578115903;4894.094592500399;63310;63898.68050659717;64842.67578662171;65062;67849.66075169691;59245;59372.406275936686;59726.40273597295;59806.40193598095;62208.377916221165;53638;54754.72622636941;54919.45080549223;54947;54992.45007549873;9012.68455604077;9029.864552031593;9032.412893161923;9184.816303674022;9207;33782;33976.30118494016;34491.31017379588;34640.653593464755;35000;44634;45113.32330015118;45597;46681;46747;11756.764864702829;11794.705132371759;11852.525898963944;12004.879951200377;12196.146269761031;12690.555830545887;12958.611241662766;12994.155379900276;13267.46930122823;13282.667933301746;12404.19372740769;12739.872601273868;12780;13004.154729942524;13047.086703931014;11910.702232444259;12366.938165309475;12408.379581021094;12661.113722039368;12684;11402.885971140182;12145.332006739714;12354.320512371547;12773.489060437478;12789.744205116032;10125.87496320542;10143.949280253551;10347.534360953925;10360.430176340373;10431.791364172523;9946.502674866084;10174.643887463903;10202.183825294107;10291.639792607222;10330.638427655296 16:09:07 INFO - PROCESS | 11855 | __end_tp_report 16:09:07 INFO - PROCESS | 11855 | __start_cc_report 16:09:07 INFO - PROCESS | 11855 | _x_x_mozilla_cycle_collect,823 16:09:07 INFO - PROCESS | 11855 | __end_cc_report 16:09:07 INFO - PROCESS | 11855 | __startTimestamp1463008147749__endTimestamp 16:09:07 INFO - PROCESS | 11855 | 16:09:07 INFO - PROCESS | 11855 | ------- Summary: start ------- 16:09:07 INFO - PROCESS | 11855 | Number of tests: 6 16:09:07 INFO - PROCESS | 11855 | 16:09:07 INFO - PROCESS | 11855 | [#0] dojo.html Cycles:165 Average:5217.82 Median:5387.49 stddev:597.45 (11.1%) stddev-sans-first:575.12 16:09:07 INFO - PROCESS | 11855 | Values: 3074.0 3080.2 3107.5 3114.0 3128.0 5101.9 5183.4 5206.1 5278.1 5302.4 5158.7 5167.5 5246.2 5262.6 5364.0 4669.3 4676.8 4680.3 4742.6 4853.6 4760.0 4765.8 4779.3 4812.1 4821.4 4632.9 4722.0 4769.9 4788.9 4791.8 4753.5 4769.4 4785.5 4791.1 4816.0 4526.8 4760.4 4782.4 4786.2 4808.3 5064.2 5113.1 5142.3 5249.9 5279.0 5675.2 5681.9 5684.8 5704.2 5704.9 5454.2 5563.5 5601.4 5613.9 5674.6 5297.8 5384.6 5387.5 5388.5 5418.4 5874.9 5876.3 5882.0 5882.0 5892.4 5374.7 5431.8 5501.8 5593.0 5628.8 5599.8 5609.0 5626.3 5629.5 5663.9 5584.0 5614.2 5617.2 5625.2 5629.0 5365.8 5527.0 5574.4 5581.7 5593.9 4274.8 4285.2 4291.4 4293.9 4326.1 3797.1 3823.9 3831.0 3858.9 3862.2 5183.0 5276.7 5327.7 5338.8 5368.0 5676.2 5703.8 5708.5 5717.8 5721.8 5105.3 5347.5 5347.5 5354.3 5415.3 4749.6 4778.5 4780.2 4780.8 4785.8 5524.9 5857.1 5878.4 5879.3 5881.1 5438.0 5628.2 5676.6 5690.9 5716.1 5820.2 5830.1 5830.2 5832.4 5905.1 5450.0 5452.8 5567.6 5568.4 5570.6 5431.8 5602.2 5622.8 5646.4 5657.5 5529.4 5551.0 5551.8 5562.8 5579.3 5522.0 5524.4 5527.8 5539.7 5550.6 5176.6 5286.9 5288.5 5327.0 5509.0 5490.4 5493.6 5515.7 5549.8 5556.2 5328.6 5332.3 5339.7 5372.9 5379.9 16:09:07 INFO - PROCESS | 11855 | 16:09:07 INFO - PROCESS | 11855 | [#1] ext.html Cycles:180 Average:58982.59 Median:38624.42 stddev:116111.67 (300.6%) stddev-sans-first:116354.57 16:09:07 INFO - PROCESS | 11855 | Values: 571.8 598.7 608.4 615.8 623.6 32390.4 43577.1 43999.8 44034.8 44246.8 44417.8 45125.3 45167.5 45208.3 45384.0 7710.8 8594.4 8759.5 10193.7 10471.2 10107.8 10265.9 10302.0 10321.3 10332.6 10406.5 10425.6 10484.6 10489.6 10498.2 7862.8 10201.9 10406.9 10506.5 10883.7 10312.5 10326.8 10386.4 10436.9 10451.3 40501.4 40664.8 40774.4 40925.2 41142.4 3410.9 3564.7 3927.4 4157.0 4199.9 31107.8 31916.8 31938.8 31971.5 32315.0 4306.8 4328.7 4331.2 4335.2 4427.3 311202.0 315377.0 315553.0 316284.0 316669.0 57269.4 57640.7 57700.4 57779.7 58069.1 36558.0 37300.8 37327.3 38920.2 39140.6 14085.0 14119.2 14182.7 14228.6 14288.0 10044.7 10061.1 10071.4 10093.1 10118.0 19601.4 19736.2 19817.4 19824.9 19979.7 6163.2 7230.3 7311.7 7338.2 7342.4 1008.3 1098.8 1109.2 1117.8 1165.6 31741.4 31755.5 31783.0 32151.2 32355.4 919.8 955.2 988.4 1013.1 1100.6 16407.7 17183.9 17301.9 17324.5 17414.2 537834.0 542203.0 542840.0 545236.0 545436.0 57561.1 58498.0 59077.4 59092.4 59140.0 1354.5 1368.5 1378.7 1385.5 1402.1 29905.4 32430.5 33255.7 33653.5 35780.8 55984.0 56501.7 56737.0 56820.4 56865.0 416000.0 426813.0 427803.9 428016.0 432127.0 37585.8 40020.8 42878.0 43583.0 43677.3 42444.4 46422.5 47192.8 47841.8 47992.3 38328.6 39310.0 40189.8 40269.6 40610.0 38994.8 39970.8 40287.8 40751.6 40955.4 40453.6 40463.8 42314.8 42796.8 42857.4 34201.0 35457.8 36246.1 36415.9 37114.4 27557.7 28150.9 28158.7 28182.4 28404.6 16:09:07 INFO - PROCESS | 11855 | 16:09:07 INFO - PROCESS | 11855 | [#2] jquery.html Cycles:180 Average:26453.72 Median:2631.82 stddev:68745.43 (2612.1%) stddev-sans-first:68910.42 16:09:07 INFO - PROCESS | 11855 | Values: 389.4 393.3 397.2 398.4 411.2 14148.7 17919.4 17999.2 19688.8 19774.0 17691.4 18416.6 18787.2 19011.6 19047.5 5544.8 5635.8 5758.7 5771.9 5773.4 4048.0 5582.6 5722.7 5769.5 6062.3 6300.4 6391.9 6401.4 6412.0 6441.2 6119.3 6262.0 6309.1 6337.6 6400.8 5520.6 5524.5 5525.9 5533.2 5537.2 21559.7 21838.5 22065.8 22441.7 22551.9 2597.0 2666.7 2682.3 3021.0 3084.7 13544.5 14039.4 14189.4 14417.5 14499.9 1852.5 2009.2 2015.7 2034.8 2035.6 79995.6 80418.6 80501.0 81521.2 81570.0 10030.2 10351.4 10417.1 10492.4 10667.5 12404.0 13412.9 14500.1 14532.5 14601.6 7491.4 7573.7 7609.0 7708.3 7721.2 6727.8 6751.2 6991.7 7124.7 7158.4 6434.1 6488.0 6543.6 6561.5 6575.7 1892.8 1898.7 1911.2 1922.5 1943.9 944.5 1056.5 1089.8 1133.9 1134.4 18294.3 18565.8 18631.8 18648.0 19017.6 1004.1 1043.0 1043.8 1064.5 1075.4 10532.5 10982.2 11017.9 11042.8 11050.7 373289.0 376561.0 378315.0 464184.0 464594.0 89185.0 98297.0 98418.0 98441.0 99280.0 987.0 987.6 989.1 1019.3 1023.8 16466.2 16771.8 17028.7 17104.1 17446.8 24947.0 36593.5 37320.8 37659.8 39269.0 48456.0 49598.8 51447.2 51602.0 51689.7 7883.4 8158.8 8262.3 8304.1 8533.3 12436.1 12469.8 12795.9 12832.0 13021.8 10746.6 11097.9 11125.2 11243.9 11249.0 10860.7 10866.3 11079.2 11095.0 11174.2 9886.0 10965.9 11329.8 11507.3 11575.1 8177.2 8308.7 8314.4 8320.7 8484.7 9956.0 11256.9 11286.9 11479.3 11486.5 16:09:07 INFO - PROCESS | 11855 | 16:09:07 INFO - PROCESS | 11855 | [#3] mootools.html Cycles:180 Average:5482.64 Median:4983.66 stddev:3074.14 (61.7%) stddev-sans-first:3067.17 16:09:07 INFO - PROCESS | 11855 | Values: 1362.0 1498.7 1531.5 1677.9 1820.3 7819.5 8123.4 8124.9 8328.3 8379.2 8151.1 8204.2 8264.6 8304.8 8322.8 1881.7 2217.6 2248.9 2262.8 2318.4 2353.8 2359.6 2367.4 2368.8 2369.1 2202.9 2236.7 2237.2 2238.7 2242.9 1962.6 2200.8 2230.5 2253.8 2267.9 2197.6 2210.2 2212.4 2214.2 2251.5 8134.6 8199.4 8203.6 8225.4 8303.8 4987.3 5144.4 5186.0 5192.7 5218.5 3433.5 3452.8 3538.3 3562.5 3575.9 1107.1 1127.5 1136.4 1159.7 1159.9 11553.9 11583.6 11583.9 11677.9 11760.8 4556.3 4635.3 4656.8 4683.0 4727.4 9690.9 9736.3 9754.4 9762.8 9780.1 4439.6 4993.6 5095.2 5156.4 5185.1 4584.5 4699.8 4713.9 4724.4 4729.7 3904.7 3915.9 3924.6 3978.2 4003.4 1247.3 1268.4 1270.6 1275.2 1277.3 768.3 885.0 918.3 948.1 961.7 5320.4 5427.2 5539.2 5559.9 5601.8 424.2 433.6 447.6 454.2 461.1 3712.2 3725.1 3808.5 3822.4 3866.7 9809.0 10218.6 10236.2 10248.5 10279.8 9933.9 10191.7 10217.9 10309.6 10313.5 7635.7 7724.2 7755.6 7840.5 7868.4 5557.7 5566.7 5607.8 5623.2 5715.7 9432.2 9568.3 9592.2 9727.9 9753.9 8652.4 8912.2 9225.0 9491.9 9546.9 6535.3 6955.5 7088.4 7112.5 7280.2 8031.3 8118.1 8128.4 8133.3 8160.4 7169.8 7279.5 7362.2 7375.2 7465.1 7058.6 7114.0 7147.3 7181.9 7208.4 6716.9 6776.3 6821.7 6841.4 6841.6 4753.0 4757.9 4759.5 4783.7 4849.2 4969.8 4980.0 4990.5 5002.9 5081.0 16:09:07 INFO - PROCESS | 11855 | 16:09:07 INFO - PROCESS | 11855 | [#4] prototype.html Cycles:180 Average:4632.99 Median:5308.75 stddev:1365.63 (25.7%) stddev-sans-first:1330.83 16:09:07 INFO - PROCESS | 11855 | Values: 335.8 335.9 342.0 345.1 346.0 5283.6 5314.8 5332.8 5338.4 5378.2 5273.7 5288.1 5302.4 5306.9 5310.6 3927.2 3939.3 4083.4 4084.3 4092.4 4046.6 4058.2 4079.2 4091.9 4094.4 3894.4 4039.0 4059.8 4068.5 4087.4 3933.7 4018.7 4024.0 4024.2 4047.7 3575.3 3576.2 3588.4 3599.2 3623.5 5034.7 5050.1 5096.9 5111.7 5128.7 4523.4 4622.7 4635.7 4648.8 4649.5 5359.7 5398.2 5403.8 5406.5 5419.3 3901.4 3938.3 3958.2 3988.0 3999.8 5382.4 5695.7 5699.3 5732.7 5746.8 3982.3 3988.2 3995.6 4067.8 4074.0 4033.4 4065.3 4083.6 4098.5 4108.3 4431.7 4678.6 4691.0 4704.9 4725.0 5516.0 5539.9 5551.6 5572.2 5586.6 1387.2 1400.0 1402.9 1411.6 1415.3 895.8 918.5 942.9 961.7 964.6 5169.5 5198.9 5220.8 5222.4 5248.2 5574.3 5576.0 5597.7 5629.7 5636.4 5068.4 5282.8 5283.3 5290.9 5314.2 2906.6 2975.1 2986.8 2992.6 3005.3 5541.7 5695.4 5826.2 5842.3 5845.0 5691.2 5697.0 5701.9 5715.9 5724.4 5797.6 5832.0 5836.5 5858.5 5858.6 5337.3 5524.3 5619.2 5723.3 5759.5 5637.8 5642.3 5650.8 5703.0 5721.8 5671.5 5877.8 5892.6 5929.7 5932.9 4103.6 4116.1 4150.3 4152.2 4175.5 5645.9 5702.3 5712.3 5754.0 5755.5 5457.4 5563.5 5598.8 5605.2 5606.2 5498.9 5524.4 5539.9 5543.7 5544.2 5209.6 5250.8 5428.8 5429.9 5443.9 5562.6 5585.2 5595.1 5597.0 5598.7 5382.8 5382.9 5391.3 5411.4 5431.3 16:09:07 INFO - PROCESS | 11855 | 16:09:07 INFO - PROCESS | 11855 | [#5] yui.html Cycles:180 Average:15338.22 Median:4805.50 stddev:18040.60 (375.4%) stddev-sans-first:18058.01 16:09:07 INFO - PROCESS | 11855 | Values: 764.2 776.1 780.8 840.4 884.9 14446.1 14723.9 15324.2 15328.3 15341.6 13637.8 14255.5 14307.7 14334.3 14355.6 690.5 809.8 825.2 839.4 840.4 822.1 826.7 826.8 827.5 828.6 862.6 868.3 872.7 873.6 874.0 719.1 848.5 863.2 872.4 872.6 640.0 643.6 644.1 648.2 648.2 14471.0 14557.8 14613.6 14625.4 14674.7 13705.0 13975.0 13987.4 13994.0 14026.3 11847.7 12023.0 12136.5 12269.8 12504.7 4363.3 4449.8 4458.1 4554.4 4575.4 55667.7 55870.7 56096.2 57217.0 57351.7 11260.9 11349.7 11361.3 11418.9 11467.2 19961.2 19991.7 20042.6 20090.7 20184.2 8285.5 8355.8 8407.6 8571.1 8664.3 4812.2 4931.4 4971.2 4978.4 5078.0 3250.8 3318.7 3338.9 3354.7 3357.9 615.7 618.8 621.3 622.0 624.4 685.3 761.5 762.6 774.9 780.4 9038.9 9601.2 9680.5 9778.1 9975.2 671.9 744.2 757.4 777.7 800.0 4609.9 4798.8 4865.7 4880.1 4894.1 63310.0 63898.7 64842.7 65062.0 67849.7 59245.0 59372.4 59726.4 59806.4 62208.4 53638.0 54754.7 54919.5 54947.0 54992.5 9012.7 9029.9 9032.4 9184.8 9207.0 33782.0 33976.3 34491.3 34640.7 35000.0 44634.0 45113.3 45597.0 46681.0 46747.0 11756.8 11794.7 11852.5 12004.9 12196.1 12690.6 12958.6 12994.2 13267.5 13282.7 12404.2 12739.9 12780.0 13004.2 13047.1 11910.7 12366.9 12408.4 12661.1 12684.0 11402.9 12145.3 12354.3 12773.5 12789.7 10125.9 10143.9 10347.5 10360.4 10431.8 9946.5 10174.6 10202.2 10291.6 10330.6 16:09:07 INFO - PROCESS | 11855 | -------- Summary: end -------- 16:09:07 INFO - PROCESS | 11855 | 16:09:08 INFO - TEST-INFO | 11855: exit 0 16:09:08 INFO - TEST-OK | dromaeo_css | took 1110006ms 16:09:08 INFO - TEST-START | kraken 16:09:08 INFO - operating with platform_type : linux_ 16:09:08 INFO - Initialising browser for kraken test... 16:09:08 INFO - TEST-INFO | started process 12034 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpx_wIoH/profile http://localhost:58776/getInfo.html) 16:09:11 INFO - PROCESS | 12034 | __metrics Screen width/height:1600/1200 16:09:11 INFO - PROCESS | 12034 | colorDepth:24 16:09:11 INFO - PROCESS | 12034 | Browser inner width/height: 1024/697 16:09:11 INFO - PROCESS | 12034 | __metrics 16:09:12 ERROR - PROCESS | 12034 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 16:09:12 INFO - TEST-INFO | 12034: exit 0 16:09:12 INFO - Browser initialized. 16:09:12 INFO - Running cycle 1/1 for kraken test... 16:09:12 INFO - TEST-INFO | started process 12117 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpx_wIoH/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:09:24 INFO - PROCESS | 12117 | RSS: Main: 150540288 16:09:24 INFO - PROCESS | 12117 | 16:09:26 INFO - PROCESS | 12117 | Cycle 1(1): loaded http://localhost:58776/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:58776/tests/kraken/driver.html?testName=audio-beat-detection) 16:09:27 INFO - PROCESS | 12117 | RSS: Main: 206807040 16:09:27 INFO - PROCESS | 12117 | 16:09:30 INFO - PROCESS | 12117 | Cycle 1(1): loaded http://localhost:58776/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:58776/tests/kraken/driver.html?testName=audio-dft) 16:09:30 INFO - PROCESS | 12117 | RSS: Main: 196460544 16:09:30 INFO - PROCESS | 12117 | 16:09:34 INFO - PROCESS | 12117 | Cycle 1(1): loaded http://localhost:58776/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:58776/tests/kraken/driver.html?testName=audio-fft) 16:09:34 INFO - PROCESS | 12117 | RSS: Main: 263344128 16:09:34 INFO - PROCESS | 12117 | 16:09:36 INFO - PROCESS | 12117 | Cycle 1(1): loaded http://localhost:58776/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:58776/tests/kraken/driver.html?testName=audio-oscillator) 16:09:37 INFO - PROCESS | 12117 | RSS: Main: 172535808 16:09:37 INFO - PROCESS | 12117 | 16:09:39 INFO - PROCESS | 12117 | Cycle 1(1): loaded http://localhost:58776/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:58776/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:09:40 INFO - PROCESS | 12117 | RSS: Main: 177512448 16:09:40 INFO - PROCESS | 12117 | 16:09:43 INFO - PROCESS | 12117 | Cycle 1(1): loaded http://localhost:58776/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:58776/tests/kraken/driver.html?testName=imaging-darkroom) 16:09:43 INFO - PROCESS | 12117 | RSS: Main: 213487616 16:09:43 INFO - PROCESS | 12117 | 16:09:47 INFO - PROCESS | 12117 | Cycle 1(1): loaded http://localhost:58776/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:58776/tests/kraken/driver.html?testName=imaging-desaturate) 16:09:48 INFO - PROCESS | 12117 | RSS: Main: 211574784 16:09:48 INFO - PROCESS | 12117 | 16:09:51 INFO - PROCESS | 12117 | Cycle 1(1): loaded http://localhost:58776/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:58776/tests/kraken/driver.html?testName=json-parse-financial) 16:09:51 INFO - PROCESS | 12117 | RSS: Main: 212643840 16:09:51 INFO - PROCESS | 12117 | 16:09:53 INFO - PROCESS | 12117 | Cycle 1(1): loaded http://localhost:58776/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:58776/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:09:53 INFO - PROCESS | 12117 | RSS: Main: 201801728 16:09:53 INFO - PROCESS | 12117 | 16:09:55 INFO - PROCESS | 12117 | Cycle 1(1): loaded http://localhost:58776/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:58776/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:09:56 INFO - PROCESS | 12117 | RSS: Main: 205586432 16:09:56 INFO - PROCESS | 12117 | 16:09:58 INFO - PROCESS | 12117 | Cycle 1(1): loaded http://localhost:58776/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:58776/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:09:59 INFO - PROCESS | 12117 | RSS: Main: 214093824 16:09:59 INFO - PROCESS | 12117 | 16:10:01 INFO - PROCESS | 12117 | Cycle 1(1): loaded http://localhost:58776/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:58776/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:10:02 INFO - PROCESS | 12117 | RSS: Main: 221667328 16:10:02 INFO - PROCESS | 12117 | 16:10:05 INFO - PROCESS | 12117 | Cycle 1(1): loaded http://localhost:58776/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:58776/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:10:05 INFO - PROCESS | 12117 | RSS: Main: 218263552 16:10:05 INFO - PROCESS | 12117 | 16:10:07 INFO - PROCESS | 12117 | Cycle 1(1): loaded http://localhost:58776/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:58776/tests/kraken/driver.html?testName=ai-astar) 16:10:07 INFO - PROCESS | 12117 | RSS: Main: 263573504 16:10:07 INFO - PROCESS | 12117 | 16:10:07 INFO - PROCESS | 12117 | __start_tp_report 16:10:07 INFO - PROCESS | 12117 | _x_x_mozilla_page_load 16:10:07 INFO - PROCESS | 12117 | _x_x_mozilla_page_load_details 16:10:07 INFO - PROCESS | 12117 | |i|pagename|runs| 16:10:07 INFO - PROCESS | 12117 | |0;ai-astar;112;108;111;111;111;110;110;113;112;112 16:10:07 INFO - PROCESS | 12117 | |1;audio-beat-detection;155;129;156;218;140;152;152;133;186;132 16:10:07 INFO - PROCESS | 12117 | |2;audio-dft;163;162;162;162;163;165;163;164;163;163 16:10:07 INFO - PROCESS | 12117 | |3;audio-fft;77;76;78;143;77;76;78;77;132;77 16:10:07 INFO - PROCESS | 12117 | |4;audio-oscillator;92;93;144;94;93;92;116;91;93;116 16:10:07 INFO - PROCESS | 12117 | |5;imaging-gaussian-blur;117;118;117;118;117;118;119;119;117;118 16:10:07 INFO - PROCESS | 12117 | |6;imaging-darkroom;149;147;146;149;148;149;149;149;148;149 16:10:07 INFO - PROCESS | 12117 | |7;imaging-desaturate;95;96;95;97;96;96;96;96;96;96 16:10:07 INFO - PROCESS | 12117 | |8;json-parse-financial;56;57;58;58;57;59;57;111;56;54 16:10:07 INFO - PROCESS | 12117 | |9;json-stringify-tinderbox;59;59;60;60;61;59;61;59;59;59 16:10:07 INFO - PROCESS | 12117 | |10;stanford-crypto-aes;83;90;84;81;82;83;82;82;82;82 16:10:07 INFO - PROCESS | 12117 | |11;stanford-crypto-ccm;139;129;132;146;129;128;133;129;214;132 16:10:07 INFO - PROCESS | 12117 | |12;stanford-crypto-pbkdf2;174;168;171;169;166;172;173;169;173;171 16:10:07 INFO - PROCESS | 12117 | |13;stanford-crypto-sha256-iterative;67;67;67;66;68;67;71;66;65;65 16:10:07 INFO - PROCESS | 12117 | __end_tp_report 16:10:07 INFO - PROCESS | 12117 | __start_cc_report 16:10:07 INFO - PROCESS | 12117 | _x_x_mozilla_cycle_collect,1717 16:10:07 INFO - PROCESS | 12117 | __end_cc_report 16:10:07 INFO - PROCESS | 12117 | __startTimestamp1463008207704__endTimestamp 16:10:07 INFO - PROCESS | 12117 | 16:10:07 INFO - PROCESS | 12117 | ------- Summary: start ------- 16:10:07 INFO - PROCESS | 12117 | Number of tests: 14 16:10:07 INFO - PROCESS | 12117 | 16:10:07 INFO - PROCESS | 12117 | [#0] ai-astar Cycles:10 Average:111.00 Median:111.50 stddev:1.41 (1.3%) stddev-sans-first:1.45 16:10:07 INFO - PROCESS | 12117 | Values: 112.0 108.0 111.0 111.0 111.0 110.0 110.0 113.0 112.0 112.0 16:10:07 INFO - PROCESS | 12117 | 16:10:07 INFO - PROCESS | 12117 | [#1] audio-beat-detection Cycles:10 Average:155.30 Median:153.50 stddev:27.61 (18.0%) stddev-sans-first:29.29 16:10:07 INFO - PROCESS | 12117 | Values: 155.0 129.0 156.0 218.0 140.0 152.0 152.0 133.0 186.0 132.0 16:10:07 INFO - PROCESS | 12117 | 16:10:07 INFO - PROCESS | 12117 | [#2] audio-dft Cycles:10 Average:163.00 Median:163.00 stddev:0.94 (0.6%) stddev-sans-first:1.00 16:10:07 INFO - PROCESS | 12117 | Values: 163.0 162.0 162.0 162.0 163.0 165.0 163.0 164.0 163.0 163.0 16:10:07 INFO - PROCESS | 12117 | 16:10:07 INFO - PROCESS | 12117 | [#3] audio-fft Cycles:10 Average:89.10 Median:77.00 stddev:25.65 (33.3%) stddev-sans-first:26.83 16:10:07 INFO - PROCESS | 12117 | Values: 77.0 76.0 78.0 143.0 77.0 76.0 78.0 77.0 132.0 77.0 16:10:07 INFO - PROCESS | 12117 | 16:10:07 INFO - PROCESS | 12117 | [#4] audio-oscillator Cycles:10 Average:102.40 Median:92.50 stddev:17.58 (19.0%) stddev-sans-first:18.24 16:10:07 INFO - PROCESS | 12117 | Values: 92.0 93.0 144.0 94.0 93.0 92.0 116.0 91.0 93.0 116.0 16:10:07 INFO - PROCESS | 12117 | 16:10:07 INFO - PROCESS | 12117 | [#5] imaging-gaussian-blur Cycles:10 Average:117.80 Median:118.00 stddev:0.79 (0.7%) stddev-sans-first:0.78 16:10:07 INFO - PROCESS | 12117 | Values: 117.0 118.0 117.0 118.0 117.0 118.0 119.0 119.0 117.0 118.0 16:10:07 INFO - PROCESS | 12117 | 16:10:07 INFO - PROCESS | 12117 | [#6] imaging-darkroom Cycles:10 Average:148.30 Median:149.00 stddev:1.06 (0.7%) stddev-sans-first:1.09 16:10:07 INFO - PROCESS | 12117 | Values: 149.0 147.0 146.0 149.0 148.0 149.0 149.0 149.0 148.0 149.0 16:10:07 INFO - PROCESS | 12117 | 16:10:07 INFO - PROCESS | 12117 | [#7] imaging-desaturate Cycles:10 Average:95.90 Median:96.00 stddev:0.57 (0.6%) stddev-sans-first:0.50 16:10:07 INFO - PROCESS | 12117 | Values: 95.0 96.0 95.0 97.0 96.0 96.0 96.0 96.0 96.0 96.0 16:10:07 INFO - PROCESS | 12117 | 16:10:07 INFO - PROCESS | 12117 | [#8] json-parse-financial Cycles:10 Average:62.30 Median:57.00 stddev:17.17 (30.1%) stddev-sans-first:18.06 16:10:07 INFO - PROCESS | 12117 | Values: 56.0 57.0 58.0 58.0 57.0 59.0 57.0 111.0 56.0 54.0 16:10:07 INFO - PROCESS | 12117 | 16:10:07 INFO - PROCESS | 12117 | [#9] json-stringify-tinderbox Cycles:10 Average:59.60 Median:59.50 stddev:0.84 (1.4%) stddev-sans-first:0.87 16:10:07 INFO - PROCESS | 12117 | Values: 59.0 59.0 60.0 60.0 61.0 59.0 61.0 59.0 59.0 59.0 16:10:07 INFO - PROCESS | 12117 | 16:10:07 INFO - PROCESS | 12117 | [#10] stanford-crypto-aes Cycles:10 Average:83.10 Median:82.50 stddev:2.56 (3.1%) stddev-sans-first:2.71 16:10:07 INFO - PROCESS | 12117 | Values: 83.0 90.0 84.0 81.0 82.0 83.0 82.0 82.0 82.0 82.0 16:10:07 INFO - PROCESS | 12117 | 16:10:07 INFO - PROCESS | 12117 | [#11] stanford-crypto-ccm Cycles:10 Average:141.10 Median:132.50 stddev:26.21 (19.8%) stddev-sans-first:27.79 16:10:07 INFO - PROCESS | 12117 | Values: 139.0 129.0 132.0 146.0 129.0 128.0 133.0 129.0 214.0 132.0 16:10:07 INFO - PROCESS | 12117 | 16:10:07 INFO - PROCESS | 12117 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:170.60 Median:171.50 stddev:2.55 (1.5%) stddev-sans-first:2.39 16:10:07 INFO - PROCESS | 12117 | Values: 174.0 168.0 171.0 169.0 166.0 172.0 173.0 169.0 173.0 171.0 16:10:07 INFO - PROCESS | 12117 | 16:10:07 INFO - PROCESS | 12117 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.90 Median:67.00 stddev:1.73 (2.6%) stddev-sans-first:1.83 16:10:07 INFO - PROCESS | 12117 | Values: 67.0 67.0 67.0 66.0 68.0 67.0 71.0 66.0 65.0 65.0 16:10:07 INFO - PROCESS | 12117 | -------- Summary: end -------- 16:10:07 INFO - PROCESS | 12117 | 16:10:08 INFO - TEST-INFO | 12117: exit 0 16:10:08 INFO - TEST-OK | kraken | took 60211ms 16:10:08 INFO - SUITE-END | took 1170s 16:10:08 INFO - Completed test suite (00:19:30) 16:10:08 INFO - javascript benchmark 16:10:08 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3074.0470454159204, 3080.2453398917255, 3107.5494053362254, 3114.019083988545, 3128.0459459864733, 5101.923471147936, 5183.429822719498, 5206.062908676437, 5278.0763366410865, 5302.416734159239, 5158.716270605115, 5167.509086636764, 5246.1868410396355, 5262.605304602161, 5363.954028964354, 4669.276262179367, 4676.766161691919, 4680.344751734769, 4742.644301677371, 4853.563179313861, 4759.952400475986, 4765.785539650707, 4779.283107533863, 4812.109759694467, 4821.373221481203, 4632.907341853161, 4721.976390118062, 4769.90460190796, 4788.928166077511, 4791.808327666924, 4753.524647535253, 4769.356136921506, 4785.473597904228, 4791.089692958361, 4816.0, 4526.841560545365, 4760.357351757503, 4782.402199725035, 4786.16242157621, 4808.3027960945465, 5064.164412871872, 5113.105206588869, 5142.280080788655, 5249.86875328116, 5279.049771041211, 5675.233843431125, 5681.914771278434, 5684.772609095631, 5704.201411802351, 5704.914426283609, 5454.236406903036, 5563.499285064323, 5601.411851755588, 5613.887722245532, 5674.602777805555, 5297.761600727977, 5384.59615528833, 5387.488188622114, 5388.5419739321815, 5418.431064738224, 5874.942510348178, 5876.294844618673, 5882.02946513829, 5882.02946513829, 5892.381299963527, 5374.677519348774, 5431.83704488866, 5501.779928802883, 5592.965301419169, 5628.802991895264, 5599.832005039855, 5609.0, 5626.2967129108865, 5629.4652008059165, 5663.915041274384, 5584.022796010682, 5614.157876318585, 5617.21359009739, 5625.240592519957, 5628.971855140698, 5365.839024829262, 5527.032769265363, 5574.386817450159, 5581.665100094007, 5593.916091258634, 4274.786260686953, 4285.185814695198, 4291.377750226262, 4293.935590966138, 4326.069894972596, 3797.0886987123445, 3823.904402389907, 3831.0039389758863, 3858.9035274118373, 3862.208247309295, 5183.0, 5276.709780962007, 5327.733613319396, 5338.839834804962, 5368.0, 5676.205331253628, 5703.800366987219, 5708.486236238676, 5717.771289148471, 5721.799737009184, 5105.259737338065, 5347.545458635982, 5347.545458635982, 5354.330708661418, 5415.268938693304, 4749.62003039756, 4778.546038126373, 4780.235162374003, 4780.784864681098, 4785.808567657325, 5524.861878453071, 5857.062869940874, 5878.382769809194, 5879.323877754027, 5881.147233651097, 5437.972810135924, 5628.240187574625, 5676.631018983753, 5690.943090569207, 5716.08542633185, 5820.156077368673, 5830.067189249699, 5830.2129212555765, 5832.416758324133, 5905.143754155538, 5449.99175152598, 5452.7546260417585, 5567.610267281251, 5568.359638641446, 5570.610057296112, 5431.8098866539485, 5602.243697100839, 5622.847316300067, 5646.407127251579, 5657.462541058593, 5529.4470552944385, 5550.972245138749, 5551.777928882961, 5562.805301814417, 5579.2746942897165, 5522.0, 5524.4199359066715, 5527.751251193787, 5539.7230138492105, 5550.639208451437, 5176.559992400537, 5286.920696189637, 5288.497592728685, 5326.97336513315, 5509.00837849191, 5490.396056433869, 5493.587980901369, 5515.669059856261, 5549.833504994856, 5556.194351819045, 5328.600354973315, 5332.253484512249, 5339.652922560022, 5372.946270537401, 5379.946200538101], "value": 5177.947913835156, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [571.788438277837, 598.7156100852094, 608.42503833877, 615.8183335915905, 623.5946634687452, 32390.352192956158, 43577.12845743083, 43999.78000110003, 44034.7798261009, 44246.77876610612, 44417.77791111048, 45125.323120153225, 45167.54832451674, 45208.321875171896, 45384.0, 7710.845783084335, 8594.441361311494, 8759.474431534096, 10193.694189174337, 10471.162307015458, 10107.797844043114, 10265.897341026568, 10302.0, 10321.277510574262, 10332.638357657483, 10406.531706073245, 10425.635102771404, 10484.633037843678, 10489.580416783358, 10498.21263405244, 7862.764117076496, 10201.897981020169, 10406.947965260126, 10506.527206275736, 10883.727906802314, 10312.535935882903, 10326.845097323547, 10386.428746418944, 10436.895631043668, 10451.320664156805, 40501.39247911284, 40664.79667601643, 40774.388384174265, 40925.181496370205, 41142.38286425676, 3410.931781364359, 3564.7326450516057, 3927.371620540728, 4157.0, 4199.853005144805, 31107.84446077778, 31916.840415798004, 31938.840305798556, 31971.52042719361, 32315.030549083564, 4306.784660766981, 4328.696991210617, 4331.198728235287, 4335.241332766753, 4427.335899615051, 311202.0, 315377.0, 315553.0, 316283.9999999977, 316669.0, 57269.42730572641, 57640.71179644075, 57700.42299577034, 57779.71110144423, 58069.12896306559, 36557.96793529091, 37300.81349593234, 37327.25345493129, 38920.22159556793, 39140.608593914265, 14085.014049016676, 14119.223442710749, 14182.716345673029, 14228.644283892985, 14288.0, 10044.748881278027, 10061.094501494901, 10071.395716257048, 10093.132770437345, 10118.0, 19601.411957641292, 19736.210551577777, 19817.405477835688, 19824.90087549553, 19979.700304495444, 6163.164944125663, 7230.349268565749, 7311.707531698778, 7338.192798792129, 7342.412606991428, 1008.2790804574764, 1098.818694915346, 1109.2401704832216, 1117.8211486162174, 1165.621173118723, 31741.365172696416, 31755.52366714501, 31783.0, 32151.196220094684, 32355.35289294201, 919.839028170081, 955.2262667239559, 988.4365911430559, 1013.1034034879185, 1100.5927806711409, 16407.67184656324, 17183.914080429517, 17301.913490432467, 17324.480265592054, 17414.216360263566, 537834.0, 542203.0, 542840.0, 545236.0, 545436.0, 57561.13658295129, 58498.0, 59077.40922590805, 59092.40907590955, 59140.0, 1354.4717560151548, 1368.4799776085224, 1378.7311474262422, 1385.49429458247, 1402.1166665000985, 29905.401891961603, 32430.513542296412, 33255.667443325256, 33653.49519757255, 35780.82109589435, 55984.0, 56501.717491413925, 56737.0, 56820.431795681514, 56865.0, 416000.0, 426813.0, 427803.8609806931, 428016.0, 432127.0, 37585.81207093947, 40020.79989600033, 42878.0, 43583.0, 43677.344839826794, 42444.36333454939, 46422.53577464182, 47192.7640361796, 47841.760791197215, 47992.28011579899, 38328.61671383362, 39310.0, 40189.79905100456, 40269.59730402658, 40610.0, 38994.80502597469, 39970.82931653669, 40287.79856100701, 40751.59248407478, 40955.38566921558, 40453.595464044985, 40463.797681012584, 42314.788426057676, 42796.786016069724, 42857.35713964355, 34201.0, 35457.82271088731, 36246.09384765296, 36415.86268008713, 37114.44328335023, 27557.724422755517, 28150.85924570364, 28158.71841281561, 28182.43635127245, 28404.57393139063], "value": 20174.93714399036, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [389.4430963721878, 393.2567447524179, 397.2373043755992, 398.4362127589461, 411.1961116018183, 14148.71702565948, 17919.372821951234, 17999.190036448374, 19688.803111968875, 19774.0, 17691.380801671945, 18416.631667366644, 18787.15457804399, 19011.61976760464, 19047.523811904673, 5544.77820887165, 5635.802746903859, 5758.7408566614395, 5771.884562308751, 5773.364929857712, 4047.9797601011956, 5582.553395728332, 5722.656640601577, 5769.509591684712, 6062.302835173968, 6300.369963003709, 6391.872162556746, 6401.359864013608, 6411.9679401602925, 6441.162648855642, 6119.326874043852, 6261.96869015652, 6309.116723658692, 6337.6197428154455, 6400.839979000516, 5520.558355331604, 5524.502794748451, 5525.944740552583, 5533.197686335499, 5537.224788529569, 21559.676604850938, 21838.454038649, 22065.779342206533, 22441.663375049386, 22551.872406379778, 2596.974195192888, 2666.6666666666665, 2682.2623778460884, 3021.0159792969566, 3084.737797287233, 13544.45822167122, 14039.368228429745, 14189.43242270308, 14417.495387661382, 14499.927500362535, 1852.5183452302363, 2009.1963214714085, 2015.7177995080701, 2034.7965203479534, 2035.5725297687504, 79995.60002199952, 80418.59790701067, 80501.0, 81521.18478815195, 81569.9999999994, 10030.247731420173, 10351.378917264989, 10417.114545263737, 10492.370457772558, 10667.466626668636, 12404.0, 13412.865871341357, 14500.057496262709, 14532.491362802251, 14601.634959126106, 7491.3632341251605, 7573.697052117854, 7608.961955190189, 7708.344790692854, 7721.150673426031, 6727.831804204836, 6751.156105486814, 6991.720331186797, 7124.679389427488, 7158.427325813923, 6434.131392261985, 6487.967560162263, 6543.574667646588, 6561.540692151503, 6575.671216439159, 1892.8012558681417, 1898.7246849206788, 1911.2259534888392, 1922.5097600111883, 1943.9308380390728, 944.4899754132691, 1056.4664844253757, 1089.841972913923, 1133.877461313294, 1134.3817619397448, 18294.268229270947, 18565.81434185668, 18631.81368186301, 18648.0, 19017.619647607247, 1004.1063453526222, 1043.0, 1043.796459690353, 1064.5309472927465, 1075.3762817565794, 10532.526036328383, 10982.231243812856, 11017.889821101686, 11042.779144417067, 11050.668479945614, 373289.0, 376561.0, 378315.0, 464184.0, 464594.0, 89185.0, 98297.0, 98418.0, 98441.0, 99280.0, 987.0474991633108, 987.6345752071779, 989.0850962859327, 1019.3323373190572, 1023.7594165371132, 16466.176691165634, 16771.832281677027, 17028.659426811148, 17104.144792760562, 17446.825531744522, 24947.0, 36593.45109823302, 37320.81339593285, 37659.81170094132, 39269.0, 48456.0, 49598.75200624118, 51447.22829157641, 51602.0, 51689.741551292005, 7883.448158628841, 8158.796030099295, 8262.256396924306, 8304.128066552925, 8533.27467165273, 12436.129470936947, 12469.750604987668, 12795.93602031984, 12832.0, 13021.804672930102, 10746.623868164577, 11097.889021109686, 11125.165612578927, 11243.943780281046, 11249.0, 10860.67417977462, 10866.293690910066, 11079.224454288122, 11095.0, 11174.217804753589, 9885.950570247103, 10965.945170274097, 11329.830052549383, 11507.252028618113, 11575.073994080622, 8177.223163799429, 8308.6676532938, 8314.376421768271, 8320.667173312999, 8484.660613575636, 9955.950220249142, 11256.887431125584, 11286.887131128584, 11479.25384849982, 11486.483108259981], "value": 9169.462377862785, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1361.9591412257637, 1498.6777842763806, 1531.4946067797625, 1677.949661510155, 1820.2627935686044, 7819.491733037348, 8123.431359804808, 8124.9187508124905, 8328.292095171902, 8379.162083791618, 8151.062627797805, 8204.220599043096, 8264.586770661457, 8304.792380190498, 8322.833543329145, 1881.6613009658256, 2217.6451767717167, 2248.85382450141, 2262.8302877284186, 2318.3856278086237, 2353.752855950126, 2359.6106642403984, 2367.419982104378, 2368.786809187173, 2369.07606033647, 2202.8678279303294, 2236.6756820261053, 2237.1834280487587, 2238.6977758002704, 2242.9439264018365, 1962.5976674781707, 2200.7799220078023, 2230.475838178043, 2253.785890340415, 2267.9319620411416, 2197.615417301966, 2210.237468073512, 2212.4026512841606, 2214.247155966963, 2251.4596496840804, 8134.552599607019, 8199.42604017713, 8203.58982050895, 8225.383096267804, 8303.750887473385, 4987.326710894019, 5144.4083930348115, 5185.988732197223, 5192.714400707997, 5218.530332270114, 3433.4678124890434, 3452.8446219920165, 3538.327717733622, 3562.536870206882, 3575.8927232183078, 1107.0755918807806, 1127.4982632728438, 1136.352279200856, 1159.6868845411775, 1159.8898104680043, 11553.884461155365, 11583.594574189863, 11583.884161158448, 11677.941610291979, 11760.823587646191, 4556.270996640522, 4635.327877457749, 4656.790444430008, 4683.0, 4727.361806156194, 9690.903090969001, 9736.318457707892, 9754.365966212174, 9762.75593110178, 9780.070893265129, 4439.578240067189, 4993.550580447777, 5095.235714642759, 5156.432792392834, 5185.092608793446, 4584.518625544337, 4699.835505757282, 4713.858584242478, 4724.362211101526, 4729.668923175344, 3904.7461914976, 3915.9216815663526, 3924.6467817895955, 3978.1844721832535, 4003.4395184674167, 1247.289045244202, 1268.4101892619976, 1270.637868207575, 1275.1647670775658, 1277.2783377391745, 768.2739810878666, 885.0, 918.3112665500875, 948.0803620488116, 961.7499450143014, 5320.441353657888, 5427.240186373911, 5539.169124631338, 5559.943610713952, 5601.80393686219, 424.16651280234487, 433.6010869999608, 447.5837471151795, 454.18700526057984, 461.0985523301937, 3712.1833196696684, 3725.1432170600915, 3808.5239345081864, 3822.4266360046217, 3866.6906647468136, 9808.950955245178, 10218.642347517802, 10236.181105511541, 10248.48757562119, 10279.845802312972, 9933.850992235122, 10191.7452063699, 10217.897821021843, 10309.587616495257, 10313.48432578368, 7635.732749353857, 7724.150343462215, 7755.573443460551, 7840.490368126054, 7868.44920855546, 5557.749901254372, 5566.666000039849, 5607.822357305012, 5623.212750215055, 5715.714214289352, 9432.151106400457, 9568.330216884751, 9592.232621390132, 9727.854082188915, 9753.853692194765, 8652.437591556529, 8912.197902188835, 9225.031371705874, 9491.85762213581, 9546.856797148188, 6535.3137920517665, 6955.547889387173, 7088.432925366062, 7112.466565007541, 7280.199178090512, 8031.277185053374, 8118.147594502729, 8128.431009829255, 8133.349332053303, 8160.387970902088, 7169.7849064528145, 7279.4540409468445, 7362.226966168475, 7375.225601311785, 7465.104187497535, 7058.576485410892, 7114.0, 7147.3210045045635, 7181.928180718126, 7208.423326134001, 6716.899246511404, 6776.322367763184, 6821.658917054227, 6841.418479429304, 6841.62371069596, 4753.049390121921, 4757.857264282077, 4759.452662943667, 4783.689060211075, 4849.248366503198, 4969.82605608802, 4980.0, 4990.47600002009, 5002.87492812668, 5081.034603425337], "value": 4352.063135199696, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [335.8354406340893, 335.8572606642176, 342.03204930047957, 345.1474857102955, 346.04491603175245, 5283.577313814895, 5314.760835762395, 5332.813351532697, 5338.412774594792, 5378.193271009351, 5273.657212281209, 5288.0745869472785, 5302.416734159239, 5306.92039619406, 5310.575153987681, 3927.17529318842, 3939.330313846653, 4083.448734420845, 4084.305668036441, 4092.386142078682, 4046.615571520701, 4058.2143856703105, 4079.2453396121814, 4091.856785012525, 4094.4472496213075, 3894.3768996960493, 4038.990252436891, 4059.817308221137, 4068.511778586559, 4087.386891966214, 3933.704972127088, 4018.738781979162, 4023.97988010061, 4024.2152780208016, 4047.736897101679, 3575.3385623659706, 3576.213233088716, 3588.3899737044767, 3599.2441587266703, 3623.5289412376487, 5034.672746271481, 5050.141475949097, 5096.923546146811, 5111.744412779383, 5128.692278463267, 4523.389342438762, 4622.745748983804, 4635.745034023127, 4648.814047438098, 4649.535046495357, 5359.651622644516, 5398.24424580559, 5403.78384864605, 5406.45876950598, 5419.295491586108, 3901.3562762144497, 3938.2714197873197, 3958.188571342868, 3987.9601203987877, 3999.8000099994883, 5382.381026182, 5695.715214239312, 5699.344575373802, 5732.684702341411, 5746.79886203985, 3982.3429134192534, 3988.202359528106, 3995.5604883462797, 4067.7559346439307, 4074.0018695419813, 4033.4353190553347, 4065.329220678614, 4083.5508094109623, 4098.549159592442, 4108.281050816155, 4431.7148027072435, 4678.6491013174145, 4690.9914368410955, 4704.929426058612, 4725.0, 5515.972420137874, 5539.9169012464035, 5551.583631227674, 5572.247746554244, 5586.608937374344, 1387.1607677355257, 1399.9720005599831, 1402.901796874209, 1411.640031791907, 1415.2782081138694, 895.785011597212, 918.5040078357746, 942.9481378524247, 961.7355227312405, 964.5948701545368, 5169.534741873174, 5198.92201616976, 5220.765065572058, 5222.373315202124, 5248.186531087611, 5574.303212098488, 5576.0, 5597.692126932976, 5629.746661400247, 5636.408177141423, 5068.4424713281505, 5282.841514754564, 5283.260343551906, 5290.894182116336, 5314.176302673091, 2906.6076079729387, 2975.137210209015, 2986.805857619291, 2992.5785252005126, 3005.2637103909246, 5541.722913854372, 5695.430456954271, 5826.242588463472, 5842.328132264844, 5844.970775146097, 5691.231683722727, 5697.03150464422, 5701.942980570224, 5715.8571035724435, 5724.370319264877, 5797.565182611237, 5831.950248955144, 5836.474717275466, 5858.50202732764, 5858.5646516602, 5337.279467272024, 5524.309461317335, 5619.213310136666, 5723.284589426322, 5759.51044161251, 5637.830865074055, 5642.266505354278, 5650.773969041356, 5702.971485142548, 5721.799737009184, 5671.546276297804, 5877.823665290048, 5892.558058145571, 5929.733162007807, 5932.872754176693, 4103.630673239303, 4116.135611521494, 4150.273702102059, 4152.169566086734, 4175.540690524101, 5645.943540564542, 5702.315722113373, 5712.314522257356, 5754.0, 5755.539556835527, 5457.426970168075, 5563.499285064364, 5598.804041858515, 5605.1795455908705, 5606.215129881902, 5498.862528436661, 5524.392316845224, 5539.8892022160135, 5543.72281385921, 5544.223808666709, 5209.6092793041435, 5250.842474725611, 5428.8328009478155, 5429.850686601682, 5443.945560544502, 5562.638428502134, 5585.245991791218, 5595.076812326083, 5596.992541342597, 5598.692071936082, 5382.757775900172, 5382.923415317031, 5391.272178255886, 5411.350637923474, 5431.2667789847865], "value": 4201.154077455649, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [764.2243123229919, 776.1307335783922, 780.7891869195314, 840.3739214285358, 884.8982367027794, 14446.0610060346, 14723.926380368097, 15324.233788310568, 15328.310226039826, 15341.616459588502, 13637.795433068512, 14255.50105746299, 14307.71384572308, 14334.283285835692, 14355.641108972255, 690.5028379566704, 809.8137428391474, 825.2078005115096, 839.3620848155415, 840.3991146330403, 822.0710597025383, 826.6858593734403, 826.7726375246795, 827.5076329583887, 828.6312590897053, 862.6420035685184, 868.2706526517724, 872.733816186063, 873.6068769053919, 874.038557586656, 719.1406269507941, 848.4866655673321, 863.2360361080426, 872.4416373520952, 872.5549969515529, 639.9648019358933, 643.6170478565293, 644.1336402538578, 648.2286079565303, 648.24803228255, 14471.0, 14557.78163327551, 14613.6346591335, 14625.414983400651, 14674.70650586993, 13705.0, 13975.0, 13987.370568324348, 13994.020418570704, 14026.298685065705, 11847.703807404798, 12023.0, 12136.453859576251, 12269.754604907941, 12504.687382815411, 4363.3454981752675, 4449.7997590108525, 4458.1306645204195, 4554.43069616298, 4575.4051973243595, 55667.721661391435, 55870.720646396505, 56096.15855762126, 57217.0, 57351.71324143394, 11260.94369528147, 11349.716257093638, 11361.261518001304, 11418.885811141783, 11467.197296189186, 19961.20155193776, 19991.700124498144, 20042.599148016958, 20090.69863952042, 20184.192632294544, 8285.461445006054, 8355.791105222417, 8407.62165702545, 8571.142885711379, 8664.263537599374, 4812.230043193073, 4931.408231012301, 4971.1797553404, 4978.427480839678, 5078.035173317058, 3250.756193285513, 3318.7013168814924, 3338.8831390901687, 3354.664533546626, 3357.9160520986684, 615.6644628677382, 618.8391018335266, 621.2700077409027, 622.0451606783565, 624.4255285137684, 685.3009929871603, 761.5240474703311, 762.6110683551428, 774.8876412920094, 780.4224873593613, 9038.86441703375, 9601.231901447867, 9680.483074400707, 9778.14215299091, 9975.152112070411, 671.8656268746173, 744.2185705009719, 757.4319260554585, 777.6694904665494, 799.9560024198724, 4609.9308510372375, 4798.760061996956, 4865.732384718804, 4880.121578115903, 4894.094592500399, 63310.0, 63898.68050659717, 64842.67578662171, 65062.0, 67849.66075169691, 59245.0, 59372.406275936686, 59726.40273597295, 59806.40193598095, 62208.377916221165, 53638.0, 54754.72622636941, 54919.45080549223, 54947.0, 54992.45007549873, 9012.68455604077, 9029.864552031593, 9032.412893161923, 9184.816303674022, 9207.0, 33782.0, 33976.30118494016, 34491.31017379588, 34640.653593464755, 35000.0, 44634.0, 45113.32330015118, 45597.0, 46681.0, 46747.0, 11756.764864702829, 11794.705132371759, 11852.525898963944, 12004.879951200377, 12196.146269761031, 12690.555830545887, 12958.611241662766, 12994.155379900276, 13267.46930122823, 13282.667933301746, 12404.19372740769, 12739.872601273868, 12780.0, 13004.154729942524, 13047.086703931014, 11910.702232444259, 12366.938165309475, 12408.379581021094, 12661.113722039368, 12684.0, 11402.885971140182, 12145.332006739714, 12354.320512371547, 12773.489060437478, 12789.744205116032, 10125.87496320542, 10143.949280253551, 10347.534360953925, 10360.430176340373, 10431.791364172523, 9946.502674866084, 10174.643887463903, 10202.183825294107, 10291.639792607222, 10330.638427655296], "value": 6912.383115010439, "unit": "score"}], "name": "dromaeo_css", "value": 7033.566550833302, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 108.0, 111.0, 111.0, 111.0, 110.0, 110.0, 113.0, 112.0, 112.0], "value": 111.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 129.0, 156.0, 218.0, 140.0, 152.0, 152.0, 133.0, 186.0, 132.0], "value": 155.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 162.0, 162.0, 162.0, 163.0, 165.0, 163.0, 164.0, 163.0, 163.0], "value": 163.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 76.0, 78.0, 143.0, 77.0, 76.0, 78.0, 77.0, 132.0, 77.0], "value": 89.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 93.0, 144.0, 94.0, 93.0, 92.0, 116.0, 91.0, 93.0, 116.0], "value": 102.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 118.0, 117.0, 118.0, 117.0, 118.0, 119.0, 119.0, 117.0, 118.0], "value": 117.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 147.0, 146.0, 149.0, 148.0, 149.0, 149.0, 149.0, 148.0, 149.0], "value": 148.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 95.0, 97.0, 96.0, 96.0, 96.0, 96.0, 96.0, 96.0], "value": 95.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 57.0, 58.0, 58.0, 57.0, 59.0, 57.0, 111.0, 56.0, 54.0], "value": 62.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 59.0, 60.0, 60.0, 61.0, 59.0, 61.0, 59.0, 59.0, 59.0], "value": 59.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 90.0, 84.0, 81.0, 82.0, 83.0, 82.0, 82.0, 82.0, 82.0], "value": 83.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 129.0, 132.0, 146.0, 129.0, 128.0, 133.0, 129.0, 214.0, 132.0], "value": 141.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 168.0, 171.0, 169.0, 166.0, 172.0, 173.0, 169.0, 173.0, 171.0], "value": 170.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 67.0, 66.0, 68.0, 67.0, 71.0, 66.0, 65.0, 65.0], "value": 66.9, "unit": "score"}], "name": "kraken", "value": 1566.3999999999996, "alertThreshold": 2.0}]} 16:10:08 INFO - Return code: 0 16:10:08 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 16:10:08 ERROR - # TBPL SUCCESS # 16:10:08 INFO - Running post-action listener: _resource_record_post_action 16:10:08 INFO - ##### 16:10:08 INFO - ##### Finished run-tests step (success) 16:10:08 INFO - ##### 16:10:08 INFO - Running post-run listener: _resource_record_post_run 16:10:10 INFO - Total resource usage - Wall time: 1188s; CPU: 25.0%; Read bytes: 4096; Write bytes: 540364800; Read time: 12; Write time: 425664 16:10:10 INFO - install - Wall time: 17s; CPU: 26.0%; Read bytes: 0; Write bytes: 244576256; Read time: 0; Write time: 197096 16:10:10 INFO - run-tests - Wall time: 1172s; CPU: 25.0%; Read bytes: 4096; Write bytes: 295788544; Read time: 12; Write time: 228568 16:10:10 INFO - Running post-run listener: _upload_blobber_files 16:10:10 INFO - Blob upload gear active. 16:10:10 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:10:10 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 16:10:10 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', 'Mozilla-Inbound', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:10:10 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 Mozilla-Inbound -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:10:10 INFO - (blobuploader) - INFO - Open directory for files ... 16:10:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:10:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:10:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:10:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:10:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:10:11 INFO - (blobuploader) - INFO - Done attempting. 16:10:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:10:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:10:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:10:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:10:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:10:12 INFO - (blobuploader) - INFO - Done attempting. 16:10:12 INFO - (blobuploader) - INFO - Iteration through files over. 16:10:12 INFO - Return code: 0 16:10:12 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:10:12 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:10:12 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/62689efe06f0a81607f7595357a0890686eb124bf9b1898bff6bf3e62b871d309f9b2bf37bbdf756cb0b831ed2283b1ebcd1dcb80c1f6802c2a10e632be3140f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/837353496e9ba91b53cb2887884185d9d4ec950da3dbc7fb88e3a8b6645a46d7a041cbc663ebd5269816d013d488c6bafa4f01e07099712e245c69c12c26b2ab"} 16:10:12 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:10:12 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:10:12 INFO - Contents: 16:10:12 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/62689efe06f0a81607f7595357a0890686eb124bf9b1898bff6bf3e62b871d309f9b2bf37bbdf756cb0b831ed2283b1ebcd1dcb80c1f6802c2a10e632be3140f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/837353496e9ba91b53cb2887884185d9d4ec950da3dbc7fb88e3a8b6645a46d7a041cbc663ebd5269816d013d488c6bafa4f01e07099712e245c69c12c26b2ab"} 16:10:12 INFO - Running post-run listener: copy_logs_to_upload_dir 16:10:12 INFO - Copying logs to upload dir... 16:10:12 INFO - mkdir: /builds/slave/test/build/upload/logs 16:10:12 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1238.312616 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2016-05-11 16:10:11.927306) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-11 16:10:11.930822) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=0274524eba3bdaad075c2247000001d4-1463006419.130737-286939692 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/62689efe06f0a81607f7595357a0890686eb124bf9b1898bff6bf3e62b871d309f9b2bf37bbdf756cb0b831ed2283b1ebcd1dcb80c1f6802c2a10e632be3140f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/837353496e9ba91b53cb2887884185d9d4ec950da3dbc7fb88e3a8b6645a46d7a041cbc663ebd5269816d013d488c6bafa4f01e07099712e245c69c12c26b2ab"} build_url:https://queue.taskcluster.net/v1/task/E7u2GtFMRdK3hzvXNP-dBA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.005397 build_url: 'https://queue.taskcluster.net/v1/task/E7u2GtFMRdK3hzvXNP-dBA/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/Mozilla-Inbound/sha512/62689efe06f0a81607f7595357a0890686eb124bf9b1898bff6bf3e62b871d309f9b2bf37bbdf756cb0b831ed2283b1ebcd1dcb80c1f6802c2a10e632be3140f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/837353496e9ba91b53cb2887884185d9d4ec950da3dbc7fb88e3a8b6645a46d7a041cbc663ebd5269816d013d488c6bafa4f01e07099712e245c69c12c26b2ab"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-11 16:10:11.944795) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 16:10:11.945100) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=0274524eba3bdaad075c2247000001d4-1463006419.130737-286939692 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.002109 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 16:10:12.027952) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-11 16:10:12.028364) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-11 16:10:12.028699) ========= ========= Total master_lag: 0.16 =========