builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-045 starttime: 1463093815.97 results: success (0) buildid: 20160512133017 builduid: ed0cf45b4893495cbc32a4cfa5dae9cd revision: 3967d009efc0ee734f4d2750d8fda2bbecca5d81 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-12 15:56:55.968222) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-12 15:56:55.969013) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-12 15:56:55.969523) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-12 15:56:55.980107) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 15:56:55.980470) ========= 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=4300f881736b422476174874000001bc-1463093815.836123-1850221183 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-12 15:56:56-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 98.8M=0s 2016-05-12 15:56:56 (98.8 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.067939 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 15:56:56.056594) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 15:56:56.056939) ========= 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=4300f881736b422476174874000001bc-1463093815.836123-1850221183 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.009526 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 15:56:56.105528) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 15:56:56.106166) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 3967d009efc0ee734f4d2750d8fda2bbecca5d81 --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 3967d009efc0ee734f4d2750d8fda2bbecca5d81 --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=4300f881736b422476174874000001bc-1463093815.836123-1850221183 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-12 15:56:56,565 truncating revision to first 12 chars 2016-05-12 15:56:56,565 Setting DEBUG logging. 2016-05-12 15:56:56,565 attempt 1/10 2016-05-12 15:56:56,565 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/3967d009efc0?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-12 15:56:56,812 unpacking tar archive at: mozilla-inbound-3967d009efc0/testing/mozharness/ program finished with exit code 0 elapsedTime=0.492044 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 15:56:56.607453) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-12 15:56:56.607796) ========= script_repo_revision: 3967d009efc0ee734f4d2750d8fda2bbecca5d81 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-12 15:56:56.608203) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-12 15:56:56.608475) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-12 15:56:56.614507) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 29 secs) (at 2016-05-12 15:56:56.614876) ========= /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=4300f881736b422476174874000001bc-1463093815.836123-1850221183 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:56:57 INFO - MultiFileLogger online at 20160512 15:56:57 in /builds/slave/test 15:56:57 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:56:57 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:56:57 INFO - {'append_to_log': False, 15:56:57 INFO - 'base_work_dir': '/builds/slave/test', 15:56:57 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 15:56:57 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:56:57 INFO - 'branch': 'Mozilla-Inbound', 15:56:57 INFO - 'buildbot_json_path': 'buildprops.json', 15:56:57 INFO - 'config_files': ('talos/linux_config.py',), 15:56:57 INFO - 'default_actions': ('clobber', 15:56:57 INFO - 'read-buildbot-config', 15:56:57 INFO - 'download-and-extract', 15:56:57 INFO - 'populate-webroot', 15:56:57 INFO - 'create-virtualenv', 15:56:57 INFO - 'install', 15:56:57 INFO - 'run-tests'), 15:56:57 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:56:57 INFO - 'download_minidump_stackwalk': True, 15:56:57 INFO - 'download_symbols': 'ondemand', 15:56:57 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:56:57 INFO - 'tooltool.py': '/tools/tooltool.py', 15:56:57 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:56:57 INFO - '/tools/misc-python/virtualenv.py')}, 15:56:57 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:56:57 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:56:57 INFO - 'installer_path': 'installer.exe', 15:56:57 INFO - 'log_level': 'info', 15:56:57 INFO - 'log_name': 'talos', 15:56:57 INFO - 'log_to_console': True, 15:56:57 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:56:57 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:56:57 INFO - 'opt_config_files': (), 15:56:57 INFO - 'pip_index': False, 15:56:57 INFO - 'sps_profile': False, 15:56:57 INFO - 'sps_profile_interval': 0, 15:56:57 INFO - 'suite': 'dromaeojs', 15:56:57 INFO - 'system_bits': '32', 15:56:57 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:56:57 INFO - 'title': 'talos-linux64-ix-045', 15:56:57 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:56:57 INFO - 'use_talos_json': True, 15:56:57 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:56:57 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:56:57 INFO - 'work_dir': 'build'} 15:56:57 INFO - ##### 15:56:57 INFO - ##### Running clobber step. 15:56:57 INFO - ##### 15:56:57 INFO - Running pre-action listener: _resource_record_pre_action 15:56:57 INFO - Running main action method: clobber 15:56:57 INFO - rmtree: /builds/slave/test/build 15:56:57 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:56:58 INFO - Running post-action listener: _resource_record_post_action 15:56:58 INFO - ##### 15:56:58 INFO - ##### Finished clobber step (success) 15:56:58 INFO - ##### 15:56:58 INFO - ##### 15:56:58 INFO - ##### Running read-buildbot-config step. 15:56:58 INFO - ##### 15:56:58 INFO - Running pre-action listener: _resource_record_pre_action 15:56:58 INFO - Running main action method: read_buildbot_config 15:56:58 INFO - Using buildbot properties: 15:56:58 INFO - { 15:56:58 INFO - "project": "", 15:56:58 INFO - "product": "firefox", 15:56:58 INFO - "script_repo_revision": "production", 15:56:58 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs", 15:56:58 INFO - "repository": "", 15:56:58 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs", 15:56:58 INFO - "buildid": "20160512133017", 15:56:58 INFO - "pgo_build": "True", 15:56:58 INFO - "basedir": "/builds/slave/test", 15:56:58 INFO - "buildnumber": 1805, 15:56:58 INFO - "slavename": "talos-linux64-ix-045", 15:56:58 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 15:56:58 INFO - "platform": "ubuntu64_hw", 15:56:58 INFO - "scheduler": "tests-mozilla-inbound-linux64-pgo-talos", 15:56:58 INFO - "branch": "mozilla-inbound", 15:56:58 INFO - "repo_path": "integration/mozilla-inbound", 15:56:58 INFO - "revision": "3967d009efc0ee734f4d2750d8fda2bbecca5d81", 15:56:58 INFO - "stage_platform": "linux64-pgo", 15:56:58 INFO - "builduid": "ed0cf45b4893495cbc32a4cfa5dae9cd", 15:56:58 INFO - "slavebuilddir": "test" 15:56:58 INFO - } 15:56:58 INFO - Found installer url https://queue.taskcluster.net/v1/task/BuaoIQ8_QJmIg2VxCtQepA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 15:56:58 INFO - Running post-action listener: _resource_record_post_action 15:56:58 INFO - ##### 15:56:58 INFO - ##### Finished read-buildbot-config step (success) 15:56:58 INFO - ##### 15:56:58 INFO - ##### 15:56:58 INFO - ##### Running download-and-extract step. 15:56:58 INFO - ##### 15:56:58 INFO - Running pre-action listener: _resource_record_pre_action 15:56:58 INFO - Running main action method: download_and_extract 15:56:58 INFO - mkdir: /builds/slave/test/build/tests 15:56:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:56:58 INFO - https://queue.taskcluster.net/v1/task/BuaoIQ8_QJmIg2VxCtQepA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 15:56:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BuaoIQ8_QJmIg2VxCtQepA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 15:56:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BuaoIQ8_QJmIg2VxCtQepA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 15:56:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BuaoIQ8_QJmIg2VxCtQepA/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:56:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BuaoIQ8_QJmIg2VxCtQepA/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:56:58 INFO - Downloaded 1361 bytes. 15:56:58 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 15:56:58 INFO - Using the following test package requirements: 15:56:58 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 15:56:58 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 15:56:58 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 15:56:58 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 15:56:58 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 15:56:58 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 15:56:58 INFO - u'jsshell-linux-x86_64.zip'], 15:56:58 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 15:56:58 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 15:56:58 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 15:56:58 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 15:56:58 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 15:56:58 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 15:56:58 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 15:56:58 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 15:56:58 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 15:56:58 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 15:56:58 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 15:56:58 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 15:56:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:56:58 INFO - https://queue.taskcluster.net/v1/task/BuaoIQ8_QJmIg2VxCtQepA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 15:56:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BuaoIQ8_QJmIg2VxCtQepA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 15:56:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BuaoIQ8_QJmIg2VxCtQepA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 15:56:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BuaoIQ8_QJmIg2VxCtQepA/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:56:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BuaoIQ8_QJmIg2VxCtQepA/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:57:00 INFO - Downloaded 23908978 bytes. 15:57:00 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:57:00 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:57:01 INFO - Return code: 0 15:57:01 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:57:01 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:57:01 INFO - https://queue.taskcluster.net/v1/task/BuaoIQ8_QJmIg2VxCtQepA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 15:57:01 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BuaoIQ8_QJmIg2VxCtQepA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 15:57:01 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BuaoIQ8_QJmIg2VxCtQepA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 15:57:01 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BuaoIQ8_QJmIg2VxCtQepA/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:57:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BuaoIQ8_QJmIg2VxCtQepA/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:57:03 INFO - Downloaded 23908978 bytes. 15:57:03 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:57:03 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:57:04 INFO - Return code: 0 15:57:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:57:04 INFO - https://queue.taskcluster.net/v1/task/BuaoIQ8_QJmIg2VxCtQepA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 15:57:04 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BuaoIQ8_QJmIg2VxCtQepA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 15:57:04 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BuaoIQ8_QJmIg2VxCtQepA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 15:57:04 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BuaoIQ8_QJmIg2VxCtQepA/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:57:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BuaoIQ8_QJmIg2VxCtQepA/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:57:06 INFO - Downloaded 10840239 bytes. 15:57:06 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:57:06 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:57:06 INFO - Return code: 0 15:57:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:57:06 INFO - https://queue.taskcluster.net/v1/task/BuaoIQ8_QJmIg2VxCtQepA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 15:57:06 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BuaoIQ8_QJmIg2VxCtQepA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 15:57:06 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BuaoIQ8_QJmIg2VxCtQepA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 15:57:06 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BuaoIQ8_QJmIg2VxCtQepA/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:57:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BuaoIQ8_QJmIg2VxCtQepA/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:57:08 INFO - Downloaded 58390756 bytes. 15:57:08 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/BuaoIQ8_QJmIg2VxCtQepA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 15:57:08 INFO - mkdir: /builds/slave/test/properties 15:57:08 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:57:08 INFO - Writing to file /builds/slave/test/properties/build_url 15:57:08 INFO - Contents: 15:57:08 INFO - build_url:https://queue.taskcluster.net/v1/task/BuaoIQ8_QJmIg2VxCtQepA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 15:57:09 INFO - Running post-action listener: _resource_record_post_action 15:57:09 INFO - Running post-action listener: set_extra_try_arguments 15:57:09 INFO - ##### 15:57:09 INFO - ##### Finished download-and-extract step (success) 15:57:09 INFO - ##### 15:57:09 INFO - ##### 15:57:09 INFO - ##### Running populate-webroot step. 15:57:09 INFO - ##### 15:57:09 INFO - Running pre-action listener: _resource_record_pre_action 15:57:09 INFO - Running main action method: populate_webroot 15:57:09 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:57:09 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:57:09 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:57:09 INFO - '--tppagecycles', 15:57:09 INFO - '7'], 15:57:09 INFO - 'tests': ['tsvgm']}}, 15:57:09 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:57:09 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:57:09 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:57:09 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:57:09 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:57:09 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:57:09 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:57:09 INFO - 'talos_options': ['--disable-e10s'], 15:57:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:57:09 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:57:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:57:09 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:57:09 INFO - 'talos_options': ['--disable-e10s'], 15:57:09 INFO - 'tests': ['damp', 'tps']}, 15:57:09 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:57:09 INFO - 'tests': ['damp', 'tps']}, 15:57:09 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:57:09 INFO - 'tests': ['dromaeo_dom']}, 15:57:09 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:57:09 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:57:09 INFO - 'tests': ['a11yr', 15:57:09 INFO - 'ts_paint', 15:57:09 INFO - 'tpaint', 15:57:09 INFO - 'sessionrestore', 15:57:09 INFO - 'sessionrestore_no_auto_restore', 15:57:09 INFO - 'tabpaint']}, 15:57:09 INFO - 'other-e10s': {'tests': ['a11yr', 15:57:09 INFO - 'ts_paint', 15:57:09 INFO - 'tpaint', 15:57:09 INFO - 'sessionrestore', 15:57:09 INFO - 'sessionrestore_no_auto_restore', 15:57:09 INFO - 'tabpaint']}, 15:57:09 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:57:09 INFO - 'ts_paint', 15:57:09 INFO - 'tpaint', 15:57:09 INFO - 'sessionrestore', 15:57:09 INFO - 'sessionrestore_no_auto_restore', 15:57:09 INFO - 'tabpaint']}, 15:57:09 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:57:09 INFO - 'ts_paint', 15:57:09 INFO - 'tpaint', 15:57:09 INFO - 'sessionrestore', 15:57:09 INFO - 'sessionrestore_no_auto_restore', 15:57:09 INFO - 'tabpaint']}, 15:57:09 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:57:09 INFO - 'tests': ['a11yr', 15:57:09 INFO - 'ts_paint', 15:57:09 INFO - 'tpaint', 15:57:09 INFO - 'sessionrestore', 15:57:09 INFO - 'sessionrestore_no_auto_restore', 15:57:09 INFO - 'tabpaint']}, 15:57:09 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:57:09 INFO - 'tests': ['a11yr', 15:57:09 INFO - 'ts_paint', 15:57:09 INFO - 'tpaint', 15:57:09 INFO - 'sessionrestore', 15:57:09 INFO - 'sessionrestore_no_auto_restore', 15:57:09 INFO - 'tabpaint']}, 15:57:09 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:57:09 INFO - 'tests': ['tsvgx', 15:57:09 INFO - 'tsvgr_opacity', 15:57:09 INFO - 'tart', 15:57:09 INFO - 'tscrollx', 15:57:09 INFO - 'cart']}, 15:57:09 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:57:09 INFO - 'tsvgr_opacity', 15:57:09 INFO - 'tart', 15:57:09 INFO - 'tscrollx', 15:57:09 INFO - 'cart']}, 15:57:09 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:57:09 INFO - 'talos_options': ['--disable-e10s'], 15:57:09 INFO - 'tests': ['tp5o']}, 15:57:09 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:57:09 INFO - 'tests': ['tp5o']}, 15:57:09 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:57:09 INFO - 'talos_options': ['--disable-e10s', 15:57:09 INFO - '--xperf_path', 15:57:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:57:09 INFO - 'tests': ['tp5n']}, 15:57:09 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:57:09 INFO - 'talos_options': ['--xperf_path', 15:57:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:57:09 INFO - 'tests': ['tp5n']}}, 15:57:09 INFO - 'talos.zip': {'path': '', 15:57:09 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:57:09 INFO - Running post-action listener: _resource_record_post_action 15:57:09 INFO - ##### 15:57:09 INFO - ##### Finished populate-webroot step (success) 15:57:09 INFO - ##### 15:57:09 INFO - ##### 15:57:09 INFO - ##### Running create-virtualenv step. 15:57:09 INFO - ##### 15:57:09 INFO - Running pre-action listener: _resource_record_pre_action 15:57:09 INFO - Running main action method: create_virtualenv 15:57:09 INFO - Creating virtualenv /builds/slave/test/build/venv 15:57:09 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:57:09 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:57:09 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:57:09 INFO - Using real prefix '/usr' 15:57:09 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:57:09 INFO - Installing distribute.............................................................................................................................................................................................done. 15:57:11 INFO - Installing pip.................done. 15:57:11 INFO - Return code: 0 15:57:11 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:57: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:57:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:57:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:57: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:57:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:57:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:57:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f4f28a37f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20a78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2172770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22019c0>, '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 0x22028c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2202d50>, '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': '4300f881736b422476174874000001bc-1463093815.836123-1850221183', '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:57:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 15:57:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 15:57:11 INFO - Using env: {'DISPLAY': ':0', 15:57:11 INFO - 'HOME': '/home/cltbld', 15:57:11 INFO - 'LANG': 'en_US.UTF-8', 15:57:11 INFO - 'LANGUAGE': 'en_US:en', 15:57:11 INFO - 'LOGNAME': 'cltbld', 15:57:11 INFO - 'MAIL': '/var/mail/cltbld', 15:57:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:57:11 INFO - 'MOZ_NO_REMOTE': '1', 15:57:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:57:11 INFO - 'NO_EM_RESTART': '1', 15:57:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:57:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:57:11 INFO - 'PWD': '/builds/slave/test', 15:57:11 INFO - 'SHELL': '/bin/bash', 15:57:11 INFO - 'SHLVL': '1', 15:57:11 INFO - 'TERM': 'linux', 15:57:11 INFO - 'TMOUT': '86400', 15:57:11 INFO - 'USER': 'cltbld', 15:57:11 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1463093815.836123-1850221183', 15:57:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:57:11 INFO - '_': '/tools/buildbot/bin/python'} 15:57:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:57:11 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:57:11 INFO - Cleaning up... 15:57:11 INFO - Return code: 0 15:57:11 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 15:57: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:57:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:57:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:57: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:57:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:57:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:57:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f4f28a37f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20a78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2172770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22019c0>, '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 0x22028c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2202d50>, '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': '4300f881736b422476174874000001bc-1463093815.836123-1850221183', '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:57:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 15:57:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 15:57:11 INFO - Using env: {'DISPLAY': ':0', 15:57:11 INFO - 'HOME': '/home/cltbld', 15:57:11 INFO - 'LANG': 'en_US.UTF-8', 15:57:11 INFO - 'LANGUAGE': 'en_US:en', 15:57:11 INFO - 'LOGNAME': 'cltbld', 15:57:11 INFO - 'MAIL': '/var/mail/cltbld', 15:57:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:57:11 INFO - 'MOZ_NO_REMOTE': '1', 15:57:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:57:11 INFO - 'NO_EM_RESTART': '1', 15:57:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:57:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:57:11 INFO - 'PWD': '/builds/slave/test', 15:57:11 INFO - 'SHELL': '/bin/bash', 15:57:11 INFO - 'SHLVL': '1', 15:57:11 INFO - 'TERM': 'linux', 15:57:11 INFO - 'TMOUT': '86400', 15:57:11 INFO - 'USER': 'cltbld', 15:57:11 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1463093815.836123-1850221183', 15:57:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:57:11 INFO - '_': '/tools/buildbot/bin/python'} 15:57:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:57:12 INFO - Downloading/unpacking psutil>=0.7.1 15:57: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:57: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:57: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:57: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:57: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:57: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:57:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:57:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:57:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:57:14 INFO - Installing collected packages: psutil 15:57:14 INFO - Running setup.py install for psutil 15:57:14 INFO - building 'psutil._psutil_linux' extension 15:57:14 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:57:14 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:57:14 INFO - building 'psutil._psutil_posix' extension 15:57:14 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:57:14 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:57:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:57:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:57:14 INFO - Successfully installed psutil 15:57:14 INFO - Cleaning up... 15:57:14 INFO - Return code: 0 15:57:14 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 15:57: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:57:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:57:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:57: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:57:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:57:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:57: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', '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 0x7f4f28a37f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20a78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2172770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22019c0>, '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 0x22028c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2202d50>, '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': '4300f881736b422476174874000001bc-1463093815.836123-1850221183', '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:57: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', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 15:57: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 mozsystemmonitor==0.0.0 15:57:14 INFO - Using env: {'DISPLAY': ':0', 15:57:14 INFO - 'HOME': '/home/cltbld', 15:57:14 INFO - 'LANG': 'en_US.UTF-8', 15:57:14 INFO - 'LANGUAGE': 'en_US:en', 15:57:14 INFO - 'LOGNAME': 'cltbld', 15:57:14 INFO - 'MAIL': '/var/mail/cltbld', 15:57:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:57:14 INFO - 'MOZ_NO_REMOTE': '1', 15:57:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:57:14 INFO - 'NO_EM_RESTART': '1', 15:57:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:57:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:57:14 INFO - 'PWD': '/builds/slave/test', 15:57:14 INFO - 'SHELL': '/bin/bash', 15:57:14 INFO - 'SHLVL': '1', 15:57:14 INFO - 'TERM': 'linux', 15:57:14 INFO - 'TMOUT': '86400', 15:57:14 INFO - 'USER': 'cltbld', 15:57:14 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1463093815.836123-1850221183', 15:57:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:57:14 INFO - '_': '/tools/buildbot/bin/python'} 15:57:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:57:14 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 15:57: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:57: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:57: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:57: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:57: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:57: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:57:16 INFO - Downloading mozsystemmonitor-0.0.tar.gz 15:57:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:57:16 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:57:16 INFO - Installing collected packages: mozsystemmonitor 15:57:16 INFO - Running setup.py install for mozsystemmonitor 15:57:16 INFO - Successfully installed mozsystemmonitor 15:57:16 INFO - Cleaning up... 15:57:16 INFO - Return code: 0 15:57:16 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:57:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:57:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:57:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:57:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:57:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:57:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:57:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f4f28a37f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20a78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2172770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22019c0>, '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 0x22028c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2202d50>, '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': '4300f881736b422476174874000001bc-1463093815.836123-1850221183', '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:57:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 15:57:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 15:57:16 INFO - Using env: {'DISPLAY': ':0', 15:57:16 INFO - 'HOME': '/home/cltbld', 15:57:16 INFO - 'LANG': 'en_US.UTF-8', 15:57:16 INFO - 'LANGUAGE': 'en_US:en', 15:57:16 INFO - 'LOGNAME': 'cltbld', 15:57:16 INFO - 'MAIL': '/var/mail/cltbld', 15:57:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:57:16 INFO - 'MOZ_NO_REMOTE': '1', 15:57:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:57:16 INFO - 'NO_EM_RESTART': '1', 15:57:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:57:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:57:16 INFO - 'PWD': '/builds/slave/test', 15:57:16 INFO - 'SHELL': '/bin/bash', 15:57:16 INFO - 'SHLVL': '1', 15:57:16 INFO - 'TERM': 'linux', 15:57:16 INFO - 'TMOUT': '86400', 15:57:16 INFO - 'USER': 'cltbld', 15:57:16 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1463093815.836123-1850221183', 15:57:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:57:16 INFO - '_': '/tools/buildbot/bin/python'} 15:57:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:57:16 INFO - Downloading/unpacking blobuploader==1.2.4 15:57: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:57: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:57: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:57: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:57: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:57: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:57:18 INFO - Downloading blobuploader-1.2.4.tar.gz 15:57:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:57:18 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:57:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:57:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:57:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:57:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:57:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:57:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:57:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:57:19 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:57: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:57: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:57: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:57: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:57: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:57: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:57:19 INFO - Downloading docopt-0.6.1.tar.gz 15:57:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:57:19 INFO - Installing collected packages: blobuploader, requests, docopt 15:57:19 INFO - Running setup.py install for blobuploader 15:57:19 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:57:19 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:57:19 INFO - Running setup.py install for requests 15:57:19 INFO - Running setup.py install for docopt 15:57:19 INFO - Successfully installed blobuploader requests docopt 15:57:19 INFO - Cleaning up... 15:57:19 INFO - Return code: 0 15:57:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:57:19 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:57:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:57:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:57:19 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:57:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:57:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:57:19 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 0x7f4f28a37f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20a78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2172770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22019c0>, '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 0x22028c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2202d50>, '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': '4300f881736b422476174874000001bc-1463093815.836123-1850221183', '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:57:19 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:57:19 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:57:19 INFO - Using env: {'DISPLAY': ':0', 15:57:19 INFO - 'HOME': '/home/cltbld', 15:57:19 INFO - 'LANG': 'en_US.UTF-8', 15:57:19 INFO - 'LANGUAGE': 'en_US:en', 15:57:19 INFO - 'LOGNAME': 'cltbld', 15:57:19 INFO - 'MAIL': '/var/mail/cltbld', 15:57:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:57:19 INFO - 'MOZ_NO_REMOTE': '1', 15:57:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:57:19 INFO - 'NO_EM_RESTART': '1', 15:57:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:57:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:57:19 INFO - 'PWD': '/builds/slave/test', 15:57:19 INFO - 'SHELL': '/bin/bash', 15:57:19 INFO - 'SHLVL': '1', 15:57:19 INFO - 'TERM': 'linux', 15:57:19 INFO - 'TMOUT': '86400', 15:57:19 INFO - 'USER': 'cltbld', 15:57:19 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1463093815.836123-1850221183', 15:57:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:57:19 INFO - '_': '/tools/buildbot/bin/python'} 15:57:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:57:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:57:20 INFO - Running setup.py (path:/tmp/pip-ND4Nq7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:57:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:57:20 INFO - Running setup.py (path:/tmp/pip-JfidBZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:57:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:57:20 INFO - Running setup.py (path:/tmp/pip-vVn605-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:57:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:57:20 INFO - Running setup.py (path:/tmp/pip-m1uYPc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:57:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:57:20 INFO - Running setup.py (path:/tmp/pip-wwBUbG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:57:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:57:20 INFO - Running setup.py (path:/tmp/pip-ykhhQJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:57:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:57:20 INFO - Running setup.py (path:/tmp/pip-8jY_lZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:57:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:57:20 INFO - Running setup.py (path:/tmp/pip-EYuaXd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:57:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:57:20 INFO - Running setup.py (path:/tmp/pip-t122BN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:57:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:57:20 INFO - Running setup.py (path:/tmp/pip-wImiIe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:57:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:57:20 INFO - Running setup.py (path:/tmp/pip-Tb4HnT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:57:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:57:20 INFO - Running setup.py (path:/tmp/pip-C7CPpC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:57:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:57:20 INFO - Running setup.py (path:/tmp/pip-MbNvPL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:57:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:57:20 INFO - Running setup.py (path:/tmp/pip-C3KTQn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:57:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:57:20 INFO - Running setup.py (path:/tmp/pip-GRl1zB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:57:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:57:21 INFO - Running setup.py (path:/tmp/pip-D16R4C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:57:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:57:21 INFO - Running setup.py (path:/tmp/pip-fcBg1Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:57:21 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:57:21 INFO - Running setup.py install for manifestparser 15:57:21 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:57:21 INFO - Running setup.py install for mozcrash 15:57:21 INFO - Running setup.py install for mozdebug 15:57:21 INFO - Running setup.py install for mozdevice 15:57:21 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:57:21 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:57:21 INFO - Running setup.py install for mozfile 15:57:21 INFO - Running setup.py install for mozhttpd 15:57:21 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:57:21 INFO - Running setup.py install for mozinfo 15:57:21 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:57:21 INFO - Running setup.py install for mozInstall 15:57:21 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:57:21 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:57:21 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:57:21 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:57:21 INFO - Running setup.py install for mozleak 15:57:21 INFO - Running setup.py install for mozlog 15:57:22 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:57:22 INFO - Running setup.py install for moznetwork 15:57:22 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:57:22 INFO - Running setup.py install for mozprocess 15:57:22 INFO - Running setup.py install for mozprofile 15:57:22 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:57:22 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:57:22 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:57:22 INFO - Running setup.py install for mozrunner 15:57:22 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:57:22 INFO - Running setup.py install for mozscreenshot 15:57:22 INFO - Running setup.py install for moztest 15:57:22 INFO - Running setup.py install for mozversion 15:57:22 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:57:22 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:57:22 INFO - Cleaning up... 15:57:22 INFO - Return code: 0 15:57:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:57:22 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:57:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:57:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:57:22 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:57:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:57:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:57:22 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 0x7f4f28a37f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20a78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2172770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22019c0>, '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 0x22028c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2202d50>, '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': '4300f881736b422476174874000001bc-1463093815.836123-1850221183', '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:57:22 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:57:22 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:57:22 INFO - Using env: {'DISPLAY': ':0', 15:57:22 INFO - 'HOME': '/home/cltbld', 15:57:22 INFO - 'LANG': 'en_US.UTF-8', 15:57:22 INFO - 'LANGUAGE': 'en_US:en', 15:57:22 INFO - 'LOGNAME': 'cltbld', 15:57:22 INFO - 'MAIL': '/var/mail/cltbld', 15:57:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:57:22 INFO - 'MOZ_NO_REMOTE': '1', 15:57:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:57:22 INFO - 'NO_EM_RESTART': '1', 15:57:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:57:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:57:22 INFO - 'PWD': '/builds/slave/test', 15:57:22 INFO - 'SHELL': '/bin/bash', 15:57:22 INFO - 'SHLVL': '1', 15:57:22 INFO - 'TERM': 'linux', 15:57:22 INFO - 'TMOUT': '86400', 15:57:22 INFO - 'USER': 'cltbld', 15:57:22 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1463093815.836123-1850221183', 15:57:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:57:22 INFO - '_': '/tools/buildbot/bin/python'} 15:57:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:57:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:57:22 INFO - Running setup.py (path:/tmp/pip-9dXEci-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:57:23 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:57:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:57:23 INFO - Running setup.py (path:/tmp/pip-qQu7ux-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:57:23 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:57:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:57:23 INFO - Running setup.py (path:/tmp/pip-NdVMdq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:57:23 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:57:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:57:23 INFO - Running setup.py (path:/tmp/pip-rjOsfd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:57:23 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:57:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:57:23 INFO - Running setup.py (path:/tmp/pip-l6s8oF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:57:23 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:57:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:57:23 INFO - Running setup.py (path:/tmp/pip-9oICEW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:57:23 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:57:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:57:23 INFO - Running setup.py (path:/tmp/pip-WAHCDd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:57:23 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:57:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:57:23 INFO - Running setup.py (path:/tmp/pip-dbKPH7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:57:23 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:57:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:57:23 INFO - Running setup.py (path:/tmp/pip-sWPwc7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:57:23 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:57:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:57:23 INFO - Running setup.py (path:/tmp/pip-nLYBGR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:57:23 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:57:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:57:23 INFO - Running setup.py (path:/tmp/pip-3DW2nu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:57:23 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:57:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:57:23 INFO - Running setup.py (path:/tmp/pip-PZuapa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:57:23 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:57:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:57:23 INFO - Running setup.py (path:/tmp/pip-2i9dSP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:57:23 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:57:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:57:23 INFO - Running setup.py (path:/tmp/pip-V4wm11-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:57:23 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:57:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:57:23 INFO - Running setup.py (path:/tmp/pip-HszkSr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:57:23 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:57:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:57:23 INFO - Running setup.py (path:/tmp/pip-6vo1uP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:57:23 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:57:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:57:23 INFO - Running setup.py (path:/tmp/pip-WdcEzW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:57:23 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:57:23 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:57:23 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:57:23 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:57:23 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:57:23 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:57:23 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:57:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:57:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:57:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:57:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:57:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:57:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:57:25 INFO - Downloading blessings-1.6.tar.gz 15:57:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:57:25 INFO - Installing collected packages: blessings 15:57:25 INFO - Running setup.py install for blessings 15:57:25 INFO - Successfully installed blessings 15:57:25 INFO - Cleaning up... 15:57:26 INFO - Return code: 0 15:57:26 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:57:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:57:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:57:26 INFO - Reading from file tmpfile_stdout 15:57:26 INFO - Current package versions: 15:57:26 INFO - argparse == 1.2.1 15:57:26 INFO - blessings == 1.6 15:57:26 INFO - blobuploader == 1.2.4 15:57:26 INFO - docopt == 0.6.1 15:57:26 INFO - manifestparser == 1.1 15:57:26 INFO - mozInstall == 1.12 15:57:26 INFO - mozcrash == 0.17 15:57:26 INFO - mozdebug == 0.1 15:57:26 INFO - mozdevice == 0.48 15:57:26 INFO - mozfile == 1.2 15:57:26 INFO - mozhttpd == 0.7 15:57:26 INFO - mozinfo == 0.9 15:57:26 INFO - mozleak == 0.1 15:57:26 INFO - mozlog == 3.2 15:57:26 INFO - moznetwork == 0.27 15:57:26 INFO - mozprocess == 0.22 15:57:26 INFO - mozprofile == 0.28 15:57:26 INFO - mozrunner == 6.11 15:57:26 INFO - mozscreenshot == 0.1 15:57:26 INFO - mozsystemmonitor == 0.0 15:57:26 INFO - moztest == 0.7 15:57:26 INFO - mozversion == 1.4 15:57:26 INFO - psutil == 3.1.1 15:57:26 INFO - requests == 1.2.3 15:57:26 INFO - wsgiref == 0.1.2 15:57:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:57:26 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:57:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:57:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:57:26 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:57:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:57:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:57:26 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 0x7f4f28a37f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20a78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2172770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22019c0>, '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 0x22028c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2202d50>, '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': '4300f881736b422476174874000001bc-1463093815.836123-1850221183', '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:57:26 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:57:26 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:57:26 INFO - Using env: {'DISPLAY': ':0', 15:57:26 INFO - 'HOME': '/home/cltbld', 15:57:26 INFO - 'LANG': 'en_US.UTF-8', 15:57:26 INFO - 'LANGUAGE': 'en_US:en', 15:57:26 INFO - 'LOGNAME': 'cltbld', 15:57:26 INFO - 'MAIL': '/var/mail/cltbld', 15:57:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:57:26 INFO - 'MOZ_NO_REMOTE': '1', 15:57:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:57:26 INFO - 'NO_EM_RESTART': '1', 15:57:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:57:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:57:26 INFO - 'PWD': '/builds/slave/test', 15:57:26 INFO - 'SHELL': '/bin/bash', 15:57:26 INFO - 'SHLVL': '1', 15:57:26 INFO - 'TERM': 'linux', 15:57:26 INFO - 'TMOUT': '86400', 15:57:26 INFO - 'USER': 'cltbld', 15:57:26 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1463093815.836123-1850221183', 15:57:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:57:26 INFO - '_': '/tools/buildbot/bin/python'} 15:57:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:57:26 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:57:26 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:57:26 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:57:26 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:57:26 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:57:26 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:57:26 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:57:26 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:57:26 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:57:26 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:57:26 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:57:26 INFO - Cleaning up... 15:57:26 INFO - Return code: 0 15:57:26 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:57:26 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:57:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:57:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:57:26 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:57:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:57:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:57:26 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 0x7f4f28a37f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20a78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2172770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22019c0>, '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 0x22028c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2202d50>, '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': '4300f881736b422476174874000001bc-1463093815.836123-1850221183', '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:57:26 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:57:26 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:57:26 INFO - Using env: {'DISPLAY': ':0', 15:57:26 INFO - 'HOME': '/home/cltbld', 15:57:26 INFO - 'LANG': 'en_US.UTF-8', 15:57:26 INFO - 'LANGUAGE': 'en_US:en', 15:57:26 INFO - 'LOGNAME': 'cltbld', 15:57:26 INFO - 'MAIL': '/var/mail/cltbld', 15:57:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:57:26 INFO - 'MOZ_NO_REMOTE': '1', 15:57:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:57:26 INFO - 'NO_EM_RESTART': '1', 15:57:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:57:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:57:26 INFO - 'PWD': '/builds/slave/test', 15:57:26 INFO - 'SHELL': '/bin/bash', 15:57:26 INFO - 'SHLVL': '1', 15:57:26 INFO - 'TERM': 'linux', 15:57:26 INFO - 'TMOUT': '86400', 15:57:26 INFO - 'USER': 'cltbld', 15:57:26 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1463093815.836123-1850221183', 15:57:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:57:26 INFO - '_': '/tools/buildbot/bin/python'} 15:57:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:57:26 INFO - Downloading/unpacking jsonschema 15:57:26 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:57:26 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:57:26 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:57:26 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:57:26 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:57:26 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:57:28 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:57:28 INFO - Downloading/unpacking functools32 (from jsonschema) 15:57:28 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:57:28 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:57:28 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:57:28 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:57:28 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:57:28 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:57:28 INFO - Downloading functools32-3.2.3-2.tar.gz 15:57:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:57:28 INFO - warning: no files found matching '*.txt' 15:57:28 INFO - no previously-included directories found matching 'build' 15:57:28 INFO - no previously-included directories found matching 'dist' 15:57:28 INFO - no previously-included directories found matching '.git*' 15:57:28 INFO - Installing collected packages: jsonschema, functools32 15:57:28 INFO - Running setup.py install for functools32 15:57:28 INFO - warning: no files found matching '*.txt' 15:57:28 INFO - no previously-included directories found matching 'build' 15:57:28 INFO - no previously-included directories found matching 'dist' 15:57:28 INFO - no previously-included directories found matching '.git*' 15:57:28 INFO - Successfully installed jsonschema functools32 15:57:28 INFO - Cleaning up... 15:57:28 INFO - Return code: 0 15:57:28 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 15:57:28 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:57:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:57:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:57:28 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:57:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:57:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:57:28 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 0x7f4f28a37f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20a78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2172770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22019c0>, '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 0x22028c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2202d50>, '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': '4300f881736b422476174874000001bc-1463093815.836123-1850221183', '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:57:28 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:57:28 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:57:28 INFO - Using env: {'DISPLAY': ':0', 15:57:28 INFO - 'HOME': '/home/cltbld', 15:57:28 INFO - 'LANG': 'en_US.UTF-8', 15:57:28 INFO - 'LANGUAGE': 'en_US:en', 15:57:28 INFO - 'LOGNAME': 'cltbld', 15:57:28 INFO - 'MAIL': '/var/mail/cltbld', 15:57:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:57:28 INFO - 'MOZ_NO_REMOTE': '1', 15:57:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:57:28 INFO - 'NO_EM_RESTART': '1', 15:57:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:57:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:57:28 INFO - 'PWD': '/builds/slave/test', 15:57:28 INFO - 'SHELL': '/bin/bash', 15:57:28 INFO - 'SHLVL': '1', 15:57:28 INFO - 'TERM': 'linux', 15:57:28 INFO - 'TMOUT': '86400', 15:57:28 INFO - 'USER': 'cltbld', 15:57:28 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1463093815.836123-1850221183', 15:57:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:57:28 INFO - '_': '/tools/buildbot/bin/python'} 15:57:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:57:29 INFO - Downloading/unpacking flake8 15:57:29 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:57:29 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:57:29 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:57:29 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:57:29 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:57:29 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:57:30 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 15:57:30 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 15:57:30 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:57:30 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:57:30 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:57:30 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:57:30 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:57:30 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:57:31 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 15:57:31 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 15:57:31 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:57:31 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:57:31 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:57:31 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:57:31 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:57:31 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:57:31 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 15:57:31 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:57:31 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:57:31 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:57:31 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:57:31 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:57:31 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:57:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 15:57:31 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:57:31 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:57:31 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:57:31 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:57:31 INFO - no previously-included directories found matching 'docs/_build' 15:57:31 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 15:57:31 INFO - Running setup.py install for pep8 15:57:31 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:57:31 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:57:31 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:57:31 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:57:31 INFO - no previously-included directories found matching 'docs/_build' 15:57:31 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 15:57:31 INFO - Successfully installed flake8 mccabe pyflakes pep8 15:57:31 INFO - Cleaning up... 15:57:31 INFO - Return code: 0 15:57:31 INFO - Running post-action listener: _resource_record_post_action 15:57:31 INFO - Running post-action listener: _start_resource_monitoring 15:57:31 INFO - Starting resource monitoring. 15:57:31 INFO - ##### 15:57:31 INFO - ##### Finished create-virtualenv step (success) 15:57:31 INFO - ##### 15:57:31 INFO - ##### 15:57:31 INFO - ##### Running install step. 15:57:31 INFO - ##### 15:57:31 INFO - Running pre-action listener: _resource_record_pre_action 15:57:31 INFO - Running main action method: install 15:57:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:57:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:57:32 INFO - Reading from file tmpfile_stdout 15:57:32 INFO - Detecting whether we're running mozinstall >=1.0... 15:57:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:57:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:57:32 INFO - Reading from file tmpfile_stdout 15:57:32 INFO - Output received: 15:57:32 INFO - Usage: mozinstall [options] installer 15:57:32 INFO - Options: 15:57:32 INFO - -h, --help show this help message and exit 15:57:32 INFO - -d DEST, --destination=DEST 15:57:32 INFO - Directory to install application into. [default: 15:57:32 INFO - "/builds/slave/test"] 15:57:32 INFO - --app=APP Application being installed. [default: firefox] 15:57:32 INFO - mkdir: /builds/slave/test/build/application 15:57:32 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:57:32 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:57:48 INFO - Reading from file tmpfile_stdout 15:57:48 INFO - Output received: 15:57:48 INFO - /builds/slave/test/build/application/firefox/firefox 15:57:48 INFO - Running post-action listener: _resource_record_post_action 15:57:48 INFO - ##### 15:57:48 INFO - ##### Finished install step (success) 15:57:48 INFO - ##### 15:57:48 INFO - ##### 15:57:48 INFO - ##### Running run-tests step. 15:57:48 INFO - ##### 15:57:48 INFO - Running pre-action listener: _resource_record_pre_action 15:57:48 INFO - Running main action method: run_tests 15:57:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:57:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:57:48 INFO - Python 2.7.3 15:57:48 INFO - Return code: 0 15:57:48 INFO - grabbing minidump binary from tooltool 15:57:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:57:48 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 0x22019c0>, '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 0x22028c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2202d50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:57:48 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:57:48 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:57:48 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:57:48 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:57:48 INFO - Return code: 0 15:57:48 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 15:57:48 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:57:48 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:57:48 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 15:57:48 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:57:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 15:57:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 15:57:49 INFO - Return code: 0 15:57:49 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/BuaoIQ8_QJmIg2VxCtQepA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-045', '--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:57:49 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/BuaoIQ8_QJmIg2VxCtQepA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-045 --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:57:49 INFO - Using env: {'DISPLAY': ':0', 15:57:49 INFO - 'HOME': '/home/cltbld', 15:57:49 INFO - 'LANG': 'en_US.UTF-8', 15:57:49 INFO - 'LANGUAGE': 'en_US:en', 15:57:49 INFO - 'LOGNAME': 'cltbld', 15:57:49 INFO - 'MAIL': '/var/mail/cltbld', 15:57:49 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:57:49 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:57:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:57:49 INFO - 'MOZ_NO_REMOTE': '1', 15:57:49 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:57:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:57:49 INFO - 'NO_EM_RESTART': '1', 15:57:49 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:57:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:57:49 INFO - 'PWD': '/builds/slave/test', 15:57:49 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:57:49 INFO - 'SHELL': '/bin/bash', 15:57:49 INFO - 'SHLVL': '1', 15:57:49 INFO - 'TERM': 'linux', 15:57:49 INFO - 'TMOUT': '86400', 15:57:49 INFO - 'USER': 'cltbld', 15:57:49 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1463093815.836123-1850221183', 15:57:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:57:49 INFO - '_': '/tools/buildbot/bin/python'} 15:57:49 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/BuaoIQ8_QJmIg2VxCtQepA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-045', '--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:57:49 INFO - mozversion application_buildid: 20160512133017 15:57:49 INFO - mozversion application_changeset: 3967d009efc0ee734f4d2750d8fda2bbecca5d81 15:57:49 INFO - mozversion application_display_name: Nightly 15:57:49 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:57:49 INFO - mozversion application_name: Firefox 15:57:49 INFO - mozversion application_remotingname: firefox 15:57:49 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 15:57:49 INFO - mozversion application_vendor: Mozilla 15:57:49 INFO - mozversion application_version: 49.0a1 15:57:49 INFO - mozversion platform_buildid: 20160512133017 15:57:49 INFO - mozversion platform_changeset: 3967d009efc0ee734f4d2750d8fda2bbecca5d81 15:57:49 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 15:57:49 INFO - mozversion platform_version: 49.0a1 15:57:49 INFO - using testdate: 1463093869 15:57:49 INFO - actual date: 1463093869 15:57:49 INFO - starting webserver on 'localhost:59952' 15:57:49 INFO - SUITE-START | Running 2 tests 15:57:49 INFO - TEST-START | dromaeo_css 15:57:49 INFO - operating with platform_type : linux_ 15:57:49 INFO - Initialising browser for dromaeo_css test... 15:57:49 INFO - TEST-INFO | started process 22929 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpEwfjJS/profile http://localhost:59952/getInfo.html) 15:57:53 INFO - PROCESS | 22929 | __metrics Screen width/height:1600/1200 15:57:53 INFO - PROCESS | 22929 | colorDepth:24 15:57:53 INFO - PROCESS | 22929 | Browser inner width/height: 1024/697 15:57:53 INFO - PROCESS | 22929 | __metrics 15:57:54 ERROR - PROCESS | 22929 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 15:57:55 INFO - TEST-INFO | 22929: exit 0 15:57:55 INFO - Browser initialized. 15:57:55 INFO - Running cycle 1/1 for dromaeo_css test... 15:57:55 INFO - TEST-INFO | started process 23010 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpEwfjJS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:58:06 INFO - PROCESS | 23010 | RSS: Main: 150966272 15:58:06 INFO - PROCESS | 23010 | 15:58:58 INFO - PROCESS | 23010 | 1463093938053 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] 16:01:06 INFO - PROCESS | 23010 | Cycle 1(1): loaded http://localhost:59952/tests/dromaeo/cssquery-dojo.html (next: http://localhost:59952/tests/dromaeo/cssquery-ext.html) 16:01:07 INFO - PROCESS | 23010 | RSS: Main: 253251584 16:01:07 INFO - PROCESS | 23010 | 16:04:09 INFO - PROCESS | 23010 | Cycle 1(1): loaded http://localhost:59952/tests/dromaeo/cssquery-ext.html (next: http://localhost:59952/tests/dromaeo/cssquery-jquery.html) 16:04:09 INFO - PROCESS | 23010 | RSS: Main: 239964160 16:04:09 INFO - PROCESS | 23010 | 16:07:11 INFO - PROCESS | 23010 | Cycle 1(1): loaded http://localhost:59952/tests/dromaeo/cssquery-jquery.html (next: http://localhost:59952/tests/dromaeo/cssquery-mootools.html) 16:07:12 INFO - PROCESS | 23010 | RSS: Main: 237113344 16:07:12 INFO - PROCESS | 23010 | 16:10:14 INFO - PROCESS | 23010 | Cycle 1(1): loaded http://localhost:59952/tests/dromaeo/cssquery-mootools.html (next: http://localhost:59952/tests/dromaeo/cssquery-prototype.html) 16:10:14 INFO - PROCESS | 23010 | RSS: Main: 230580224 16:10:14 INFO - PROCESS | 23010 | 16:13:17 INFO - PROCESS | 23010 | Cycle 1(1): loaded http://localhost:59952/tests/dromaeo/cssquery-prototype.html (next: http://localhost:59952/tests/dromaeo/cssquery-yui.html) 16:13:18 INFO - PROCESS | 23010 | RSS: Main: 223592448 16:13:18 INFO - PROCESS | 23010 | 16:16:20 INFO - PROCESS | 23010 | Cycle 1(1): loaded http://localhost:59952/tests/dromaeo/cssquery-yui.html (next: http://localhost:59952/tests/dromaeo/cssquery-dojo.html) 16:16:20 INFO - PROCESS | 23010 | RSS: Main: 321986560 16:16:20 INFO - PROCESS | 23010 | 16:16:20 INFO - PROCESS | 23010 | __start_tp_report 16:16:20 INFO - PROCESS | 23010 | _x_x_mozilla_page_load 16:16:20 INFO - PROCESS | 23010 | _x_x_mozilla_page_load_details 16:16:20 INFO - PROCESS | 23010 | |i|pagename|runs| 16:16:20 INFO - PROCESS | 23010 | |0;dojo.html;3167.271527548664;3169.0651257878926;3197.152754520052;3200.9116900253907;3226.612806463225;5160.638755287131;5238.292830467885;5346.866328341788;5349.3045904032515;5378.865528361788;5321.3348331458565;5331.946680533193;5335.119705248639;5350.759215835287;5361.329833770778;4764.999999999991;4780.641451891105;4884.267359896008;4924.039812236615;5007.324011258488;4879.072976134523;4882.97558512207;4895;4957.305977163182;4959.156943319645;4702.177119004177;4797.448293446263;4799.328094066834;4832.444268909086;4836.613070954315;4837.685550439211;4840.5159484051665;4892.706437613719;4903.607711383081;4915.950840491585;4789.161896668104;4834.613230941516;4848.757562121879;4850.029994001178;4878.682885612459;5075.949240507584;5161.767720452589;5218.739063046833;5222;5287.894242115136;5686.431356864322;5688.459596338341;5707.3721890592005;5727.198192253088;5729.197912292282;5494.615376923617;5777.7977770777825;5781.103928891029;5786.1610066540115;5817.0401883689565;5096.694198348111;5241.0828105081455;5340.866478338034;5378.462153784591;5383.838484845461;5604.187392828017;5783.248177736951;5867.823965281048;5903.616264942765;5918.615290006136;5420.295361603045;5547.0570003098765;5601.691906945076;5608.242887210175;5664.065429204217;5702.173184888251;5735.627184233012;5778.451047150514;5792.102224155263;5839.036558967808;5686;5699.914501282484;5712.314522257273;5717.456841600041;5723.313202415737;5511.476409741148;5592;5635.0702134147405;5716.885662286731;5735.340435849847;4272.594103560219;4309.5044069932355;4311.094670119247;4315.892102697395;4358.607725304738;3822.5604055533413;3831.8658846940225;3868.323043467382;3883.475730776309;3892.1048158923604;5201.245819356172;5216.686998780086;5246.973765131226;5295.894082118336;5322.946770532322;5586.16207568868;5643.927653745775;5650.378458369576;5702.2301989231755;5706.229658996065;5079.263506791494;5311;5334.946650533445;5336.733163341817;5375.946240537544;4744.4069491313585;4770.61835053195;4796.304535842353;4822.300766388854;4880.6583539152625;5899.882002360015;5911.142884281755;5912.113183022495;5924.407559244041;5926.4666180044005;5527.336719593513;5711.057675483498;5745.2818397700285;5752.367239603724;5773.53811695055;5839.270091238595;5856.385079566755;5893.02765043763;5907.468327850343;5927.081302398135;5635.239242702347;5677.091665333527;5677.1768093627015;5684.459976302244;5695;5449.536789372789;5669.914951275817;5785;5785.566082543742;5803.767849285981;5587.1339942309005;5596.076647353141;5606.074997625346;5614.326280846161;5671.6029877909805;5510.586705996986;5579.944200557942;5589.860253493695;5600.635958662674;5610.298712660918;5325.520703136582;5413.9729301353245;5453.045716999589;5525.751341189576;5564.381563302531;5622.606417550732;5662.546996240373;5695.686737229326;5699.658020518782;5710.828675139753;5490.945090549203;5494.560435165257;5505.9724701376235;5514.80698175562;5519.558435325084 16:16:20 INFO - PROCESS | 23010 | |1;ext.html;550.7576666266842;585.3502612100569;594.2749845188873;604.4016423740499;623.9064140378944;32795.67204327956;43785.78107109468;44971.10057798846;45165.160961318325;45186;45534.77232613832;46041;46131.76934115333;46207.30689039667;46278;7978.361731061501;8869.423487473327;9081.54592270384;10301.227407944396;10587.523561439753;10394.376337419779;10517.211209159306;10522.368657880514;10531;10566.154707623371;10546.367217966908;10594.682159535227;10610.151187904989;10617.73455663607;10643.14854811617;10502.737431564196;10584.523696433678;10588.15294776416;10622.946885265601;10680.359178449318;10510.264281500298;10551.84172237417;10592.62925797601;10608.469576521144;10610.681679549625;41759.7912010438;42018.159636807395;42065.78967105175;42072.57927420748;42485;3372.763906526544;3507.403741363949;3517.50754894315;3616.4936908832783;3807.8096095195406;32079.51880721791;32198.839005805054;32913.177170570685;32941.17647058843;33708.49437258443;4209.368594710787;4211.684123690705;4283.65730741543;4284.871453856389;4297.613214810682;238411;238990;239972;241375;241939;57827.4217257822;58186;58548.121778173365;58762;58842.11736823951;35503.11242218965;36487.4526882097;36631.26737465289;38765.41851872224;60904.69547652322;17826.46520604384;17878.55303617394;17880.642387152184;17989.010604416882;18032.188551515213;12815.295158766357;13609.387577559033;13858;13894.861051389356;14080.788788168185;19442.99416740023;19547;19631.50921226952;19665.410037698894;19679.704804427944;7368.599966006442;7451.813704657427;7500.849983000309;7520.172780994087;7604.923950760422;992.2211064314479;1035.6634093919356;1045.5242864496636;1083.0631503749357;1120.8150655141974;32401.675983240337;32522;32574.837125814694;32970.50544241838;33236.83381583125;1073.1146803886668;1124.6288724720823;1154.7459558897028;1165.5920427850185;1238.0467040379037;16371.918140409221;17004.829951700573;17084.829151708323;17111.22999465027;17318;536965;537755;540852;541586;546179;59122;59293.40706592879;59295;59317.40682593205;59526.70236648876;1305.3212329588562;1406.9366878490493;1411.0334420921702;1413.9505117320844;1442.4879167895665;31442.21394465157;32721.509177361884;33833;33870.32259354848;34321.82839085789;50136.49863501464;50564.74717626388;50934.235986460975;51212;51387.743061284455;425662;429816;430448;430644;430672;25428.618570721825;27668;28547.28631784222;28707.712922870505;29416.852915735286;47331;48726.75636621936;48958.26562601492;50033.49966500434;50210.74894625504;40865;41742.37386439145;42497.36253956131;43073.353899692156;43538.56461435472;41617.37573936333;41738.16523669449;41969.58030419657;43009.56990430056;43119.78440107779;41454.37818432786;41607.58392416037;42273.78863105665;42552.14895702131;42733;36055.459168113026;36728.26543469169;36814;37328.62671373251;37672.246555069294;28323.575146373234;28808.423831523673;28826.27934301659;28908.566371504025;28985 16:16:20 INFO - PROCESS | 23010 | |2;jquery.html;410.8890599538123;415.5698851688503;416.1655879960678;416.33386581469654;432.01716095881864;17961.7305740414;18296.542586435346;18980;19004.90497547512;19250.807491925076;17540.210690518925;18083.367082152126;18450;18482.630347393046;18483.168257428413;5619.971900140504;5661.009323368415;5763.654180749148;5797.043487824504;5824.796132135376;5674.290713660793;5762.308522977249;5763.625364351325;5770.567207459437;5800.071988481844;5551.2228288039705;5683.687397193153;5720.771169153229;5721.31344238692;5724.198612194296;5603.495685388294;5656.264685590888;5668.744906479177;5671.206031155621;5708.22938903249;4893.241547560134;4953.975230123826;4973.676711013809;5035.647504674675;5047.621428392848;21190.576188476298;21388;21446.678299825515;21512.354629361143;22072.33782986513;2106.662933930579;2250.5273892482596;2361.1971929543865;2421.7093948726088;2474.938126546833;13783.173009619455;13816.654583635389;13913.860861391358;13932.512362067278;14503.854961450355;1904.8476121910214;1957;1962.8920409377479;1983.1571582077559;2049.2008116834445;71439;74010.62994684992;74037.25962740411;74493;74812.6259368705;9356.204722598519;9653.71038868835;10538.63114790994;10580.52387642558;10730;14628.634284142981;14672;14689.706205876037;14693.412263509554;14724.55826325212;8244.134365891616;8294.419390642597;8322.62548185333;8425.031121420992;8483.787905302417;7996.080450748238;7998.520088794575;8037.959810200911;8115.269625733712;8188.14024527428;6519.706613202417;6575.473962083021;6578.901316480256;6592.670366481657;6637.800865973932;1894.895780732073;1937.321937321926;1940.7865134835156;1954.1890115601866;1971.2213675598346;986.3982970388057;1026.7850473805072;1044.8850626431085;1053.3890343600692;1067.0253306433628;17958.730619040725;18104.9094754528;18174.363897263796;18749.156287967075;19159.904200478908;973.9047306078066;1036.7097212780434;1047.6804574604819;1070.6359837655234;1101.0695961912172;10306.845397319046;10321.277510574339;10844.620438284623;10889.292196007234;11191.608293709682;476694;485827;491176;494788;495794;67693;87508.5624571873;90264.09735902557;93820;96294.51852740691;1076.2896488317672;1088.684281558355;1089.3355053417408;1114.3759494683004;1119.921605487608;16124.516264512084;16369.017858928502;16583;16611.83388166151;16667.416640417527;39933.60066399299;40090.06984906782;40521;41268.222448857785;41542.376864347665;50035;51134.23298650597;53368.4663153379;58978.41021589729;59427.70286148541;8261.463004904663;8664;8748.212660860552;8826.735197944072;9015.639374424949;12342;13386.26375549354;13434.261115638734;13593;13727.862721373058;11554.768904621693;11749.765004699688;11901.226420282654;11975.820362694742;12170.878291217328;11325.093992480417;11352.375619341015;11429.542818287508;11460.484278207334;11474.254173478697;11703.356315402734;11952.820707689218;12058.336791476553;12084.577039803564;12193.329366884907;8537.103604121477;8660.35047371437;8734.432261902955;8883.200511953955;8890.777730556787;8502;8534.615942282488;8658.39391242607;8801.47191168532;8970.506622135845 16:16:20 INFO - PROCESS | 23010 | |3;mootools.html;1357.2806412601324;1526.6030831983685;1566.514380542032;1729.3947118508524;1857.6377606366764;8674.089220631837;9196.034416386276;9214.98635150133;9524.904750952488;9537.380070295427;9192.816143677122;9409.435433873972;9419.48192849393;9424.104710052534;9432.103950124745;2076.0138934006377;2348.7181538215355;2387.55830171418;2412.6381042843536;2418.0569577864558;2419.4919066995953;2426.842255253403;2439.9512009759883;2440.3777036855577;2447.119037146626;2387.069043073203;2404.3989002749313;2412.8311018228733;2441.4506735984437;2441.6581678565017;2014.4359579317816;2327.7904988551027;2388.6536452214505;2396.5446565152565;2406.7713567211085;2306.8731219782903;2325.8255630827757;2328.953420931572;2331.2889568681544;2339.777721116508;9326;9332.953335233347;9368.531573421302;9413.388129771543;9437.480938548377;5376.946230537723;5554.611177217596;5559.33288005442;5584.776608935637;5625.8593535161535;3566.5898421681577;3599.6580324869096;3654.196076863086;3655.3785856404475;3657.414813629833;1119.6641007697658;1120.7814476177148;1120.7814476177148;1131.0612191880718;1145.604766355606;13214.735705285937;13224.735505289937;13270.734585308337;13339.66650833737;13341.866581334256;4890.779914903838;4897.632677549127;5013.724245166551;5015.22264049073;5043.646944713908;10914.508847101899;10927.836082458769;10958.671239862817;11001.669949901516;11025.724356891142;4968.8757781055765;5574.860628484239;5587.3015873015875;5591.608587398924;5599;4971.825986090469;5076.832328564451;5102.974485127551;5120.974395128001;5141.897162056738;4130.958690413117;4184.560621134798;4207.663386929038;4236.9364459533135;4239.533651298355;1313.753481546566;1324.443733631877;1327.6017194841509;1334.8264725585807;1337.104140226051;933.3000249812641;1007.6926537406015;1008.3546530220665;1016.7966406718685;1021.7956408718137;6411.871762564816;6477.287498375175;6587.703553340111;6629.9337006630285;6700.396964273239;479.57317986991603;482.4235039128278;482.85755702067974;1045.0281238448165;1048.727330893973;4262.552431994658;4306.5262821089655;4394.340848872695;4409.801558929856;4488.820447182076;11654.242474239147;11788.528458861721;11810.704732381759;11864.16950813452;11897.940510297392;11232.269902456477;11676.47455864488;11719.707007324885;11791.056715462742;11818.704532386588;8329.750107496784;8723.171298726611;8768.123187681182;8771.298296136294;8775.166359195866;6197.876042479035;6418.101465794886;6503.934960650522;6515.6742162892615;6548.214214294315;10762.461876905967;10920.836187457036;10929.508172132115;10944.726381840517;11135.33188008722;11060.446977650925;11203.663890083311;11291.717707057389;11408.771824563628;11424.314541127558;7405.518641288299;8022.358211343195;8221.876671849808;8321.583920803814;8355.206255405727;9034.096590340914;9103.772405689911;9144.679936202467;9179.495127768037;9185.724428267162;7857.646405911633;8105.675772969251;8164.265216130577;8227.876581851397;8385.58072096405;7867.567283799217;7919.326857216971;8003.11965683786;8142.18578142181;8299.751007469786;7069.9646501767165;7099.680514376969;7102.751403700846;7145.249748776304;7253.238409967089;5257.185136303879;5482.5613950883035;5501.367342755634;5530.170474428868;5625.943740562542;5671.234383358359;5723.456271654186;5768.44252051952;5793.710314484343;5823.854403639943 16:16:20 INFO - PROCESS | 23010 | |4;prototype.html;325.78660977060036;335.57382124701627;338.38582971905976;339.9167204035012;340.17168195444094;4948.455669876311;5070.51544743076;5198;5234.319538459999;5241.44964778698;5086.567641750456;5149.510796474338;5156.524868063332;5227.607929405291;5263.947360526394;4115.814788334532;4119.237940980921;4124.6906482013965;4128.2981893078095;4138.3999320098565;3889.2999260133165;3891.708121890855;4074.6553637299767;4079.3473044312914;4100.63094321511;3985.4021896715435;4019.7387169834014;4071.063655359269;4076.8369265229353;4080.9387859182134;3978.269452788032;4013.1772986537835;4019.8995025124314;4037.9596204037875;4061.390791381287;3554.6445355464507;3613.963860361389;3621.022323972539;3621.7283703722064;3634.618365071682;4933.580645645088;4943.555080042814;4948.604111671058;4988.725620090893;5015.39815222172;4570.360149579062;4586.908261834745;4594.632429405639;4601.493835678073;4630.8147674093;5119.334486516766;5318.946810531884;5333.466653334674;5348.91976620351;5360.276362691065;3941.659835655862;3942.211557688474;3962.128331767007;3965.7620542767527;3977.6817854571564;5575.553955683536;5578.079616863212;5601.439856014406;5612.579056570774;5634.098544232903;4063.3701776224143;4071.063655359225;4071.42999980003;4078.7144899856726;4084.448599439097;4025.0742329264435;4064.207479541461;4163.980469472303;4171.83312667496;4198.097409057067;4690.015096829636;4692.741899195577;4693.601043911238;4698.295255711602;4742.691725037862;5522.861928451821;5524.226608274845;5530.087535556588;5539.473749993744;5548.778048878;1312.5471712259255;1343.482759137739;1396.1623026184209;1408.788681697733;1409.5771268619374;903.9050899655573;920.6179435534312;923.6951805904035;936.391345625349;942.4533770413144;4640.303954406866;4654.883627909262;4659.767011649472;4697.694649847749;4709.458412282563;4914.493608259741;4955.876103097379;4961.776720047535;4979.4771548987555;5002.274670172805;4444.509110661691;4551.977240113844;4601.895545069159;4678.157931572282;4692.859214223578;2539.9238022859345;2608.5565453872887;2628.3166376742183;2645.365112373055;2670.559357705996;4972.801087956441;5005.724685142352;5012.147934851084;5040.873978150502;5071.2139618359215;4942.851714448572;4972.627052971042;4975.606830087581;5047.4447810740785;5253.3958594761325;5662.886742265132;5682.346530149003;5770.249867517112;5770.711464426762;5810.4189581041555;5547.583931205095;5556.444355564573;5582.637128586629;5583.692896890548;5597.132444471114;5333.7066461345;5537.4495141360485;5544.805931792368;5547.722613869371;5607.271054762855;5764.827055188351;5768.307803063659;5776.277965254343;5797.376734514342;5806.071028635398;3865.69074474047;4044.4742183515955;4081.1225586499045;4082.6320733974817;4094.8125043738173;5593.776248950159;5663.603547751618;5692.865175559165;5709.600327977002;5711.543076553949;5519;5523.585731070266;5532.197831314517;5565.30433695788;5567.8585889891665;5311.3891902431715;5515.917261241005;5519.951209270226;5550.639208451437;5561.221429000024;5355.357357117171;5362.490563396471;5386.48828361305;5415.187721841755;5459.044667183148;5180.171172612364;5406.4052954175795;5545.195946587803;5552.333719953632;5558.138488534284;5254.579633629377;5357.249985002025;5390.272313237662;5404.297441332602;5419.34967803866 16:16:20 INFO - PROCESS | 23010 | |5;yui.html;751.3650964934628;789.2029050658837;802.4022103532867;805.8710606302993;816.738643634037;15511.61220969475;16043.919780401111;16059.437919672813;16075.678486430279;16082.276297566608;14621.707565848677;14699.926500367483;14816.55550333489;14821.258937053131;14850.1832399218;672.9360710732473;787.6061969015492;832.8126171611369;841.0832192909727;848.4824257203101;749.8237914090184;774.484967496617;785.2265518464308;813.3818298093462;824.2787560884226;880.520116536486;889.973300800977;890.9064548222441;892.2995448572872;892.8035832116924;711.4521818200009;830.1656834880936;862.2756884217225;863.7926897544606;887.4586502233665;635.196476457282;635.2186810223404;635.4090695653041;635.9491240700733;636.0427556527396;14518;14598.270086495633;14607.926960365236;14609.123452592772;14610.561683149523;13989.30053497321;14272.857271427256;14305.856941430557;14329.57011289663;14330.785038224434;12328.630141095782;12488.875111248863;12709.364531773466;12745.61763147107;12941.029422793234;4296.914061718748;4300.65594752419;4304.074623955865;4318.805653745589;4345.717528360678;58246.70876645632;59012.70493647547;59397.7030114851;59419.40580594182;59611.70194149001;11481.540738370539;11537.769244615061;11626.941865290619;11664.591739289253;11828.408579570987;20581.07385167671;20632.48418789542;20654.793452065587;20694.275700350416;21376.679349809765;8443.282321002784;8501.787455313543;8512.70205542803;8584.613692383733;8597.828043439096;4560.612347950461;4643.558861908114;4643.628509719214;4658.114958157939;4805.0870334637;3516.7889926603975;3595.424732042861;3600.6939410149907;3606.495090687306;3617.8010209438735;657.3426573426574;663.5355251324092;664.7407511070686;665.4210836572213;675.2066322071546;726.197551705358;727.7271023366237;735.4373903963366;756.8297133144998;776.2714060078828;9373.531323433801;9433.76415589602;9574.66488672907;9577.185939195246;9683.806323873483;581.2792137749161;633.4204203154062;687.6836655138692;687.7455341523669;688.1707542411382;4567.566081222279;4663.48701642819;4922.827701030446;4931.3835770528685;4997.425296090996;72808;74823.25176748163;74962.25037749662;75222.24777752261;78589;67724.6613766928;68691;69824.65087674529;70489;70568.2943170572;60688;60850.39149608536;61108.38891611027;61317.38682613206;62800.68599656972;9339;9358.29812764059;9390.436573805593;9409.85885211736;9414.905850941403;38932.80533597314;39024.41463378108;39484;40376.3943540853;40580.79709601433;50300.748496257285;55907.16139257833;56351.71824140853;56368.718156408955;56580.151297729746;13358.398872050975;13522.526711565048;13536.323183840965;13742.175469471864;14015.859841401456;15006.6998660024;15126.39494420255;15553.455629052929;15701.293441795375;16051.388362868076;14444;14857.851421485646;14924.104553726376;15411.36951416479;15495;13726.519571814939;13998.090124141898;14003;14060.08609440383;14114.647133821736;13435.596932092052;13610.183388996691;14346.426142954166;14398.640033998814;14425.49510767118;11266.155038372319;11527.308361498337;11574.768504629692;11679.766404671689;11729.237599556001;10917.344959302467;11510.30938143714;11625.128115391548;11631.185816993067;11717.12121590867 16:16:20 INFO - PROCESS | 23010 | __end_tp_report 16:16:20 INFO - PROCESS | 23010 | __start_cc_report 16:16:20 INFO - PROCESS | 23010 | _x_x_mozilla_cycle_collect,765 16:16:20 INFO - PROCESS | 23010 | __end_cc_report 16:16:20 INFO - PROCESS | 23010 | __startTimestamp1463094980895__endTimestamp 16:16:20 INFO - PROCESS | 23010 | 16:16:20 INFO - PROCESS | 23010 | ------- Summary: start ------- 16:16:20 INFO - PROCESS | 23010 | Number of tests: 6 16:16:20 INFO - PROCESS | 23010 | 16:16:20 INFO - PROCESS | 23010 | [#0] dojo.html Cycles:165 Average:5285.79 Median:5494.62 stddev:598.47 (10.9%) stddev-sans-first:576.76 16:16:20 INFO - PROCESS | 23010 | Values: 3167.3 3169.1 3197.2 3200.9 3226.6 5160.6 5238.3 5346.9 5349.3 5378.9 5321.3 5331.9 5335.1 5350.8 5361.3 4765.0 4780.6 4884.3 4924.0 5007.3 4879.1 4883.0 4895.0 4957.3 4959.2 4702.2 4797.4 4799.3 4832.4 4836.6 4837.7 4840.5 4892.7 4903.6 4916.0 4789.2 4834.6 4848.8 4850.0 4878.7 5075.9 5161.8 5218.7 5222.0 5287.9 5686.4 5688.5 5707.4 5727.2 5729.2 5494.6 5777.8 5781.1 5786.2 5817.0 5096.7 5241.1 5340.9 5378.5 5383.8 5604.2 5783.2 5867.8 5903.6 5918.6 5420.3 5547.1 5601.7 5608.2 5664.1 5702.2 5735.6 5778.5 5792.1 5839.0 5686.0 5699.9 5712.3 5717.5 5723.3 5511.5 5592.0 5635.1 5716.9 5735.3 4272.6 4309.5 4311.1 4315.9 4358.6 3822.6 3831.9 3868.3 3883.5 3892.1 5201.2 5216.7 5247.0 5295.9 5322.9 5586.2 5643.9 5650.4 5702.2 5706.2 5079.3 5311.0 5334.9 5336.7 5375.9 4744.4 4770.6 4796.3 4822.3 4880.7 5899.9 5911.1 5912.1 5924.4 5926.5 5527.3 5711.1 5745.3 5752.4 5773.5 5839.3 5856.4 5893.0 5907.5 5927.1 5635.2 5677.1 5677.2 5684.5 5695.0 5449.5 5669.9 5785.0 5785.6 5803.8 5587.1 5596.1 5606.1 5614.3 5671.6 5510.6 5579.9 5589.9 5600.6 5610.3 5325.5 5414.0 5453.0 5525.8 5564.4 5622.6 5662.5 5695.7 5699.7 5710.8 5490.9 5494.6 5506.0 5514.8 5519.6 16:16:20 INFO - PROCESS | 23010 | 16:16:20 INFO - PROCESS | 23010 | [#1] ext.html Cycles:180 Average:57180.46 Median:35779.29 stddev:112062.79 (313.2%) stddev-sans-first:112296.50 16:16:20 INFO - PROCESS | 23010 | Values: 550.8 585.4 594.3 604.4 623.9 32795.7 43785.8 44971.1 45165.2 45186.0 45534.8 46041.0 46131.8 46207.3 46278.0 7978.4 8869.4 9081.5 10301.2 10587.5 10394.4 10517.2 10522.4 10531.0 10566.2 10546.4 10594.7 10610.2 10617.7 10643.1 10502.7 10584.5 10588.2 10622.9 10680.4 10510.3 10551.8 10592.6 10608.5 10610.7 41759.8 42018.2 42065.8 42072.6 42485.0 3372.8 3507.4 3517.5 3616.5 3807.8 32079.5 32198.8 32913.2 32941.2 33708.5 4209.4 4211.7 4283.7 4284.9 4297.6 238411.0 238990.0 239972.0 241375.0 241939.0 57827.4 58186.0 58548.1 58762.0 58842.1 35503.1 36487.5 36631.3 38765.4 60904.7 17826.5 17878.6 17880.6 17989.0 18032.2 12815.3 13609.4 13858.0 13894.9 14080.8 19443.0 19547.0 19631.5 19665.4 19679.7 7368.6 7451.8 7500.8 7520.2 7604.9 992.2 1035.7 1045.5 1083.1 1120.8 32401.7 32522.0 32574.8 32970.5 33236.8 1073.1 1124.6 1154.7 1165.6 1238.0 16371.9 17004.8 17084.8 17111.2 17318.0 536965.0 537755.0 540852.0 541586.0 546179.0 59122.0 59293.4 59295.0 59317.4 59526.7 1305.3 1406.9 1411.0 1414.0 1442.5 31442.2 32721.5 33833.0 33870.3 34321.8 50136.5 50564.7 50934.2 51212.0 51387.7 425662.0 429816.0 430448.0 430644.0 430672.0 25428.6 27668.0 28547.3 28707.7 29416.9 47331.0 48726.8 48958.3 50033.5 50210.7 40865.0 41742.4 42497.4 43073.4 43538.6 41617.4 41738.2 41969.6 43009.6 43119.8 41454.4 41607.6 42273.8 42552.1 42733.0 36055.5 36728.3 36814.0 37328.6 37672.2 28323.6 28808.4 28826.3 28908.6 28985.0 16:16:20 INFO - PROCESS | 23010 | 16:16:20 INFO - PROCESS | 23010 | [#2] jquery.html Cycles:180 Average:28423.04 Median:21204.27 stddev:80377.23 (379.1%) stddev-sans-first:80575.19 16:16:20 INFO - PROCESS | 23010 | Values: 410.9 415.6 416.2 416.3 432.0 17961.7 18296.5 18980.0 19004.9 19250.8 17540.2 18083.4 18450.0 18482.6 18483.2 5620.0 5661.0 5763.7 5797.0 5824.8 5674.3 5762.3 5763.6 5770.6 5800.1 5551.2 5683.7 5720.8 5721.3 5724.2 5603.5 5656.3 5668.7 5671.2 5708.2 4893.2 4954.0 4973.7 5035.6 5047.6 21190.6 21388.0 21446.7 21512.4 22072.3 2106.7 2250.5 2361.2 2421.7 2474.9 13783.2 13816.7 13913.9 13932.5 14503.9 1904.8 1957.0 1962.9 1983.2 2049.2 71439.0 74010.6 74037.3 74493.0 74812.6 9356.2 9653.7 10538.6 10580.5 10730.0 14628.6 14672.0 14689.7 14693.4 14724.6 8244.1 8294.4 8322.6 8425.0 8483.8 7996.1 7998.5 8038.0 8115.3 8188.1 6519.7 6575.5 6578.9 6592.7 6637.8 1894.9 1937.3 1940.8 1954.2 1971.2 986.4 1026.8 1044.9 1053.4 1067.0 17958.7 18104.9 18174.4 18749.2 19159.9 973.9 1036.7 1047.7 1070.6 1101.1 10306.8 10321.3 10844.6 10889.3 11191.6 476694.0 485827.0 491176.0 494788.0 495794.0 67693.0 87508.6 90264.1 93820.0 96294.5 1076.3 1088.7 1089.3 1114.4 1119.9 16124.5 16369.0 16583.0 16611.8 16667.4 39933.6 40090.1 40521.0 41268.2 41542.4 50035.0 51134.2 53368.5 58978.4 59427.7 8261.5 8664.0 8748.2 8826.7 9015.6 12342.0 13386.3 13434.3 13593.0 13727.9 11554.8 11749.8 11901.2 11975.8 12170.9 11325.1 11352.4 11429.5 11460.5 11474.3 11703.4 11952.8 12058.3 12084.6 12193.3 8537.1 8660.4 8734.4 8883.2 8890.8 8502.0 8534.6 8658.4 8801.5 8970.5 16:16:20 INFO - PROCESS | 23010 | 16:16:20 INFO - PROCESS | 23010 | [#3] mootools.html Cycles:180 Average:6171.21 Median:2484.20 stddev:3578.37 (144.0%) stddev-sans-first:3570.12 16:16:20 INFO - PROCESS | 23010 | Values: 1357.3 1526.6 1566.5 1729.4 1857.6 8674.1 9196.0 9215.0 9524.9 9537.4 9192.8 9409.4 9419.5 9424.1 9432.1 2076.0 2348.7 2387.6 2412.6 2418.1 2419.5 2426.8 2440.0 2440.4 2447.1 2387.1 2404.4 2412.8 2441.5 2441.7 2014.4 2327.8 2388.7 2396.5 2406.8 2306.9 2325.8 2329.0 2331.3 2339.8 9326.0 9333.0 9368.5 9413.4 9437.5 5376.9 5554.6 5559.3 5584.8 5625.9 3566.6 3599.7 3654.2 3655.4 3657.4 1119.7 1120.8 1120.8 1131.1 1145.6 13214.7 13224.7 13270.7 13339.7 13341.9 4890.8 4897.6 5013.7 5015.2 5043.6 10914.5 10927.8 10958.7 11001.7 11025.7 4968.9 5574.9 5587.3 5591.6 5599.0 4971.8 5076.8 5103.0 5121.0 5141.9 4131.0 4184.6 4207.7 4236.9 4239.5 1313.8 1324.4 1327.6 1334.8 1337.1 933.3 1007.7 1008.4 1016.8 1021.8 6411.9 6477.3 6587.7 6629.9 6700.4 479.6 482.4 482.9 1045.0 1048.7 4262.6 4306.5 4394.3 4409.8 4488.8 11654.2 11788.5 11810.7 11864.2 11897.9 11232.3 11676.5 11719.7 11791.1 11818.7 8329.8 8723.2 8768.1 8771.3 8775.2 6197.9 6418.1 6503.9 6515.7 6548.2 10762.5 10920.8 10929.5 10944.7 11135.3 11060.4 11203.7 11291.7 11408.8 11424.3 7405.5 8022.4 8221.9 8321.6 8355.2 9034.1 9103.8 9144.7 9179.5 9185.7 7857.6 8105.7 8164.3 8227.9 8385.6 7867.6 7919.3 8003.1 8142.2 8299.8 7070.0 7099.7 7102.8 7145.2 7253.2 5257.2 5482.6 5501.4 5530.2 5625.9 5671.2 5723.5 5768.4 5793.7 5823.9 16:16:20 INFO - PROCESS | 23010 | 16:16:20 INFO - PROCESS | 23010 | [#4] prototype.html Cycles:180 Average:4502.24 Median:4977.54 stddev:1317.26 (26.5%) stddev-sans-first:1283.12 16:16:20 INFO - PROCESS | 23010 | Values: 325.8 335.6 338.4 339.9 340.2 4948.5 5070.5 5198.0 5234.3 5241.4 5086.6 5149.5 5156.5 5227.6 5263.9 4115.8 4119.2 4124.7 4128.3 4138.4 3889.3 3891.7 4074.7 4079.3 4100.6 3985.4 4019.7 4071.1 4076.8 4080.9 3978.3 4013.2 4019.9 4038.0 4061.4 3554.6 3614.0 3621.0 3621.7 3634.6 4933.6 4943.6 4948.6 4988.7 5015.4 4570.4 4586.9 4594.6 4601.5 4630.8 5119.3 5318.9 5333.5 5348.9 5360.3 3941.7 3942.2 3962.1 3965.8 3977.7 5575.6 5578.1 5601.4 5612.6 5634.1 4063.4 4071.1 4071.4 4078.7 4084.4 4025.1 4064.2 4164.0 4171.8 4198.1 4690.0 4692.7 4693.6 4698.3 4742.7 5522.9 5524.2 5530.1 5539.5 5548.8 1312.5 1343.5 1396.2 1408.8 1409.6 903.9 920.6 923.7 936.4 942.5 4640.3 4654.9 4659.8 4697.7 4709.5 4914.5 4955.9 4961.8 4979.5 5002.3 4444.5 4552.0 4601.9 4678.2 4692.9 2539.9 2608.6 2628.3 2645.4 2670.6 4972.8 5005.7 5012.1 5040.9 5071.2 4942.9 4972.6 4975.6 5047.4 5253.4 5662.9 5682.3 5770.2 5770.7 5810.4 5547.6 5556.4 5582.6 5583.7 5597.1 5333.7 5537.4 5544.8 5547.7 5607.3 5764.8 5768.3 5776.3 5797.4 5806.1 3865.7 4044.5 4081.1 4082.6 4094.8 5593.8 5663.6 5692.9 5709.6 5711.5 5519.0 5523.6 5532.2 5565.3 5567.9 5311.4 5515.9 5520.0 5550.6 5561.2 5355.4 5362.5 5386.5 5415.2 5459.0 5180.2 5406.4 5545.2 5552.3 5558.1 5254.6 5357.2 5390.3 5404.3 5419.3 16:16:20 INFO - PROCESS | 23010 | 16:16:20 INFO - PROCESS | 23010 | [#5] yui.html Cycles:180 Average:16984.71 Median:4734.29 stddev:20638.91 (435.9%) stddev-sans-first:20660.80 16:16:20 INFO - PROCESS | 23010 | Values: 751.4 789.2 802.4 805.9 816.7 15511.6 16043.9 16059.4 16075.7 16082.3 14621.7 14699.9 14816.6 14821.3 14850.2 672.9 787.6 832.8 841.1 848.5 749.8 774.5 785.2 813.4 824.3 880.5 890.0 890.9 892.3 892.8 711.5 830.2 862.3 863.8 887.5 635.2 635.2 635.4 635.9 636.0 14518.0 14598.3 14607.9 14609.1 14610.6 13989.3 14272.9 14305.9 14329.6 14330.8 12328.6 12488.9 12709.4 12745.6 12941.0 4296.9 4300.7 4304.1 4318.8 4345.7 58246.7 59012.7 59397.7 59419.4 59611.7 11481.5 11537.8 11626.9 11664.6 11828.4 20581.1 20632.5 20654.8 20694.3 21376.7 8443.3 8501.8 8512.7 8584.6 8597.8 4560.6 4643.6 4643.6 4658.1 4805.1 3516.8 3595.4 3600.7 3606.5 3617.8 657.3 663.5 664.7 665.4 675.2 726.2 727.7 735.4 756.8 776.3 9373.5 9433.8 9574.7 9577.2 9683.8 581.3 633.4 687.7 687.7 688.2 4567.6 4663.5 4922.8 4931.4 4997.4 72808.0 74823.3 74962.3 75222.2 78589.0 67724.7 68691.0 69824.7 70489.0 70568.3 60688.0 60850.4 61108.4 61317.4 62800.7 9339.0 9358.3 9390.4 9409.9 9414.9 38932.8 39024.4 39484.0 40376.4 40580.8 50300.7 55907.2 56351.7 56368.7 56580.2 13358.4 13522.5 13536.3 13742.2 14015.9 15006.7 15126.4 15553.5 15701.3 16051.4 14444.0 14857.9 14924.1 15411.4 15495.0 13726.5 13998.1 14003.0 14060.1 14114.6 13435.6 13610.2 14346.4 14398.6 14425.5 11266.2 11527.3 11574.8 11679.8 11729.2 10917.3 11510.3 11625.1 11631.2 11717.1 16:16:20 INFO - PROCESS | 23010 | -------- Summary: end -------- 16:16:20 INFO - PROCESS | 23010 | 16:16:21 INFO - TEST-INFO | 23010: exit 0 16:16:21 INFO - TEST-OK | dromaeo_css | took 1111480ms 16:16:21 INFO - TEST-START | kraken 16:16:21 INFO - operating with platform_type : linux_ 16:16:21 INFO - Initialising browser for kraken test... 16:16:21 INFO - TEST-INFO | started process 23187 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpYMtFYA/profile http://localhost:59952/getInfo.html) 16:16:25 INFO - PROCESS | 23187 | __metrics Screen width/height:1600/1200 16:16:25 INFO - PROCESS | 23187 | colorDepth:24 16:16:25 INFO - PROCESS | 23187 | Browser inner width/height: 1024/697 16:16:25 INFO - PROCESS | 23187 | __metrics 16:16:26 ERROR - PROCESS | 23187 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 16:16:26 INFO - TEST-INFO | 23187: exit 0 16:16:26 INFO - Browser initialized. 16:16:26 INFO - Running cycle 1/1 for kraken test... 16:16:26 INFO - TEST-INFO | started process 23267 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpYMtFYA/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:16:38 INFO - PROCESS | 23267 | RSS: Main: 151191552 16:16:38 INFO - PROCESS | 23267 | 16:16:40 INFO - PROCESS | 23267 | Cycle 1(1): loaded http://localhost:59952/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:59952/tests/kraken/driver.html?testName=audio-beat-detection) 16:16:41 INFO - PROCESS | 23267 | RSS: Main: 207777792 16:16:41 INFO - PROCESS | 23267 | 16:16:44 INFO - PROCESS | 23267 | Cycle 1(1): loaded http://localhost:59952/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:59952/tests/kraken/driver.html?testName=audio-dft) 16:16:44 INFO - PROCESS | 23267 | RSS: Main: 196759552 16:16:44 INFO - PROCESS | 23267 | 16:16:47 INFO - PROCESS | 23267 | Cycle 1(1): loaded http://localhost:59952/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:59952/tests/kraken/driver.html?testName=audio-fft) 16:16:48 INFO - PROCESS | 23267 | RSS: Main: 264032256 16:16:48 INFO - PROCESS | 23267 | 16:16:50 INFO - PROCESS | 23267 | Cycle 1(1): loaded http://localhost:59952/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:59952/tests/kraken/driver.html?testName=audio-oscillator) 16:16:51 INFO - PROCESS | 23267 | RSS: Main: 173481984 16:16:51 INFO - PROCESS | 23267 | 16:16:53 INFO - PROCESS | 23267 | Cycle 1(1): loaded http://localhost:59952/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:59952/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:16:54 INFO - PROCESS | 23267 | RSS: Main: 178225152 16:16:54 INFO - PROCESS | 23267 | 16:16:57 INFO - PROCESS | 23267 | Cycle 1(1): loaded http://localhost:59952/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:59952/tests/kraken/driver.html?testName=imaging-darkroom) 16:16:57 INFO - PROCESS | 23267 | RSS: Main: 213692416 16:16:57 INFO - PROCESS | 23267 | 16:17:01 INFO - PROCESS | 23267 | Cycle 1(1): loaded http://localhost:59952/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:59952/tests/kraken/driver.html?testName=imaging-desaturate) 16:17:02 INFO - PROCESS | 23267 | RSS: Main: 212164608 16:17:02 INFO - PROCESS | 23267 | 16:17:05 INFO - PROCESS | 23267 | Cycle 1(1): loaded http://localhost:59952/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:59952/tests/kraken/driver.html?testName=json-parse-financial) 16:17:05 INFO - PROCESS | 23267 | RSS: Main: 213454848 16:17:05 INFO - PROCESS | 23267 | 16:17:07 INFO - PROCESS | 23267 | Cycle 1(1): loaded http://localhost:59952/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:59952/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:17:07 INFO - PROCESS | 23267 | RSS: Main: 201588736 16:17:07 INFO - PROCESS | 23267 | 16:17:09 INFO - PROCESS | 23267 | Cycle 1(1): loaded http://localhost:59952/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:59952/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:17:10 INFO - PROCESS | 23267 | RSS: Main: 205824000 16:17:10 INFO - PROCESS | 23267 | 16:17:12 INFO - PROCESS | 23267 | Cycle 1(1): loaded http://localhost:59952/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:59952/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:17:12 INFO - PROCESS | 23267 | RSS: Main: 215519232 16:17:12 INFO - PROCESS | 23267 | 16:17:15 INFO - PROCESS | 23267 | Cycle 1(1): loaded http://localhost:59952/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:59952/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:17:16 INFO - PROCESS | 23267 | RSS: Main: 223031296 16:17:16 INFO - PROCESS | 23267 | 16:17:18 INFO - PROCESS | 23267 | Cycle 1(1): loaded http://localhost:59952/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:59952/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:17:19 INFO - PROCESS | 23267 | RSS: Main: 219295744 16:17:19 INFO - PROCESS | 23267 | 16:17:21 INFO - PROCESS | 23267 | Cycle 1(1): loaded http://localhost:59952/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:59952/tests/kraken/driver.html?testName=ai-astar) 16:17:21 INFO - PROCESS | 23267 | RSS: Main: 264040448 16:17:21 INFO - PROCESS | 23267 | 16:17:21 INFO - PROCESS | 23267 | __start_tp_report 16:17:21 INFO - PROCESS | 23267 | _x_x_mozilla_page_load 16:17:21 INFO - PROCESS | 23267 | _x_x_mozilla_page_load_details 16:17:21 INFO - PROCESS | 23267 | |i|pagename|runs| 16:17:21 INFO - PROCESS | 23267 | |0;ai-astar;112;105;116;113;110;110;110;111;110;111 16:17:21 INFO - PROCESS | 23267 | |1;audio-beat-detection;153;134;150;206;129;155;162;130;179;129 16:17:21 INFO - PROCESS | 23267 | |2;audio-dft;162;160;160;160;162;161;161;164;161;162 16:17:21 INFO - PROCESS | 23267 | |3;audio-fft;75;76;76;150;76;75;76;76;131;77 16:17:21 INFO - PROCESS | 23267 | |4;audio-oscillator;92;91;143;93;92;92;119;92;92;116 16:17:21 INFO - PROCESS | 23267 | |5;imaging-gaussian-blur;117;118;117;116;116;116;116;116;118;117 16:17:21 INFO - PROCESS | 23267 | |6;imaging-darkroom;148;146;148;149;148;152;148;158;148;148 16:17:21 INFO - PROCESS | 23267 | |7;imaging-desaturate;96;96;97;96;97;97;97;97;97;95 16:17:21 INFO - PROCESS | 23267 | |8;json-parse-financial;54;54;54;55;54;54;53;108;52;51 16:17:21 INFO - PROCESS | 23267 | |9;json-stringify-tinderbox;58;58;59;58;59;58;58;59;58;59 16:17:21 INFO - PROCESS | 23267 | |10;stanford-crypto-aes;84;86;83;80;81;82;87;85;81;82 16:17:21 INFO - PROCESS | 23267 | |11;stanford-crypto-ccm;125;124;135;125;131;135;136;133;205;130 16:17:21 INFO - PROCESS | 23267 | |12;stanford-crypto-pbkdf2;169;166;169;166;169;168;167;171;170;165 16:17:21 INFO - PROCESS | 23267 | |13;stanford-crypto-sha256-iterative;65;65;66;65;66;64;64;64;64;66 16:17:21 INFO - PROCESS | 23267 | __end_tp_report 16:17:21 INFO - PROCESS | 23267 | __start_cc_report 16:17:21 INFO - PROCESS | 23267 | _x_x_mozilla_cycle_collect,1722 16:17:21 INFO - PROCESS | 23267 | __end_cc_report 16:17:21 INFO - PROCESS | 23267 | __startTimestamp1463095041466__endTimestamp 16:17:21 INFO - PROCESS | 23267 | 16:17:21 INFO - PROCESS | 23267 | ------- Summary: start ------- 16:17:21 INFO - PROCESS | 23267 | Number of tests: 14 16:17:21 INFO - PROCESS | 23267 | 16:17:21 INFO - PROCESS | 23267 | [#0] ai-astar Cycles:10 Average:110.80 Median:111.00 stddev:2.78 (2.5%) stddev-sans-first:2.92 16:17:21 INFO - PROCESS | 23267 | Values: 112.0 105.0 116.0 113.0 110.0 110.0 110.0 111.0 110.0 111.0 16:17:21 INFO - PROCESS | 23267 | 16:17:21 INFO - PROCESS | 23267 | [#1] audio-beat-detection Cycles:10 Average:152.70 Median:154.00 stddev:24.99 (16.2%) stddev-sans-first:26.50 16:17:21 INFO - PROCESS | 23267 | Values: 153.0 134.0 150.0 206.0 129.0 155.0 162.0 130.0 179.0 129.0 16:17:21 INFO - PROCESS | 23267 | 16:17:21 INFO - PROCESS | 23267 | [#2] audio-dft Cycles:10 Average:161.30 Median:161.50 stddev:1.25 (0.8%) stddev-sans-first:1.30 16:17:21 INFO - PROCESS | 23267 | Values: 162.0 160.0 160.0 160.0 162.0 161.0 161.0 164.0 161.0 162.0 16:17:21 INFO - PROCESS | 23267 | 16:17:21 INFO - PROCESS | 23267 | [#3] audio-fft Cycles:10 Average:88.80 Median:76.00 stddev:27.62 (36.3%) stddev-sans-first:28.84 16:17:21 INFO - PROCESS | 23267 | Values: 75.0 76.0 76.0 150.0 76.0 75.0 76.0 76.0 131.0 77.0 16:17:21 INFO - PROCESS | 23267 | 16:17:21 INFO - PROCESS | 23267 | [#4] audio-oscillator Cycles:10 Average:102.20 Median:92.00 stddev:17.85 (19.4%) stddev-sans-first:18.55 16:17:21 INFO - PROCESS | 23267 | Values: 92.0 91.0 143.0 93.0 92.0 92.0 119.0 92.0 92.0 116.0 16:17:21 INFO - PROCESS | 23267 | 16:17:21 INFO - PROCESS | 23267 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.82 (0.7%) stddev-sans-first:0.87 16:17:21 INFO - PROCESS | 23267 | Values: 117.0 118.0 117.0 116.0 116.0 116.0 116.0 116.0 118.0 117.0 16:17:21 INFO - PROCESS | 23267 | 16:17:21 INFO - PROCESS | 23267 | [#6] imaging-darkroom Cycles:10 Average:149.30 Median:148.00 stddev:3.40 (2.3%) stddev-sans-first:3.57 16:17:21 INFO - PROCESS | 23267 | Values: 148.0 146.0 148.0 149.0 148.0 152.0 148.0 158.0 148.0 148.0 16:17:21 INFO - PROCESS | 23267 | 16:17:21 INFO - PROCESS | 23267 | [#7] imaging-desaturate Cycles:10 Average:96.50 Median:97.00 stddev:0.71 (0.7%) stddev-sans-first:0.73 16:17:21 INFO - PROCESS | 23267 | Values: 96.0 96.0 97.0 96.0 97.0 97.0 97.0 97.0 97.0 95.0 16:17:21 INFO - PROCESS | 23267 | 16:17:21 INFO - PROCESS | 23267 | [#8] json-parse-financial Cycles:10 Average:58.90 Median:54.00 stddev:17.29 (32.0%) stddev-sans-first:18.25 16:17:21 INFO - PROCESS | 23267 | Values: 54.0 54.0 54.0 55.0 54.0 54.0 53.0 108.0 52.0 51.0 16:17:21 INFO - PROCESS | 23267 | 16:17:21 INFO - PROCESS | 23267 | [#9] json-stringify-tinderbox Cycles:10 Average:58.40 Median:58.50 stddev:0.52 (0.9%) stddev-sans-first:0.53 16:17:21 INFO - PROCESS | 23267 | Values: 58.0 58.0 59.0 58.0 59.0 58.0 58.0 59.0 58.0 59.0 16:17:21 INFO - PROCESS | 23267 | 16:17:21 INFO - PROCESS | 23267 | [#10] stanford-crypto-aes Cycles:10 Average:83.10 Median:83.50 stddev:2.33 (2.8%) stddev-sans-first:2.45 16:17:21 INFO - PROCESS | 23267 | Values: 84.0 86.0 83.0 80.0 81.0 82.0 87.0 85.0 81.0 82.0 16:17:21 INFO - PROCESS | 23267 | 16:17:21 INFO - PROCESS | 23267 | [#11] stanford-crypto-ccm Cycles:10 Average:137.90 Median:134.00 stddev:24.00 (17.9%) stddev-sans-first:24.99 16:17:21 INFO - PROCESS | 23267 | Values: 125.0 124.0 135.0 125.0 131.0 135.0 136.0 133.0 205.0 130.0 16:17:21 INFO - PROCESS | 23267 | 16:17:21 INFO - PROCESS | 23267 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.00 Median:169.00 stddev:1.94 (1.2%) stddev-sans-first:2.03 16:17:21 INFO - PROCESS | 23267 | Values: 169.0 166.0 169.0 166.0 169.0 168.0 167.0 171.0 170.0 165.0 16:17:21 INFO - PROCESS | 23267 | 16:17:21 INFO - PROCESS | 23267 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.90 Median:65.00 stddev:0.88 (1.3%) stddev-sans-first:0.93 16:17:21 INFO - PROCESS | 23267 | Values: 65.0 65.0 66.0 65.0 66.0 64.0 64.0 64.0 64.0 66.0 16:17:21 INFO - PROCESS | 23267 | -------- Summary: end -------- 16:17:21 INFO - PROCESS | 23267 | 16:17:22 INFO - TEST-INFO | 23267: exit 0 16:17:22 INFO - TEST-OK | kraken | took 60864ms 16:17:22 INFO - SUITE-END | took 1172s 16:17:22 INFO - Completed test suite (00:19:32) 16:17:22 INFO - javascript benchmark 16:17:22 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3167.271527548664, 3169.0651257878926, 3197.152754520052, 3200.9116900253907, 3226.612806463225, 5160.638755287131, 5238.292830467885, 5346.866328341788, 5349.3045904032515, 5378.865528361788, 5321.3348331458565, 5331.946680533193, 5335.119705248639, 5350.759215835287, 5361.329833770778, 4764.999999999991, 4780.641451891105, 4884.267359896008, 4924.039812236615, 5007.324011258488, 4879.072976134523, 4882.97558512207, 4895.0, 4957.305977163182, 4959.156943319645, 4702.177119004177, 4797.448293446263, 4799.328094066834, 4832.444268909086, 4836.613070954315, 4837.685550439211, 4840.5159484051665, 4892.706437613719, 4903.607711383081, 4915.950840491585, 4789.161896668104, 4834.613230941516, 4848.757562121879, 4850.029994001178, 4878.682885612459, 5075.949240507584, 5161.767720452589, 5218.739063046833, 5222.0, 5287.894242115136, 5686.431356864322, 5688.459596338341, 5707.3721890592005, 5727.198192253088, 5729.197912292282, 5494.615376923617, 5777.7977770777825, 5781.103928891029, 5786.1610066540115, 5817.0401883689565, 5096.694198348111, 5241.0828105081455, 5340.866478338034, 5378.462153784591, 5383.838484845461, 5604.187392828017, 5783.248177736951, 5867.823965281048, 5903.616264942765, 5918.615290006136, 5420.295361603045, 5547.0570003098765, 5601.691906945076, 5608.242887210175, 5664.065429204217, 5702.173184888251, 5735.627184233012, 5778.451047150514, 5792.102224155263, 5839.036558967808, 5686.0, 5699.914501282484, 5712.314522257273, 5717.456841600041, 5723.313202415737, 5511.476409741148, 5592.0, 5635.0702134147405, 5716.885662286731, 5735.340435849847, 4272.594103560219, 4309.5044069932355, 4311.094670119247, 4315.892102697395, 4358.607725304738, 3822.5604055533413, 3831.8658846940225, 3868.323043467382, 3883.475730776309, 3892.1048158923604, 5201.245819356172, 5216.686998780086, 5246.973765131226, 5295.894082118336, 5322.946770532322, 5586.16207568868, 5643.927653745775, 5650.378458369576, 5702.2301989231755, 5706.229658996065, 5079.263506791494, 5311.0, 5334.946650533445, 5336.733163341817, 5375.946240537544, 4744.4069491313585, 4770.61835053195, 4796.304535842353, 4822.300766388854, 4880.6583539152625, 5899.882002360015, 5911.142884281755, 5912.113183022495, 5924.407559244041, 5926.4666180044005, 5527.336719593513, 5711.057675483498, 5745.2818397700285, 5752.367239603724, 5773.53811695055, 5839.270091238595, 5856.385079566755, 5893.02765043763, 5907.468327850343, 5927.081302398135, 5635.239242702347, 5677.091665333527, 5677.1768093627015, 5684.459976302244, 5695.0, 5449.536789372789, 5669.914951275817, 5785.0, 5785.566082543742, 5803.767849285981, 5587.1339942309005, 5596.076647353141, 5606.074997625346, 5614.326280846161, 5671.6029877909805, 5510.586705996986, 5579.944200557942, 5589.860253493695, 5600.635958662674, 5610.298712660918, 5325.520703136582, 5413.9729301353245, 5453.045716999589, 5525.751341189576, 5564.381563302531, 5622.606417550732, 5662.546996240373, 5695.686737229326, 5699.658020518782, 5710.828675139753, 5490.945090549203, 5494.560435165257, 5505.9724701376235, 5514.80698175562, 5519.558435325084], "value": 5246.475365722567, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [550.7576666266842, 585.3502612100569, 594.2749845188873, 604.4016423740499, 623.9064140378944, 32795.67204327956, 43785.78107109468, 44971.10057798846, 45165.160961318325, 45186.0, 45534.77232613832, 46041.0, 46131.76934115333, 46207.30689039667, 46278.0, 7978.361731061501, 8869.423487473327, 9081.54592270384, 10301.227407944396, 10587.523561439753, 10394.376337419779, 10517.211209159306, 10522.368657880514, 10531.0, 10566.154707623371, 10546.367217966908, 10594.682159535227, 10610.151187904989, 10617.73455663607, 10643.14854811617, 10502.737431564196, 10584.523696433678, 10588.15294776416, 10622.946885265601, 10680.359178449318, 10510.264281500298, 10551.84172237417, 10592.62925797601, 10608.469576521144, 10610.681679549625, 41759.7912010438, 42018.159636807395, 42065.78967105175, 42072.57927420748, 42485.0, 3372.763906526544, 3507.403741363949, 3517.50754894315, 3616.4936908832783, 3807.8096095195406, 32079.51880721791, 32198.839005805054, 32913.177170570685, 32941.17647058843, 33708.49437258443, 4209.368594710787, 4211.684123690705, 4283.65730741543, 4284.871453856389, 4297.613214810682, 238411.0, 238990.0, 239972.0, 241375.0, 241939.0, 57827.4217257822, 58186.0, 58548.121778173365, 58762.0, 58842.11736823951, 35503.11242218965, 36487.4526882097, 36631.26737465289, 38765.41851872224, 60904.69547652322, 17826.46520604384, 17878.55303617394, 17880.642387152184, 17989.010604416882, 18032.188551515213, 12815.295158766357, 13609.387577559033, 13858.0, 13894.861051389356, 14080.788788168185, 19442.99416740023, 19547.0, 19631.50921226952, 19665.410037698894, 19679.704804427944, 7368.599966006442, 7451.813704657427, 7500.849983000309, 7520.172780994087, 7604.923950760422, 992.2211064314479, 1035.6634093919356, 1045.5242864496636, 1083.0631503749357, 1120.8150655141974, 32401.675983240337, 32522.0, 32574.837125814694, 32970.50544241838, 33236.83381583125, 1073.1146803886668, 1124.6288724720823, 1154.7459558897028, 1165.5920427850185, 1238.0467040379037, 16371.918140409221, 17004.829951700573, 17084.829151708323, 17111.22999465027, 17318.0, 536965.0, 537755.0, 540852.0, 541586.0, 546179.0, 59122.0, 59293.40706592879, 59295.0, 59317.40682593205, 59526.70236648876, 1305.3212329588562, 1406.9366878490493, 1411.0334420921702, 1413.9505117320844, 1442.4879167895665, 31442.21394465157, 32721.509177361884, 33833.0, 33870.32259354848, 34321.82839085789, 50136.49863501464, 50564.74717626388, 50934.235986460975, 51212.0, 51387.743061284455, 425662.0, 429816.0, 430448.0, 430644.0, 430672.0, 25428.618570721825, 27668.0, 28547.28631784222, 28707.712922870505, 29416.852915735286, 47331.0, 48726.75636621936, 48958.26562601492, 50033.49966500434, 50210.74894625504, 40865.0, 41742.37386439145, 42497.36253956131, 43073.353899692156, 43538.56461435472, 41617.37573936333, 41738.16523669449, 41969.58030419657, 43009.56990430056, 43119.78440107779, 41454.37818432786, 41607.58392416037, 42273.78863105665, 42552.14895702131, 42733.0, 36055.459168113026, 36728.26543469169, 36814.0, 37328.62671373251, 37672.246555069294, 28323.575146373234, 28808.423831523673, 28826.27934301659, 28908.566371504025, 28985.0], "value": 20413.140208173583, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [410.8890599538123, 415.5698851688503, 416.1655879960678, 416.33386581469654, 432.01716095881864, 17961.7305740414, 18296.542586435346, 18980.0, 19004.90497547512, 19250.807491925076, 17540.210690518925, 18083.367082152126, 18450.0, 18482.630347393046, 18483.168257428413, 5619.971900140504, 5661.009323368415, 5763.654180749148, 5797.043487824504, 5824.796132135376, 5674.290713660793, 5762.308522977249, 5763.625364351325, 5770.567207459437, 5800.071988481844, 5551.2228288039705, 5683.687397193153, 5720.771169153229, 5721.31344238692, 5724.198612194296, 5603.495685388294, 5656.264685590888, 5668.744906479177, 5671.206031155621, 5708.22938903249, 4893.241547560134, 4953.975230123826, 4973.676711013809, 5035.647504674675, 5047.621428392848, 21190.576188476298, 21388.0, 21446.678299825515, 21512.354629361143, 22072.33782986513, 2106.662933930579, 2250.5273892482596, 2361.1971929543865, 2421.7093948726088, 2474.938126546833, 13783.173009619455, 13816.654583635389, 13913.860861391358, 13932.512362067278, 14503.854961450355, 1904.8476121910214, 1957.0, 1962.8920409377479, 1983.1571582077559, 2049.2008116834445, 71439.0, 74010.62994684992, 74037.25962740411, 74493.0, 74812.6259368705, 9356.204722598519, 9653.71038868835, 10538.63114790994, 10580.52387642558, 10730.0, 14628.634284142981, 14672.0, 14689.706205876037, 14693.412263509554, 14724.55826325212, 8244.134365891616, 8294.419390642597, 8322.62548185333, 8425.031121420992, 8483.787905302417, 7996.080450748238, 7998.520088794575, 8037.959810200911, 8115.269625733712, 8188.14024527428, 6519.706613202417, 6575.473962083021, 6578.901316480256, 6592.670366481657, 6637.800865973932, 1894.895780732073, 1937.321937321926, 1940.7865134835156, 1954.1890115601866, 1971.2213675598346, 986.3982970388057, 1026.7850473805072, 1044.8850626431085, 1053.3890343600692, 1067.0253306433628, 17958.730619040725, 18104.9094754528, 18174.363897263796, 18749.156287967075, 19159.904200478908, 973.9047306078066, 1036.7097212780434, 1047.6804574604819, 1070.6359837655234, 1101.0695961912172, 10306.845397319046, 10321.277510574339, 10844.620438284623, 10889.292196007234, 11191.608293709682, 476694.0, 485827.0, 491176.0, 494788.0, 495794.0, 67693.0, 87508.5624571873, 90264.09735902557, 93820.0, 96294.51852740691, 1076.2896488317672, 1088.684281558355, 1089.3355053417408, 1114.3759494683004, 1119.921605487608, 16124.516264512084, 16369.017858928502, 16583.0, 16611.83388166151, 16667.416640417527, 39933.60066399299, 40090.06984906782, 40521.0, 41268.222448857785, 41542.376864347665, 50035.0, 51134.23298650597, 53368.4663153379, 58978.41021589729, 59427.70286148541, 8261.463004904663, 8664.0, 8748.212660860552, 8826.735197944072, 9015.639374424949, 12342.0, 13386.26375549354, 13434.261115638734, 13593.0, 13727.862721373058, 11554.768904621693, 11749.765004699688, 11901.226420282654, 11975.820362694742, 12170.878291217328, 11325.093992480417, 11352.375619341015, 11429.542818287508, 11460.484278207334, 11474.254173478697, 11703.356315402734, 11952.820707689218, 12058.336791476553, 12084.577039803564, 12193.329366884907, 8537.103604121477, 8660.35047371437, 8734.432261902955, 8883.200511953955, 8890.777730556787, 8502.0, 8534.615942282488, 8658.39391242607, 8801.47191168532, 8970.506622135845], "value": 9209.74973376458, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1357.2806412601324, 1526.6030831983685, 1566.514380542032, 1729.3947118508524, 1857.6377606366764, 8674.089220631837, 9196.034416386276, 9214.98635150133, 9524.904750952488, 9537.380070295427, 9192.816143677122, 9409.435433873972, 9419.48192849393, 9424.104710052534, 9432.103950124745, 2076.0138934006377, 2348.7181538215355, 2387.55830171418, 2412.6381042843536, 2418.0569577864558, 2419.4919066995953, 2426.842255253403, 2439.9512009759883, 2440.3777036855577, 2447.119037146626, 2387.069043073203, 2404.3989002749313, 2412.8311018228733, 2441.4506735984437, 2441.6581678565017, 2014.4359579317816, 2327.7904988551027, 2388.6536452214505, 2396.5446565152565, 2406.7713567211085, 2306.8731219782903, 2325.8255630827757, 2328.953420931572, 2331.2889568681544, 2339.777721116508, 9326.0, 9332.953335233347, 9368.531573421302, 9413.388129771543, 9437.480938548377, 5376.946230537723, 5554.611177217596, 5559.33288005442, 5584.776608935637, 5625.8593535161535, 3566.5898421681577, 3599.6580324869096, 3654.196076863086, 3655.3785856404475, 3657.414813629833, 1119.6641007697658, 1120.7814476177148, 1120.7814476177148, 1131.0612191880718, 1145.604766355606, 13214.735705285937, 13224.735505289937, 13270.734585308337, 13339.66650833737, 13341.866581334256, 4890.779914903838, 4897.632677549127, 5013.724245166551, 5015.22264049073, 5043.646944713908, 10914.508847101899, 10927.836082458769, 10958.671239862817, 11001.669949901516, 11025.724356891142, 4968.8757781055765, 5574.860628484239, 5587.3015873015875, 5591.608587398924, 5599.0, 4971.825986090469, 5076.832328564451, 5102.974485127551, 5120.974395128001, 5141.897162056738, 4130.958690413117, 4184.560621134798, 4207.663386929038, 4236.9364459533135, 4239.533651298355, 1313.753481546566, 1324.443733631877, 1327.6017194841509, 1334.8264725585807, 1337.104140226051, 933.3000249812641, 1007.6926537406015, 1008.3546530220665, 1016.7966406718685, 1021.7956408718137, 6411.871762564816, 6477.287498375175, 6587.703553340111, 6629.9337006630285, 6700.396964273239, 479.57317986991603, 482.4235039128278, 482.85755702067974, 1045.0281238448165, 1048.727330893973, 4262.552431994658, 4306.5262821089655, 4394.340848872695, 4409.801558929856, 4488.820447182076, 11654.242474239147, 11788.528458861721, 11810.704732381759, 11864.16950813452, 11897.940510297392, 11232.269902456477, 11676.47455864488, 11719.707007324885, 11791.056715462742, 11818.704532386588, 8329.750107496784, 8723.171298726611, 8768.123187681182, 8771.298296136294, 8775.166359195866, 6197.876042479035, 6418.101465794886, 6503.934960650522, 6515.6742162892615, 6548.214214294315, 10762.461876905967, 10920.836187457036, 10929.508172132115, 10944.726381840517, 11135.33188008722, 11060.446977650925, 11203.663890083311, 11291.717707057389, 11408.771824563628, 11424.314541127558, 7405.518641288299, 8022.358211343195, 8221.876671849808, 8321.583920803814, 8355.206255405727, 9034.096590340914, 9103.772405689911, 9144.679936202467, 9179.495127768037, 9185.724428267162, 7857.646405911633, 8105.675772969251, 8164.265216130577, 8227.876581851397, 8385.58072096405, 7867.567283799217, 7919.326857216971, 8003.11965683786, 8142.18578142181, 8299.751007469786, 7069.9646501767165, 7099.680514376969, 7102.751403700846, 7145.249748776304, 7253.238409967089, 5257.185136303879, 5482.5613950883035, 5501.367342755634, 5530.170474428868, 5625.943740562542, 5671.234383358359, 5723.456271654186, 5768.44252051952, 5793.710314484343, 5823.854403639943], "value": 4856.281082398259, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [325.78660977060036, 335.57382124701627, 338.38582971905976, 339.9167204035012, 340.17168195444094, 4948.455669876311, 5070.51544743076, 5198.0, 5234.319538459999, 5241.44964778698, 5086.567641750456, 5149.510796474338, 5156.524868063332, 5227.607929405291, 5263.947360526394, 4115.814788334532, 4119.237940980921, 4124.6906482013965, 4128.2981893078095, 4138.3999320098565, 3889.2999260133165, 3891.708121890855, 4074.6553637299767, 4079.3473044312914, 4100.63094321511, 3985.4021896715435, 4019.7387169834014, 4071.063655359269, 4076.8369265229353, 4080.9387859182134, 3978.269452788032, 4013.1772986537835, 4019.8995025124314, 4037.9596204037875, 4061.390791381287, 3554.6445355464507, 3613.963860361389, 3621.022323972539, 3621.7283703722064, 3634.618365071682, 4933.580645645088, 4943.555080042814, 4948.604111671058, 4988.725620090893, 5015.39815222172, 4570.360149579062, 4586.908261834745, 4594.632429405639, 4601.493835678073, 4630.8147674093, 5119.334486516766, 5318.946810531884, 5333.466653334674, 5348.91976620351, 5360.276362691065, 3941.659835655862, 3942.211557688474, 3962.128331767007, 3965.7620542767527, 3977.6817854571564, 5575.553955683536, 5578.079616863212, 5601.439856014406, 5612.579056570774, 5634.098544232903, 4063.3701776224143, 4071.063655359225, 4071.42999980003, 4078.7144899856726, 4084.448599439097, 4025.0742329264435, 4064.207479541461, 4163.980469472303, 4171.83312667496, 4198.097409057067, 4690.015096829636, 4692.741899195577, 4693.601043911238, 4698.295255711602, 4742.691725037862, 5522.861928451821, 5524.226608274845, 5530.087535556588, 5539.473749993744, 5548.778048878, 1312.5471712259255, 1343.482759137739, 1396.1623026184209, 1408.788681697733, 1409.5771268619374, 903.9050899655573, 920.6179435534312, 923.6951805904035, 936.391345625349, 942.4533770413144, 4640.303954406866, 4654.883627909262, 4659.767011649472, 4697.694649847749, 4709.458412282563, 4914.493608259741, 4955.876103097379, 4961.776720047535, 4979.4771548987555, 5002.274670172805, 4444.509110661691, 4551.977240113844, 4601.895545069159, 4678.157931572282, 4692.859214223578, 2539.9238022859345, 2608.5565453872887, 2628.3166376742183, 2645.365112373055, 2670.559357705996, 4972.801087956441, 5005.724685142352, 5012.147934851084, 5040.873978150502, 5071.2139618359215, 4942.851714448572, 4972.627052971042, 4975.606830087581, 5047.4447810740785, 5253.3958594761325, 5662.886742265132, 5682.346530149003, 5770.249867517112, 5770.711464426762, 5810.4189581041555, 5547.583931205095, 5556.444355564573, 5582.637128586629, 5583.692896890548, 5597.132444471114, 5333.7066461345, 5537.4495141360485, 5544.805931792368, 5547.722613869371, 5607.271054762855, 5764.827055188351, 5768.307803063659, 5776.277965254343, 5797.376734514342, 5806.071028635398, 3865.69074474047, 4044.4742183515955, 4081.1225586499045, 4082.6320733974817, 4094.8125043738173, 5593.776248950159, 5663.603547751618, 5692.865175559165, 5709.600327977002, 5711.543076553949, 5519.0, 5523.585731070266, 5532.197831314517, 5565.30433695788, 5567.8585889891665, 5311.3891902431715, 5515.917261241005, 5519.951209270226, 5550.639208451437, 5561.221429000024, 5355.357357117171, 5362.490563396471, 5386.48828361305, 5415.187721841755, 5459.044667183148, 5180.171172612364, 5406.4052954175795, 5545.195946587803, 5552.333719953632, 5558.138488534284, 5254.579633629377, 5357.249985002025, 5390.272313237662, 5404.297441332602, 5419.34967803866], "value": 4087.11928709413, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [751.3650964934628, 789.2029050658837, 802.4022103532867, 805.8710606302993, 816.738643634037, 15511.61220969475, 16043.919780401111, 16059.437919672813, 16075.678486430279, 16082.276297566608, 14621.707565848677, 14699.926500367483, 14816.55550333489, 14821.258937053131, 14850.1832399218, 672.9360710732473, 787.6061969015492, 832.8126171611369, 841.0832192909727, 848.4824257203101, 749.8237914090184, 774.484967496617, 785.2265518464308, 813.3818298093462, 824.2787560884226, 880.520116536486, 889.973300800977, 890.9064548222441, 892.2995448572872, 892.8035832116924, 711.4521818200009, 830.1656834880936, 862.2756884217225, 863.7926897544606, 887.4586502233665, 635.196476457282, 635.2186810223404, 635.4090695653041, 635.9491240700733, 636.0427556527396, 14518.0, 14598.270086495633, 14607.926960365236, 14609.123452592772, 14610.561683149523, 13989.30053497321, 14272.857271427256, 14305.856941430557, 14329.57011289663, 14330.785038224434, 12328.630141095782, 12488.875111248863, 12709.364531773466, 12745.61763147107, 12941.029422793234, 4296.914061718748, 4300.65594752419, 4304.074623955865, 4318.805653745589, 4345.717528360678, 58246.70876645632, 59012.70493647547, 59397.7030114851, 59419.40580594182, 59611.70194149001, 11481.540738370539, 11537.769244615061, 11626.941865290619, 11664.591739289253, 11828.408579570987, 20581.07385167671, 20632.48418789542, 20654.793452065587, 20694.275700350416, 21376.679349809765, 8443.282321002784, 8501.787455313543, 8512.70205542803, 8584.613692383733, 8597.828043439096, 4560.612347950461, 4643.558861908114, 4643.628509719214, 4658.114958157939, 4805.0870334637, 3516.7889926603975, 3595.424732042861, 3600.6939410149907, 3606.495090687306, 3617.8010209438735, 657.3426573426574, 663.5355251324092, 664.7407511070686, 665.4210836572213, 675.2066322071546, 726.197551705358, 727.7271023366237, 735.4373903963366, 756.8297133144998, 776.2714060078828, 9373.531323433801, 9433.76415589602, 9574.66488672907, 9577.185939195246, 9683.806323873483, 581.2792137749161, 633.4204203154062, 687.6836655138692, 687.7455341523669, 688.1707542411382, 4567.566081222279, 4663.48701642819, 4922.827701030446, 4931.3835770528685, 4997.425296090996, 72808.0, 74823.25176748163, 74962.25037749662, 75222.24777752261, 78589.0, 67724.6613766928, 68691.0, 69824.65087674529, 70489.0, 70568.2943170572, 60688.0, 60850.39149608536, 61108.38891611027, 61317.38682613206, 62800.68599656972, 9339.0, 9358.29812764059, 9390.436573805593, 9409.85885211736, 9414.905850941403, 38932.80533597314, 39024.41463378108, 39484.0, 40376.3943540853, 40580.79709601433, 50300.748496257285, 55907.16139257833, 56351.71824140853, 56368.718156408955, 56580.151297729746, 13358.398872050975, 13522.526711565048, 13536.323183840965, 13742.175469471864, 14015.859841401456, 15006.6998660024, 15126.39494420255, 15553.455629052929, 15701.293441795375, 16051.388362868076, 14444.0, 14857.851421485646, 14924.104553726376, 15411.36951416479, 15495.0, 13726.519571814939, 13998.090124141898, 14003.0, 14060.08609440383, 14114.647133821736, 13435.596932092052, 13610.183388996691, 14346.426142954166, 14398.640033998814, 14425.49510767118, 11266.155038372319, 11527.308361498337, 11574.768504629692, 11679.766404671689, 11729.237599556001, 10917.344959302467, 11510.30938143714, 11625.128115391548, 11631.185816993067, 11717.12121590867], "value": 7271.477581782686, "unit": "score"}], "name": "dromaeo_css", "value": 7226.07583361544, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 105.0, 116.0, 113.0, 110.0, 110.0, 110.0, 111.0, 110.0, 111.0], "value": 110.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 134.0, 150.0, 206.0, 129.0, 155.0, 162.0, 130.0, 179.0, 129.0], "value": 152.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 160.0, 160.0, 160.0, 162.0, 161.0, 161.0, 164.0, 161.0, 162.0], "value": 161.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 76.0, 150.0, 76.0, 75.0, 76.0, 76.0, 131.0, 77.0], "value": 88.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 91.0, 143.0, 93.0, 92.0, 92.0, 119.0, 92.0, 92.0, 116.0], "value": 102.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 118.0, 117.0, 116.0, 116.0, 116.0, 116.0, 116.0, 118.0, 117.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 146.0, 148.0, 149.0, 148.0, 152.0, 148.0, 158.0, 148.0, 148.0], "value": 149.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 97.0, 96.0, 97.0, 97.0, 97.0, 97.0, 97.0, 95.0], "value": 96.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 54.0, 54.0, 55.0, 54.0, 54.0, 53.0, 108.0, 52.0, 51.0], "value": 58.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 58.0, 59.0, 58.0, 59.0, 58.0, 58.0, 59.0, 58.0, 59.0], "value": 58.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 86.0, 83.0, 80.0, 81.0, 82.0, 87.0, 85.0, 81.0, 82.0], "value": 83.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [125.0, 124.0, 135.0, 125.0, 131.0, 135.0, 136.0, 133.0, 205.0, 130.0], "value": 137.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 166.0, 169.0, 166.0, 169.0, 168.0, 167.0, 171.0, 170.0, 165.0], "value": 168.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 66.0, 65.0, 66.0, 64.0, 64.0, 64.0, 64.0, 66.0], "value": 64.9, "unit": "score"}], "name": "kraken", "value": 1549.5000000000005, "alertThreshold": 2.0}]} 16:17:22 INFO - Return code: 0 16:17:22 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 16:17:22 ERROR - # TBPL SUCCESS # 16:17:22 INFO - Running post-action listener: _resource_record_post_action 16:17:22 INFO - ##### 16:17:22 INFO - ##### Finished run-tests step (success) 16:17:22 INFO - ##### 16:17:22 INFO - Running post-run listener: _resource_record_post_run 16:17:23 INFO - Total resource usage - Wall time: 1190s; CPU: 26.0%; Read bytes: 0; Write bytes: 557383680; Read time: 0; Write time: 469840 16:17:23 INFO - TinderboxPrint: CPU usage
25.5% 16:17:23 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 16:17:23 INFO - TinderboxPrint: I/O write bytes / time
557,383,680 / 469,840 16:17:23 INFO - TinderboxPrint: CPU idle
7,071.1 (74.4%) 16:17:23 INFO - TinderboxPrint: CPU user
2,389.5 (25.2%) 16:17:23 INFO - TinderboxPrint: Swap in / out
0 / 0 16:17:23 INFO - install - Wall time: 17s; CPU: 26.0%; Read bytes: 0; Write bytes: 261697536; Read time: 0; Write time: 271076 16:17:23 INFO - run-tests - Wall time: 1174s; CPU: 26.0%; Read bytes: 0; Write bytes: 295473152; Read time: 0; Write time: 198736 16:17:23 INFO - Running post-run listener: _upload_blobber_files 16:17:23 INFO - Blob upload gear active. 16:17:23 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:17:23 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:17:23 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:17:23 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:17:24 INFO - (blobuploader) - INFO - Open directory for files ... 16:17:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:17:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:17:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:17:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:17:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:17:25 INFO - (blobuploader) - INFO - Done attempting. 16:17:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:17:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:17:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:17:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:17:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:17:26 INFO - (blobuploader) - INFO - Done attempting. 16:17:26 INFO - (blobuploader) - INFO - Iteration through files over. 16:17:26 INFO - Return code: 0 16:17:26 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:17:26 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:17:26 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/dc3433b7fa7696896ec8c7a6aecbe6b85ec39f9ae20fc8c87b95c7070a527dbff07808a03cdf11d9cf9bef43277cd420685cf25cbbb184741caff5158911f749", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/a3490138fcdd4d48408dc8be3d31b408cc78b91fd5148fd130fc9d3d49574df31a6149a6138b52cf03e7a41d271289a381caefab84ab98928538c24d90813075"} 16:17:26 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:17:26 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:17:26 INFO - Contents: 16:17:26 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/dc3433b7fa7696896ec8c7a6aecbe6b85ec39f9ae20fc8c87b95c7070a527dbff07808a03cdf11d9cf9bef43277cd420685cf25cbbb184741caff5158911f749", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/a3490138fcdd4d48408dc8be3d31b408cc78b91fd5148fd130fc9d3d49574df31a6149a6138b52cf03e7a41d271289a381caefab84ab98928538c24d90813075"} 16:17:26 INFO - Running post-run listener: copy_logs_to_upload_dir 16:17:26 INFO - Copying logs to upload dir... 16:17:26 INFO - mkdir: /builds/slave/test/build/upload/logs 16:17:26 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1229.078853 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 29 secs) (at 2016-05-12 16:17:25.704098) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-12 16:17:25.708181) ========= 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=4300f881736b422476174874000001bc-1463093815.836123-1850221183 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/dc3433b7fa7696896ec8c7a6aecbe6b85ec39f9ae20fc8c87b95c7070a527dbff07808a03cdf11d9cf9bef43277cd420685cf25cbbb184741caff5158911f749", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/a3490138fcdd4d48408dc8be3d31b408cc78b91fd5148fd130fc9d3d49574df31a6149a6138b52cf03e7a41d271289a381caefab84ab98928538c24d90813075"} build_url:https://queue.taskcluster.net/v1/task/BuaoIQ8_QJmIg2VxCtQepA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.005395 build_url: 'https://queue.taskcluster.net/v1/task/BuaoIQ8_QJmIg2VxCtQepA/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/dc3433b7fa7696896ec8c7a6aecbe6b85ec39f9ae20fc8c87b95c7070a527dbff07808a03cdf11d9cf9bef43277cd420685cf25cbbb184741caff5158911f749", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/a3490138fcdd4d48408dc8be3d31b408cc78b91fd5148fd130fc9d3d49574df31a6149a6138b52cf03e7a41d271289a381caefab84ab98928538c24d90813075"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-12 16:17:25.722170) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 16:17:25.722495) ========= 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=4300f881736b422476174874000001bc-1463093815.836123-1850221183 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.002095 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 16:17:25.801412) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-12 16:17:25.801729) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-12 16:17:25.802025) ========= ========= Total master_lag: 0.15 =========