builder: fx-team_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-055 starttime: 1462932500.34 results: success (0) buildid: 20160510174116 builduid: aaeac18913184f7085dd4218b989d9a2 revision: 68fddeb3a7dbc1adc7b8b8841523ec48961add04 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-10 19:08:20.335742) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-10 19:08:20.336133) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-10 19:08:20.336402) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-10 19:08:20.347060) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 19:08:20.347385) ========= 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=82e07478c745e68b1fe4678a000001bc-1462931301.948880-1036685957 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-10 19:08:21-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 426M=0s 2016-05-10 19:08:21 (426 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.069490 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 19:08:20.424859) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 19:08:20.425173) ========= 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=82e07478c745e68b1fe4678a000001bc-1462931301.948880-1036685957 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.009472 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 19:08:20.471215) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 19:08:20.471544) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 68fddeb3a7dbc1adc7b8b8841523ec48961add04 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 68fddeb3a7dbc1adc7b8b8841523ec48961add04 --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=82e07478c745e68b1fe4678a000001bc-1462931301.948880-1036685957 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-10 19:08:21,290 truncating revision to first 12 chars 2016-05-10 19:08:21,290 Setting DEBUG logging. 2016-05-10 19:08:21,290 attempt 1/10 2016-05-10 19:08:21,290 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/68fddeb3a7db?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-10 19:08:21,646 unpacking tar archive at: fx-team-68fddeb3a7db/testing/mozharness/ program finished with exit code 0 elapsedTime=0.604214 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 19:08:21.083548) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-10 19:08:21.083876) ========= script_repo_revision: 68fddeb3a7dbc1adc7b8b8841523ec48961add04 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-10 19:08:21.084278) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-10 19:08:21.084541) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-10 19:08:21.090517) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2016-05-10 19:08:21.090793) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO 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', 'Fx-Team-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team-Non-PGO'] 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=82e07478c745e68b1fe4678a000001bc-1462931301.948880-1036685957 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 19:08:21 INFO - MultiFileLogger online at 20160510 19:08:21 in /builds/slave/test 19:08:21 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO 19:08:21 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:08:21 INFO - {'append_to_log': False, 19:08:21 INFO - 'base_work_dir': '/builds/slave/test', 19:08:21 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 19:08:21 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:08:21 INFO - 'branch': 'Fx-Team-Non-PGO', 19:08:21 INFO - 'buildbot_json_path': 'buildprops.json', 19:08:21 INFO - 'config_files': ('talos/linux_config.py',), 19:08:21 INFO - 'default_actions': ('clobber', 19:08:21 INFO - 'read-buildbot-config', 19:08:21 INFO - 'download-and-extract', 19:08:21 INFO - 'populate-webroot', 19:08:21 INFO - 'create-virtualenv', 19:08:21 INFO - 'install', 19:08:21 INFO - 'run-tests'), 19:08:21 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:08:21 INFO - 'download_minidump_stackwalk': True, 19:08:21 INFO - 'download_symbols': 'ondemand', 19:08:21 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:08:21 INFO - 'tooltool.py': '/tools/tooltool.py', 19:08:21 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:08:21 INFO - '/tools/misc-python/virtualenv.py')}, 19:08:21 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:08:21 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:08:21 INFO - 'installer_path': 'installer.exe', 19:08:21 INFO - 'log_level': 'info', 19:08:21 INFO - 'log_name': 'talos', 19:08:21 INFO - 'log_to_console': True, 19:08:21 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 19:08:21 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 19:08:21 INFO - 'opt_config_files': (), 19:08:21 INFO - 'pip_index': False, 19:08:21 INFO - 'sps_profile': False, 19:08:21 INFO - 'sps_profile_interval': 0, 19:08:21 INFO - 'suite': 'dromaeojs', 19:08:21 INFO - 'system_bits': '32', 19:08:21 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:08:21 INFO - 'title': 'talos-linux64-ix-055', 19:08:21 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:08:21 INFO - 'use_talos_json': True, 19:08:21 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:08:21 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:08:21 INFO - 'work_dir': 'build'} 19:08:21 INFO - ##### 19:08:21 INFO - ##### Running clobber step. 19:08:21 INFO - ##### 19:08:21 INFO - Running pre-action listener: _resource_record_pre_action 19:08:21 INFO - Running main action method: clobber 19:08:21 INFO - rmtree: /builds/slave/test/build 19:08:21 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:08:22 INFO - Running post-action listener: _resource_record_post_action 19:08:22 INFO - ##### 19:08:22 INFO - ##### Finished clobber step (success) 19:08:22 INFO - ##### 19:08:22 INFO - ##### 19:08:22 INFO - ##### Running read-buildbot-config step. 19:08:22 INFO - ##### 19:08:22 INFO - Running pre-action listener: _resource_record_pre_action 19:08:22 INFO - Running main action method: read_buildbot_config 19:08:22 INFO - Using buildbot properties: 19:08:22 INFO - { 19:08:22 INFO - "project": "", 19:08:22 INFO - "product": "firefox", 19:08:22 INFO - "script_repo_revision": "production", 19:08:22 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 19:08:22 INFO - "repository": "", 19:08:22 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs", 19:08:22 INFO - "buildid": "20160510174116", 19:08:22 INFO - "pgo_build": "False", 19:08:22 INFO - "basedir": "/builds/slave/test", 19:08:22 INFO - "buildnumber": 2958, 19:08:22 INFO - "slavename": "talos-linux64-ix-055", 19:08:22 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 19:08:22 INFO - "platform": "ubuntu64_hw", 19:08:22 INFO - "scheduler": "tests-fx-team-linux64-talos", 19:08:22 INFO - "branch": "fx-team", 19:08:22 INFO - "repo_path": "integration/fx-team", 19:08:22 INFO - "revision": "68fddeb3a7dbc1adc7b8b8841523ec48961add04", 19:08:22 INFO - "stage_platform": "linux64", 19:08:22 INFO - "builduid": "aaeac18913184f7085dd4218b989d9a2", 19:08:22 INFO - "slavebuilddir": "test" 19:08:22 INFO - } 19:08:22 INFO - Found installer url https://queue.taskcluster.net/v1/task/D1-qap_SQMueqU6K-n7cSg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 19:08:22 INFO - Running post-action listener: _resource_record_post_action 19:08:22 INFO - ##### 19:08:22 INFO - ##### Finished read-buildbot-config step (success) 19:08:22 INFO - ##### 19:08:22 INFO - ##### 19:08:22 INFO - ##### Running download-and-extract step. 19:08:22 INFO - ##### 19:08:22 INFO - Running pre-action listener: _resource_record_pre_action 19:08:22 INFO - Running main action method: download_and_extract 19:08:22 INFO - mkdir: /builds/slave/test/build/tests 19:08: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')]} 19:08:22 INFO - https://queue.taskcluster.net/v1/task/D1-qap_SQMueqU6K-n7cSg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 19:08:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D1-qap_SQMueqU6K-n7cSg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 19:08:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D1-qap_SQMueqU6K-n7cSg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 19:08:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D1-qap_SQMueqU6K-n7cSg/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 19:08:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D1-qap_SQMueqU6K-n7cSg/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 19:08:25 INFO - Downloaded 1361 bytes. 19:08:25 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 19:08:25 INFO - Using the following test package requirements: 19:08:25 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 19:08:25 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 19:08:25 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 19:08:25 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 19:08:25 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 19:08:25 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 19:08:25 INFO - u'jsshell-linux-x86_64.zip'], 19:08:25 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 19:08:25 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 19:08:25 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 19:08:25 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 19:08:25 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 19:08:25 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 19:08:25 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 19:08:25 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 19:08:25 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 19:08:25 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 19:08:25 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 19:08:25 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 19:08:25 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')]} 19:08:25 INFO - https://queue.taskcluster.net/v1/task/D1-qap_SQMueqU6K-n7cSg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 19:08:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D1-qap_SQMueqU6K-n7cSg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 19:08:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D1-qap_SQMueqU6K-n7cSg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 19:08:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D1-qap_SQMueqU6K-n7cSg/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 19:08:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D1-qap_SQMueqU6K-n7cSg/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 19:08:27 INFO - Downloaded 22329481 bytes. 19:08:27 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'] 19:08:27 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 19:08:28 INFO - Return code: 0 19:08:28 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 19:08: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')]} 19:08:28 INFO - https://queue.taskcluster.net/v1/task/D1-qap_SQMueqU6K-n7cSg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 19:08:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D1-qap_SQMueqU6K-n7cSg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 19:08:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D1-qap_SQMueqU6K-n7cSg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 19:08:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D1-qap_SQMueqU6K-n7cSg/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 19:08:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D1-qap_SQMueqU6K-n7cSg/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 19:08:33 INFO - Downloaded 22329481 bytes. 19:08:33 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'] 19:08:33 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 19:08:34 INFO - Return code: 0 19:08:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:08:34 INFO - https://queue.taskcluster.net/v1/task/D1-qap_SQMueqU6K-n7cSg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 19:08:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D1-qap_SQMueqU6K-n7cSg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 19:08:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D1-qap_SQMueqU6K-n7cSg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 19:08:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D1-qap_SQMueqU6K-n7cSg/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 19:08:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D1-qap_SQMueqU6K-n7cSg/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 19:08:35 INFO - Downloaded 10840152 bytes. 19:08:35 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'] 19:08:35 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 19:08:35 INFO - Return code: 0 19:08:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:08:35 INFO - https://queue.taskcluster.net/v1/task/D1-qap_SQMueqU6K-n7cSg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 19:08:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D1-qap_SQMueqU6K-n7cSg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 19:08:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D1-qap_SQMueqU6K-n7cSg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 19:08:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D1-qap_SQMueqU6K-n7cSg/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 19:08:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D1-qap_SQMueqU6K-n7cSg/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 19:08:36 INFO - Downloaded 54677694 bytes. 19:08:36 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/D1-qap_SQMueqU6K-n7cSg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 19:08:36 INFO - mkdir: /builds/slave/test/properties 19:08:36 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:08:36 INFO - Writing to file /builds/slave/test/properties/build_url 19:08:36 INFO - Contents: 19:08:36 INFO - build_url:https://queue.taskcluster.net/v1/task/D1-qap_SQMueqU6K-n7cSg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 19:08:37 INFO - Running post-action listener: _resource_record_post_action 19:08:37 INFO - Running post-action listener: set_extra_try_arguments 19:08:37 INFO - ##### 19:08:37 INFO - ##### Finished download-and-extract step (success) 19:08:37 INFO - ##### 19:08:37 INFO - ##### 19:08:37 INFO - ##### Running populate-webroot step. 19:08:37 INFO - ##### 19:08:37 INFO - Running pre-action listener: _resource_record_pre_action 19:08:37 INFO - Running main action method: populate_webroot 19:08:37 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:08:37 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:08:37 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:08:37 INFO - '--tppagecycles', 19:08:37 INFO - '7'], 19:08:37 INFO - 'tests': ['tsvgm']}}, 19:08:37 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:08:37 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:08:37 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:08:37 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:08:37 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:08:37 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:08:37 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:08:37 INFO - 'talos_options': ['--disable-e10s'], 19:08:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:08:37 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:08:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:08:37 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:08:37 INFO - 'talos_options': ['--disable-e10s'], 19:08:37 INFO - 'tests': ['damp', 'tps']}, 19:08:37 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:08:37 INFO - 'tests': ['damp', 'tps']}, 19:08:37 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:08:37 INFO - 'tests': ['dromaeo_dom']}, 19:08:37 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:08:37 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:08:37 INFO - 'tests': ['a11yr', 19:08:37 INFO - 'ts_paint', 19:08:37 INFO - 'tpaint', 19:08:37 INFO - 'sessionrestore', 19:08:37 INFO - 'sessionrestore_no_auto_restore', 19:08:37 INFO - 'tabpaint']}, 19:08:37 INFO - 'other-e10s': {'tests': ['a11yr', 19:08:37 INFO - 'ts_paint', 19:08:37 INFO - 'tpaint', 19:08:37 INFO - 'sessionrestore', 19:08:37 INFO - 'sessionrestore_no_auto_restore', 19:08:37 INFO - 'tabpaint']}, 19:08:37 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:08:37 INFO - 'ts_paint', 19:08:37 INFO - 'tpaint', 19:08:37 INFO - 'sessionrestore', 19:08:37 INFO - 'sessionrestore_no_auto_restore', 19:08:37 INFO - 'tabpaint']}, 19:08:37 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:08:37 INFO - 'ts_paint', 19:08:37 INFO - 'tpaint', 19:08:37 INFO - 'sessionrestore', 19:08:37 INFO - 'sessionrestore_no_auto_restore', 19:08:37 INFO - 'tabpaint']}, 19:08:37 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:08:37 INFO - 'tests': ['a11yr', 19:08:37 INFO - 'ts_paint', 19:08:37 INFO - 'tpaint', 19:08:37 INFO - 'sessionrestore', 19:08:37 INFO - 'sessionrestore_no_auto_restore', 19:08:37 INFO - 'tabpaint']}, 19:08:37 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:08:37 INFO - 'tests': ['a11yr', 19:08:37 INFO - 'ts_paint', 19:08:37 INFO - 'tpaint', 19:08:37 INFO - 'sessionrestore', 19:08:37 INFO - 'sessionrestore_no_auto_restore', 19:08:37 INFO - 'tabpaint']}, 19:08:37 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:08:37 INFO - 'tests': ['tsvgx', 19:08:37 INFO - 'tsvgr_opacity', 19:08:37 INFO - 'tart', 19:08:37 INFO - 'tscrollx', 19:08:37 INFO - 'cart']}, 19:08:37 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:08:37 INFO - 'tsvgr_opacity', 19:08:37 INFO - 'tart', 19:08:37 INFO - 'tscrollx', 19:08:37 INFO - 'cart']}, 19:08:37 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:08:37 INFO - 'talos_options': ['--disable-e10s'], 19:08:37 INFO - 'tests': ['tp5o']}, 19:08:37 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:08:37 INFO - 'tests': ['tp5o']}, 19:08:37 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:08:37 INFO - 'talos_options': ['--disable-e10s', 19:08:37 INFO - '--xperf_path', 19:08:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:08:37 INFO - 'tests': ['tp5n']}, 19:08:37 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:08:37 INFO - 'talos_options': ['--xperf_path', 19:08:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:08:37 INFO - 'tests': ['tp5n']}}, 19:08:37 INFO - 'talos.zip': {'path': '', 19:08:37 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:08:37 INFO - Running post-action listener: _resource_record_post_action 19:08:37 INFO - ##### 19:08:37 INFO - ##### Finished populate-webroot step (success) 19:08:37 INFO - ##### 19:08:37 INFO - ##### 19:08:37 INFO - ##### Running create-virtualenv step. 19:08:37 INFO - ##### 19:08:37 INFO - Running pre-action listener: _resource_record_pre_action 19:08:37 INFO - Running main action method: create_virtualenv 19:08:37 INFO - Creating virtualenv /builds/slave/test/build/venv 19:08:37 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 19:08:37 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:08:37 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:08:37 INFO - Using real prefix '/usr' 19:08:37 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:08:38 INFO - Installing distribute.............................................................................................................................................................................................done. 19:08:40 INFO - Installing pip.................done. 19:08:40 INFO - Return code: 0 19:08:40 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:08:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:08:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:08:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:08:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:08:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:08:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:08:40 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 0x7f98709c0f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x169e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1769770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17f89c0>, '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 0x17f98c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17f9d50>, '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': '82e07478c745e68b1fe4678a000001bc-1462931301.948880-1036685957', '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 19:08:40 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 19:08:40 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 19:08:40 INFO - Using env: {'DISPLAY': ':0', 19:08:40 INFO - 'HOME': '/home/cltbld', 19:08:40 INFO - 'LANG': 'en_US.UTF-8', 19:08:40 INFO - 'LANGUAGE': 'en_US:en', 19:08:40 INFO - 'LOGNAME': 'cltbld', 19:08:40 INFO - 'MAIL': '/var/mail/cltbld', 19:08:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:08:40 INFO - 'MOZ_NO_REMOTE': '1', 19:08:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:08:40 INFO - 'NO_EM_RESTART': '1', 19:08:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:08:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:08:40 INFO - 'PWD': '/builds/slave/test', 19:08:40 INFO - 'SHELL': '/bin/bash', 19:08:40 INFO - 'SHLVL': '1', 19:08:40 INFO - 'TERM': 'linux', 19:08:40 INFO - 'TMOUT': '86400', 19:08:40 INFO - 'USER': 'cltbld', 19:08:40 INFO - 'XDG_SESSION_COOKIE': '82e07478c745e68b1fe4678a000001bc-1462931301.948880-1036685957', 19:08:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:08:40 INFO - '_': '/tools/buildbot/bin/python'} 19:08:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:08:40 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 19:08:40 INFO - Cleaning up... 19:08:40 INFO - Return code: 0 19:08:40 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 19:08:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:08:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:08:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:08:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:08:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:08:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:08:40 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 0x7f98709c0f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x169e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1769770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17f89c0>, '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 0x17f98c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17f9d50>, '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': '82e07478c745e68b1fe4678a000001bc-1462931301.948880-1036685957', '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 19:08:40 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 19:08:40 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 19:08:40 INFO - Using env: {'DISPLAY': ':0', 19:08:40 INFO - 'HOME': '/home/cltbld', 19:08:40 INFO - 'LANG': 'en_US.UTF-8', 19:08:40 INFO - 'LANGUAGE': 'en_US:en', 19:08:40 INFO - 'LOGNAME': 'cltbld', 19:08:40 INFO - 'MAIL': '/var/mail/cltbld', 19:08:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:08:40 INFO - 'MOZ_NO_REMOTE': '1', 19:08:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:08:40 INFO - 'NO_EM_RESTART': '1', 19:08:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:08:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:08:40 INFO - 'PWD': '/builds/slave/test', 19:08:40 INFO - 'SHELL': '/bin/bash', 19:08:40 INFO - 'SHLVL': '1', 19:08:40 INFO - 'TERM': 'linux', 19:08:40 INFO - 'TMOUT': '86400', 19:08:40 INFO - 'USER': 'cltbld', 19:08:40 INFO - 'XDG_SESSION_COOKIE': '82e07478c745e68b1fe4678a000001bc-1462931301.948880-1036685957', 19:08:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:08:40 INFO - '_': '/tools/buildbot/bin/python'} 19:08:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:08:40 INFO - Downloading/unpacking psutil>=0.7.1 19:08:40 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 19:08:40 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 19:08:40 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 19:08:40 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 19:08:40 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 19:08:40 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 19:08:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:08:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:08:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:08:42 INFO - Installing collected packages: psutil 19:08:42 INFO - Running setup.py install for psutil 19:08:42 INFO - building 'psutil._psutil_linux' extension 19:08:42 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 19:08:42 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 19:08:42 INFO - building 'psutil._psutil_posix' extension 19:08:42 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 19:08:42 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 19:08:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:08:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:08:43 INFO - Successfully installed psutil 19:08:43 INFO - Cleaning up... 19:08:43 INFO - Return code: 0 19:08:43 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 19:08:43 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')]} 19:08:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:08:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:08:43 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')]} 19:08:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:08:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:08:43 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 0x7f98709c0f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x169e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1769770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17f89c0>, '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 0x17f98c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17f9d50>, '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': '82e07478c745e68b1fe4678a000001bc-1462931301.948880-1036685957', '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 19:08:43 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 19:08:43 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 19:08:43 INFO - Using env: {'DISPLAY': ':0', 19:08:43 INFO - 'HOME': '/home/cltbld', 19:08:43 INFO - 'LANG': 'en_US.UTF-8', 19:08:43 INFO - 'LANGUAGE': 'en_US:en', 19:08:43 INFO - 'LOGNAME': 'cltbld', 19:08:43 INFO - 'MAIL': '/var/mail/cltbld', 19:08:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:08:43 INFO - 'MOZ_NO_REMOTE': '1', 19:08:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:08:43 INFO - 'NO_EM_RESTART': '1', 19:08:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:08:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:08:43 INFO - 'PWD': '/builds/slave/test', 19:08:43 INFO - 'SHELL': '/bin/bash', 19:08:43 INFO - 'SHLVL': '1', 19:08:43 INFO - 'TERM': 'linux', 19:08:43 INFO - 'TMOUT': '86400', 19:08:43 INFO - 'USER': 'cltbld', 19:08:43 INFO - 'XDG_SESSION_COOKIE': '82e07478c745e68b1fe4678a000001bc-1462931301.948880-1036685957', 19:08:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:08:43 INFO - '_': '/tools/buildbot/bin/python'} 19:08:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:08:43 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 19:08:43 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 19:08:43 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 19:08:43 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 19:08:43 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 19:08:43 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 19:08:43 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 19:08:45 INFO - Downloading mozsystemmonitor-0.0.tar.gz 19:08:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:08:45 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 19:08:45 INFO - Installing collected packages: mozsystemmonitor 19:08:45 INFO - Running setup.py install for mozsystemmonitor 19:08:45 INFO - Successfully installed mozsystemmonitor 19:08:45 INFO - Cleaning up... 19:08:45 INFO - Return code: 0 19:08:45 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:08:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:08:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:08:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:08:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:08:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:08:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:08:45 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 0x7f98709c0f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x169e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1769770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17f89c0>, '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 0x17f98c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17f9d50>, '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': '82e07478c745e68b1fe4678a000001bc-1462931301.948880-1036685957', '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 19:08:45 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 19:08:45 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 19:08:45 INFO - Using env: {'DISPLAY': ':0', 19:08:45 INFO - 'HOME': '/home/cltbld', 19:08:45 INFO - 'LANG': 'en_US.UTF-8', 19:08:45 INFO - 'LANGUAGE': 'en_US:en', 19:08:45 INFO - 'LOGNAME': 'cltbld', 19:08:45 INFO - 'MAIL': '/var/mail/cltbld', 19:08:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:08:45 INFO - 'MOZ_NO_REMOTE': '1', 19:08:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:08:45 INFO - 'NO_EM_RESTART': '1', 19:08:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:08:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:08:45 INFO - 'PWD': '/builds/slave/test', 19:08:45 INFO - 'SHELL': '/bin/bash', 19:08:45 INFO - 'SHLVL': '1', 19:08:45 INFO - 'TERM': 'linux', 19:08:45 INFO - 'TMOUT': '86400', 19:08:45 INFO - 'USER': 'cltbld', 19:08:45 INFO - 'XDG_SESSION_COOKIE': '82e07478c745e68b1fe4678a000001bc-1462931301.948880-1036685957', 19:08:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:08:45 INFO - '_': '/tools/buildbot/bin/python'} 19:08:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:08:45 INFO - Downloading/unpacking blobuploader==1.2.4 19:08:45 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 19:08:45 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 19:08:45 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 19:08:45 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 19:08:45 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 19:08:45 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 19:08:47 INFO - Downloading blobuploader-1.2.4.tar.gz 19:08:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:08:47 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:08:47 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 19:08:47 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 19:08:47 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 19:08:47 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 19:08:47 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 19:08:47 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 19:08:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:08:47 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:08:47 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 19:08:47 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 19:08:47 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 19:08:47 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 19:08:47 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 19:08:47 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 19:08:48 INFO - Downloading docopt-0.6.1.tar.gz 19:08:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:08:48 INFO - Installing collected packages: blobuploader, requests, docopt 19:08:48 INFO - Running setup.py install for blobuploader 19:08:48 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:08:48 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:08:48 INFO - Running setup.py install for requests 19:08:48 INFO - Running setup.py install for docopt 19:08:48 INFO - Successfully installed blobuploader requests docopt 19:08:48 INFO - Cleaning up... 19:08:48 INFO - Return code: 0 19:08:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:08: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')]} 19:08:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:08:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:08: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')]} 19:08:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:08:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:08:48 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 0x7f98709c0f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x169e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1769770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17f89c0>, '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 0x17f98c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17f9d50>, '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': '82e07478c745e68b1fe4678a000001bc-1462931301.948880-1036685957', '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 19:08:48 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 19:08:48 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 19:08:48 INFO - Using env: {'DISPLAY': ':0', 19:08:48 INFO - 'HOME': '/home/cltbld', 19:08:48 INFO - 'LANG': 'en_US.UTF-8', 19:08:48 INFO - 'LANGUAGE': 'en_US:en', 19:08:48 INFO - 'LOGNAME': 'cltbld', 19:08:48 INFO - 'MAIL': '/var/mail/cltbld', 19:08:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:08:48 INFO - 'MOZ_NO_REMOTE': '1', 19:08:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:08:48 INFO - 'NO_EM_RESTART': '1', 19:08:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:08:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:08:48 INFO - 'PWD': '/builds/slave/test', 19:08:48 INFO - 'SHELL': '/bin/bash', 19:08:48 INFO - 'SHLVL': '1', 19:08:48 INFO - 'TERM': 'linux', 19:08:48 INFO - 'TMOUT': '86400', 19:08:48 INFO - 'USER': 'cltbld', 19:08:48 INFO - 'XDG_SESSION_COOKIE': '82e07478c745e68b1fe4678a000001bc-1462931301.948880-1036685957', 19:08:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:08:48 INFO - '_': '/tools/buildbot/bin/python'} 19:08:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:08:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:08:48 INFO - Running setup.py (path:/tmp/pip-Zn4YS5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:08:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:08:48 INFO - Running setup.py (path:/tmp/pip-0KhAlk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:08:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:08:48 INFO - Running setup.py (path:/tmp/pip-BWdywE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:08:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:08:49 INFO - Running setup.py (path:/tmp/pip-XC9r_O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:08:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:08:49 INFO - Running setup.py (path:/tmp/pip-ALTZ_K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:08:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:08:49 INFO - Running setup.py (path:/tmp/pip-HdLZXq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:08:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:08:49 INFO - Running setup.py (path:/tmp/pip-E1WSNv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:08:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:08:49 INFO - Running setup.py (path:/tmp/pip-ejMNiY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:08:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:08:49 INFO - Running setup.py (path:/tmp/pip-UtfAs1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:08:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:08:49 INFO - Running setup.py (path:/tmp/pip-tGczEw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:08:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:08:49 INFO - Running setup.py (path:/tmp/pip-KrMh8M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:08:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:08:49 INFO - Running setup.py (path:/tmp/pip-5xLBfQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:08:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:08:49 INFO - Running setup.py (path:/tmp/pip-87fbKz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:08:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:08:49 INFO - Running setup.py (path:/tmp/pip-ZL87kx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:08:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:08:49 INFO - Running setup.py (path:/tmp/pip-z8EoKV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:08:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:08:49 INFO - Running setup.py (path:/tmp/pip-ef2lWw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:08:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:08:49 INFO - Running setup.py (path:/tmp/pip-vWvy5j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:08:49 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:08:49 INFO - Running setup.py install for manifestparser 19:08:49 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:08:49 INFO - Running setup.py install for mozcrash 19:08:49 INFO - Running setup.py install for mozdebug 19:08:50 INFO - Running setup.py install for mozdevice 19:08:50 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:08:50 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:08:50 INFO - Running setup.py install for mozfile 19:08:50 INFO - Running setup.py install for mozhttpd 19:08:50 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:08:50 INFO - Running setup.py install for mozinfo 19:08:50 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:08:50 INFO - Running setup.py install for mozInstall 19:08:50 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:08:50 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:08:50 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:08:50 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:08:50 INFO - Running setup.py install for mozleak 19:08:50 INFO - Running setup.py install for mozlog 19:08:50 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:08:50 INFO - Running setup.py install for moznetwork 19:08:50 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:08:50 INFO - Running setup.py install for mozprocess 19:08:50 INFO - Running setup.py install for mozprofile 19:08:51 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:08:51 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:08:51 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:08:51 INFO - Running setup.py install for mozrunner 19:08:51 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:08:51 INFO - Running setup.py install for mozscreenshot 19:08:51 INFO - Running setup.py install for moztest 19:08:51 INFO - Running setup.py install for mozversion 19:08:51 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:08:51 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:08:51 INFO - Cleaning up... 19:08:51 INFO - Return code: 0 19:08:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:08:51 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')]} 19:08:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:08:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:08:51 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')]} 19:08:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:08:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:08:51 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 0x7f98709c0f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x169e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1769770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17f89c0>, '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 0x17f98c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17f9d50>, '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': '82e07478c745e68b1fe4678a000001bc-1462931301.948880-1036685957', '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 19:08:51 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 19:08:51 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 19:08:51 INFO - Using env: {'DISPLAY': ':0', 19:08:51 INFO - 'HOME': '/home/cltbld', 19:08:51 INFO - 'LANG': 'en_US.UTF-8', 19:08:51 INFO - 'LANGUAGE': 'en_US:en', 19:08:51 INFO - 'LOGNAME': 'cltbld', 19:08:51 INFO - 'MAIL': '/var/mail/cltbld', 19:08:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:08:51 INFO - 'MOZ_NO_REMOTE': '1', 19:08:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:08:51 INFO - 'NO_EM_RESTART': '1', 19:08:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:08:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:08:51 INFO - 'PWD': '/builds/slave/test', 19:08:51 INFO - 'SHELL': '/bin/bash', 19:08:51 INFO - 'SHLVL': '1', 19:08:51 INFO - 'TERM': 'linux', 19:08:51 INFO - 'TMOUT': '86400', 19:08:51 INFO - 'USER': 'cltbld', 19:08:51 INFO - 'XDG_SESSION_COOKIE': '82e07478c745e68b1fe4678a000001bc-1462931301.948880-1036685957', 19:08:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:08:51 INFO - '_': '/tools/buildbot/bin/python'} 19:08:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:08:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:08:51 INFO - Running setup.py (path:/tmp/pip-cSiGjm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:08:51 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)) 19:08:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:08:51 INFO - Running setup.py (path:/tmp/pip-xFQEx3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:08:51 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)) 19:08:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:08:51 INFO - Running setup.py (path:/tmp/pip-WSrsGt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:08:51 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)) 19:08:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:08:51 INFO - Running setup.py (path:/tmp/pip-PADKSx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:08:51 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)) 19:08:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:08:51 INFO - Running setup.py (path:/tmp/pip-KNDUgp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:08:51 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)) 19:08:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:08:51 INFO - Running setup.py (path:/tmp/pip-vWENCU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:08:51 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)) 19:08:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:08:51 INFO - Running setup.py (path:/tmp/pip-KANX83-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:08:52 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)) 19:08:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:08:52 INFO - Running setup.py (path:/tmp/pip-XdbjeR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:08:52 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)) 19:08:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:08:52 INFO - Running setup.py (path:/tmp/pip-gki7Gz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:08:52 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)) 19:08:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:08:52 INFO - Running setup.py (path:/tmp/pip-iEEk1K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:08:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:08:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:08:52 INFO - Running setup.py (path:/tmp/pip-LcXKeG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:08:52 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)) 19:08:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:08:52 INFO - Running setup.py (path:/tmp/pip-cdG7y9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:08:52 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)) 19:08:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:08:52 INFO - Running setup.py (path:/tmp/pip-Ue4mi0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:08:52 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)) 19:08:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:08:52 INFO - Running setup.py (path:/tmp/pip-4D_eFU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:08:52 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)) 19:08:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:08:52 INFO - Running setup.py (path:/tmp/pip-GrJGlb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:08:52 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)) 19:08:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:08:52 INFO - Running setup.py (path:/tmp/pip-XjwPDX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:08:52 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)) 19:08:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:08:52 INFO - Running setup.py (path:/tmp/pip-VTuuox-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:08:52 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)) 19:08:52 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)) 19:08:52 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)) 19:08:52 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)) 19:08:52 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)) 19:08:52 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)) 19:08:52 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:08:52 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 19:08:52 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 19:08:52 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 19:08:52 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 19:08:52 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 19:08:52 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 19:08:54 INFO - Downloading blessings-1.6.tar.gz 19:08:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:08:54 INFO - Installing collected packages: blessings 19:08:54 INFO - Running setup.py install for blessings 19:08:54 INFO - Successfully installed blessings 19:08:54 INFO - Cleaning up... 19:08:54 INFO - Return code: 0 19:08:54 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:08:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:08:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:08:54 INFO - Reading from file tmpfile_stdout 19:08:54 INFO - Current package versions: 19:08:54 INFO - argparse == 1.2.1 19:08:54 INFO - blessings == 1.6 19:08:54 INFO - blobuploader == 1.2.4 19:08:54 INFO - docopt == 0.6.1 19:08:54 INFO - manifestparser == 1.1 19:08:54 INFO - mozInstall == 1.12 19:08:54 INFO - mozcrash == 0.17 19:08:54 INFO - mozdebug == 0.1 19:08:54 INFO - mozdevice == 0.48 19:08:54 INFO - mozfile == 1.2 19:08:54 INFO - mozhttpd == 0.7 19:08:54 INFO - mozinfo == 0.9 19:08:54 INFO - mozleak == 0.1 19:08:54 INFO - mozlog == 3.1 19:08:54 INFO - moznetwork == 0.27 19:08:54 INFO - mozprocess == 0.22 19:08:54 INFO - mozprofile == 0.28 19:08:54 INFO - mozrunner == 6.11 19:08:54 INFO - mozscreenshot == 0.1 19:08:54 INFO - mozsystemmonitor == 0.0 19:08:54 INFO - moztest == 0.7 19:08:54 INFO - mozversion == 1.4 19:08:54 INFO - psutil == 3.1.1 19:08:54 INFO - requests == 1.2.3 19:08:54 INFO - wsgiref == 0.1.2 19:08:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:08:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:08:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:08:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:08:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:08:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:08:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:08:54 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 0x7f98709c0f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x169e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1769770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17f89c0>, '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 0x17f98c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17f9d50>, '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': '82e07478c745e68b1fe4678a000001bc-1462931301.948880-1036685957', '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 19:08:54 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 19:08:54 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 19:08:54 INFO - Using env: {'DISPLAY': ':0', 19:08:54 INFO - 'HOME': '/home/cltbld', 19:08:54 INFO - 'LANG': 'en_US.UTF-8', 19:08:54 INFO - 'LANGUAGE': 'en_US:en', 19:08:54 INFO - 'LOGNAME': 'cltbld', 19:08:54 INFO - 'MAIL': '/var/mail/cltbld', 19:08:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:08:54 INFO - 'MOZ_NO_REMOTE': '1', 19:08:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:08:54 INFO - 'NO_EM_RESTART': '1', 19:08:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:08:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:08:54 INFO - 'PWD': '/builds/slave/test', 19:08:54 INFO - 'SHELL': '/bin/bash', 19:08:54 INFO - 'SHLVL': '1', 19:08:54 INFO - 'TERM': 'linux', 19:08:54 INFO - 'TMOUT': '86400', 19:08:54 INFO - 'USER': 'cltbld', 19:08:54 INFO - 'XDG_SESSION_COOKIE': '82e07478c745e68b1fe4678a000001bc-1462931301.948880-1036685957', 19:08:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:08:54 INFO - '_': '/tools/buildbot/bin/python'} 19:08:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:08:55 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)) 19:08:55 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)) 19:08:55 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)) 19:08:55 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)) 19:08:55 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)) 19:08:55 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)) 19:08:55 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)) 19:08:55 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)) 19:08:55 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)) 19:08:55 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)) 19:08:55 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)) 19:08:55 INFO - Cleaning up... 19:08:55 INFO - Return code: 0 19:08:55 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:08:55 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')]} 19:08:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:08:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:08:55 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')]} 19:08:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:08:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:08:55 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 0x7f98709c0f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x169e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1769770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17f89c0>, '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 0x17f98c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17f9d50>, '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': '82e07478c745e68b1fe4678a000001bc-1462931301.948880-1036685957', '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 19:08:55 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 19:08:55 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 19:08:55 INFO - Using env: {'DISPLAY': ':0', 19:08:55 INFO - 'HOME': '/home/cltbld', 19:08:55 INFO - 'LANG': 'en_US.UTF-8', 19:08:55 INFO - 'LANGUAGE': 'en_US:en', 19:08:55 INFO - 'LOGNAME': 'cltbld', 19:08:55 INFO - 'MAIL': '/var/mail/cltbld', 19:08:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:08:55 INFO - 'MOZ_NO_REMOTE': '1', 19:08:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:08:55 INFO - 'NO_EM_RESTART': '1', 19:08:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:08:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:08:55 INFO - 'PWD': '/builds/slave/test', 19:08:55 INFO - 'SHELL': '/bin/bash', 19:08:55 INFO - 'SHLVL': '1', 19:08:55 INFO - 'TERM': 'linux', 19:08:55 INFO - 'TMOUT': '86400', 19:08:55 INFO - 'USER': 'cltbld', 19:08:55 INFO - 'XDG_SESSION_COOKIE': '82e07478c745e68b1fe4678a000001bc-1462931301.948880-1036685957', 19:08:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:08:55 INFO - '_': '/tools/buildbot/bin/python'} 19:08:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:08:55 INFO - Downloading/unpacking jsonschema 19:08:55 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 19:08:55 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 19:08:55 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 19:08:55 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 19:08:55 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 19:08:55 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 19:08:57 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:08:57 INFO - Downloading/unpacking functools32 (from jsonschema) 19:08:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:08:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:08:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:08:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:08:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:08:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:08:57 INFO - Downloading functools32-3.2.3-2.tar.gz 19:08:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:08:57 INFO - warning: no files found matching '*.txt' 19:08:57 INFO - no previously-included directories found matching 'build' 19:08:57 INFO - no previously-included directories found matching 'dist' 19:08:57 INFO - no previously-included directories found matching '.git*' 19:08:57 INFO - Installing collected packages: jsonschema, functools32 19:08:57 INFO - Running setup.py install for functools32 19:08:57 INFO - warning: no files found matching '*.txt' 19:08:57 INFO - no previously-included directories found matching 'build' 19:08:57 INFO - no previously-included directories found matching 'dist' 19:08:57 INFO - no previously-included directories found matching '.git*' 19:08:57 INFO - Successfully installed jsonschema functools32 19:08:57 INFO - Cleaning up... 19:08:57 INFO - Return code: 0 19:08:57 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 19:08:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:08:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:08:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:08:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:08:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:08:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:08:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f98709c0f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x169e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1769770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17f89c0>, '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 0x17f98c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17f9d50>, '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': '82e07478c745e68b1fe4678a000001bc-1462931301.948880-1036685957', '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 19:08:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 19:08:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 19:08:57 INFO - Using env: {'DISPLAY': ':0', 19:08:57 INFO - 'HOME': '/home/cltbld', 19:08:57 INFO - 'LANG': 'en_US.UTF-8', 19:08:57 INFO - 'LANGUAGE': 'en_US:en', 19:08:57 INFO - 'LOGNAME': 'cltbld', 19:08:57 INFO - 'MAIL': '/var/mail/cltbld', 19:08:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:08:57 INFO - 'MOZ_NO_REMOTE': '1', 19:08:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:08:57 INFO - 'NO_EM_RESTART': '1', 19:08:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:08:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:08:57 INFO - 'PWD': '/builds/slave/test', 19:08:57 INFO - 'SHELL': '/bin/bash', 19:08:57 INFO - 'SHLVL': '1', 19:08:57 INFO - 'TERM': 'linux', 19:08:57 INFO - 'TMOUT': '86400', 19:08:57 INFO - 'USER': 'cltbld', 19:08:57 INFO - 'XDG_SESSION_COOKIE': '82e07478c745e68b1fe4678a000001bc-1462931301.948880-1036685957', 19:08:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:08:57 INFO - '_': '/tools/buildbot/bin/python'} 19:08:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:08:57 INFO - Downloading/unpacking flake8 19:08:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:08:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:08:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:08:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:08:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:08:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:08:59 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 19:08:59 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 19:08:59 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 19:08:59 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 19:08:59 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 19:08:59 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 19:08:59 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 19:08:59 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 19:08:59 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 19:08:59 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 19:08:59 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 19:08:59 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 19:08:59 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 19:08:59 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 19:08:59 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 19:08:59 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 19:09:00 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 19:09:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:09:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:09:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:09:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:09:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:09:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:09:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 19:09:00 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 19:09:00 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 19:09:00 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 19:09:00 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 19:09:00 INFO - no previously-included directories found matching 'docs/_build' 19:09:00 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 19:09:00 INFO - Running setup.py install for pep8 19:09:00 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 19:09:00 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 19:09:00 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 19:09:00 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 19:09:00 INFO - no previously-included directories found matching 'docs/_build' 19:09:00 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 19:09:00 INFO - Successfully installed flake8 mccabe pyflakes pep8 19:09:00 INFO - Cleaning up... 19:09:00 INFO - Return code: 0 19:09:00 INFO - Running post-action listener: _resource_record_post_action 19:09:00 INFO - Running post-action listener: _start_resource_monitoring 19:09:00 INFO - Starting resource monitoring. 19:09:00 INFO - ##### 19:09:00 INFO - ##### Finished create-virtualenv step (success) 19:09:00 INFO - ##### 19:09:00 INFO - ##### 19:09:00 INFO - ##### Running install step. 19:09:00 INFO - ##### 19:09:00 INFO - Running pre-action listener: _resource_record_pre_action 19:09:00 INFO - Running main action method: install 19:09:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:09:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:09:00 INFO - Reading from file tmpfile_stdout 19:09:00 INFO - Detecting whether we're running mozinstall >=1.0... 19:09:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:09:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:09:00 INFO - Reading from file tmpfile_stdout 19:09:00 INFO - Output received: 19:09:00 INFO - Usage: mozinstall [options] installer 19:09:00 INFO - Options: 19:09:00 INFO - -h, --help show this help message and exit 19:09:00 INFO - -d DEST, --destination=DEST 19:09:00 INFO - Directory to install application into. [default: 19:09:00 INFO - "/builds/slave/test"] 19:09:00 INFO - --app=APP Application being installed. [default: firefox] 19:09:00 INFO - mkdir: /builds/slave/test/build/application 19:09:00 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'] 19:09:00 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 19:09:16 INFO - Reading from file tmpfile_stdout 19:09:16 INFO - Output received: 19:09:16 INFO - /builds/slave/test/build/application/firefox/firefox 19:09:16 INFO - Running post-action listener: _resource_record_post_action 19:09:16 INFO - ##### 19:09:16 INFO - ##### Finished install step (success) 19:09:16 INFO - ##### 19:09:16 INFO - ##### 19:09:16 INFO - ##### Running run-tests step. 19:09:16 INFO - ##### 19:09:16 INFO - Running pre-action listener: _resource_record_pre_action 19:09:16 INFO - Running main action method: run_tests 19:09:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:09:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:09:16 INFO - Python 2.7.3 19:09:16 INFO - Return code: 0 19:09:16 INFO - grabbing minidump binary from tooltool 19:09: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')]} 19:09:16 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 0x17f89c0>, '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 0x17f98c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17f9d50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:09:16 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 19:09:16 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 19:09:16 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 19:09:16 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:09:16 INFO - Return code: 0 19:09:16 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 19:09:16 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:09:16 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:09:16 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 19:09:16 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:09:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 19:09:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 19:09:17 INFO - Return code: 0 19:09:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/D1-qap_SQMueqU6K-n7cSg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-055', '--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 19:09:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/D1-qap_SQMueqU6K-n7cSg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-055 --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 19:09:17 INFO - Using env: {'DISPLAY': ':0', 19:09:17 INFO - 'HOME': '/home/cltbld', 19:09:17 INFO - 'LANG': 'en_US.UTF-8', 19:09:17 INFO - 'LANGUAGE': 'en_US:en', 19:09:17 INFO - 'LOGNAME': 'cltbld', 19:09:17 INFO - 'MAIL': '/var/mail/cltbld', 19:09:17 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:09:17 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 19:09:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:09:17 INFO - 'MOZ_NO_REMOTE': '1', 19:09:17 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:09:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:09:17 INFO - 'NO_EM_RESTART': '1', 19:09:17 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:09:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:09:17 INFO - 'PWD': '/builds/slave/test', 19:09:17 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:09:17 INFO - 'SHELL': '/bin/bash', 19:09:17 INFO - 'SHLVL': '1', 19:09:17 INFO - 'TERM': 'linux', 19:09:17 INFO - 'TMOUT': '86400', 19:09:17 INFO - 'USER': 'cltbld', 19:09:17 INFO - 'XDG_SESSION_COOKIE': '82e07478c745e68b1fe4678a000001bc-1462931301.948880-1036685957', 19:09:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:09:17 INFO - '_': '/tools/buildbot/bin/python'} 19:09:17 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/D1-qap_SQMueqU6K-n7cSg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-055', '--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 19:09:17 INFO - mozversion application_buildid: 20160510174116 19:09:17 INFO - mozversion application_changeset: 68fddeb3a7dbc1adc7b8b8841523ec48961add04 19:09:17 INFO - mozversion application_display_name: Nightly 19:09:17 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:09:17 INFO - mozversion application_name: Firefox 19:09:17 INFO - mozversion application_remotingname: firefox 19:09:17 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 19:09:17 INFO - mozversion application_vendor: Mozilla 19:09:17 INFO - mozversion application_version: 49.0a1 19:09:17 INFO - mozversion platform_buildid: 20160510174116 19:09:17 INFO - mozversion platform_changeset: 68fddeb3a7dbc1adc7b8b8841523ec48961add04 19:09:17 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 19:09:17 INFO - mozversion platform_version: 49.0a1 19:09:17 INFO - using testdate: 1462932557 19:09:17 INFO - actual date: 1462932557 19:09:17 INFO - starting webserver on 'localhost:58996' 19:09:17 INFO - SUITE-START | Running 2 tests 19:09:17 INFO - TEST-START | dromaeo_css 19:09:17 INFO - operating with platform_type : linux_ 19:09:17 INFO - Initialising browser for dromaeo_css test... 19:09:17 INFO - TEST-INFO | started process 20109 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp4R3szu/profile http://localhost:58996/getInfo.html) 19:09:21 INFO - PROCESS | 20109 | __metrics Screen width/height:1600/1200 19:09:21 INFO - PROCESS | 20109 | colorDepth:24 19:09:21 INFO - PROCESS | 20109 | Browser inner width/height: 1024/697 19:09:21 INFO - PROCESS | 20109 | __metrics 19:09:22 ERROR - PROCESS | 20109 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 19:09:23 INFO - TEST-INFO | 20109: exit 0 19:09:23 INFO - Browser initialized. 19:09:23 INFO - Running cycle 1/1 for dromaeo_css test... 19:09:23 INFO - TEST-INFO | started process 20188 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp4R3szu/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:09:35 INFO - PROCESS | 20188 | RSS: Main: 153309184 19:09:35 INFO - PROCESS | 20188 | 19:10:26 INFO - PROCESS | 20188 | 1462932626488 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] 19:12:37 INFO - PROCESS | 20188 | Cycle 1(1): loaded http://localhost:58996/tests/dromaeo/cssquery-dojo.html (next: http://localhost:58996/tests/dromaeo/cssquery-ext.html) 19:12:38 INFO - PROCESS | 20188 | RSS: Main: 240406528 19:12:38 INFO - PROCESS | 20188 | 19:15:41 INFO - PROCESS | 20188 | Cycle 1(1): loaded http://localhost:58996/tests/dromaeo/cssquery-ext.html (next: http://localhost:58996/tests/dromaeo/cssquery-jquery.html) 19:15:41 INFO - PROCESS | 20188 | RSS: Main: 235532288 19:15:41 INFO - PROCESS | 20188 | 19:18:43 INFO - PROCESS | 20188 | Cycle 1(1): loaded http://localhost:58996/tests/dromaeo/cssquery-jquery.html (next: http://localhost:58996/tests/dromaeo/cssquery-mootools.html) 19:18:44 INFO - PROCESS | 20188 | RSS: Main: 236363776 19:18:44 INFO - PROCESS | 20188 | 19:21:46 INFO - PROCESS | 20188 | Cycle 1(1): loaded http://localhost:58996/tests/dromaeo/cssquery-mootools.html (next: http://localhost:58996/tests/dromaeo/cssquery-prototype.html) 19:21:46 INFO - PROCESS | 20188 | RSS: Main: 230260736 19:21:46 INFO - PROCESS | 20188 | 19:24:49 INFO - PROCESS | 20188 | Cycle 1(1): loaded http://localhost:58996/tests/dromaeo/cssquery-prototype.html (next: http://localhost:58996/tests/dromaeo/cssquery-yui.html) 19:24:50 INFO - PROCESS | 20188 | RSS: Main: 209555456 19:24:50 INFO - PROCESS | 20188 | 19:27:52 INFO - PROCESS | 20188 | Cycle 1(1): loaded http://localhost:58996/tests/dromaeo/cssquery-yui.html (next: http://localhost:58996/tests/dromaeo/cssquery-dojo.html) 19:27:53 INFO - PROCESS | 20188 | RSS: Main: 290668544 19:27:53 INFO - PROCESS | 20188 | 19:27:53 INFO - PROCESS | 20188 | __start_tp_report 19:27:53 INFO - PROCESS | 20188 | _x_x_mozilla_page_load 19:27:53 INFO - PROCESS | 20188 | _x_x_mozilla_page_load_details 19:27:53 INFO - PROCESS | 20188 | |i|pagename|runs| 19:27:53 INFO - PROCESS | 20188 | |0;dojo.html;2593.701724301706;2598.7401259874014;2634.538955682755;2640.2871224769306;2660.2950218192173;4621.930671039937;4632.698874573159;4634.884127896804;4682.531746825316;4729.12511185431;4633.536646335365;4675.789589468473;4703.529647035295;4720.197566413709;4749.121412538674;4247.469066366704;4358.5423530529315;4372.23485889968;4405.625521830632;4433.689641725065;4312.202242585116;4360.258756011485;4371.060222052258;4375.168717943597;4419.11617676466;4240.17316623257;4240.703150779446;4289.978550107261;4300.763458009808;4360.498542667602;4321.978390108061;4351.238533256667;4404.911901761979;4410.05183885463;4426.889327766799;4372.2130016597;4413.801378937956;4431.7784110794655;4432.20220360335;4439.289713645801;4421;4618.5843274105155;4693.812247510095;4715.976420117878;4742.2886567014875;5127.0258650856595;5156.3296771419855;5160.277561141444;5173.741312934376;5174.560162386202;4926.679765815211;5149.897002059975;5167.457416971201;5190.013897359489;5194.896102077975;4671.48613652498;4693.084848454587;4697.694649847749;4698.835540756091;4739.099571081483;5120.462351453044;5142.151544995034;5156.1492353761205;5172.094883395391;5195.48045195485;4774.140654682121;4926.8768280793265;4972.303877457159;4978.128827455181;4998.550130488201;5138.820141295037;5143.305653736773;5144.897102058013;5192.247124166975;5237.580993520509;5088.338515992971;5124.205748109049;5144.71704056273;5163.741812909339;5178.663386879841;4907.3620429343955;4908.558229759339;4941.728204948762;5126.38483381989;5155.252488389237;4254.787260636956;4280.422143010716;4304;4317.740935543878;4328.9134217316105;3734.2718169956597;3734.3278209922473;3762.680172185395;3767.981160094182;3793.279276937373;4185.393117997874;4206.978965105155;4225.049363893161;4241.172971270635;4268.658507319407;4979.726115063706;5017.347744793154;5031.371078615173;5042.722650254198;5065.062963351792;4229.175310814423;4231.957680423218;4238.978805105955;4268.935965960513;4308.504521980012;4486.910261794746;4501;4587.8759703872765;4625.236835922035;4661.207594708908;4822.397200349957;4826.58973987215;4837.984023355135;4840.782164802592;4886.560209581155;5144.228365745168;5165.560927321219;5195.766190521511;5214.530692237717;5273.868153296198;4639.280911458729;4761.40482439695;4763.16644587189;4800.951990480051;4820.493848145894;5035.949640503548;5051.848444546523;5059.46875578059;5073;5115.3094332266155;4964.429090654621;5092.592592592658;5137.075326441276;5155.407128180157;5161.483851614808;4893.241547560134;4939.209726443752;4981.953789704203;5013.448520662797;5014.122528557561;4955.6531042826655;4961.404631444106;4967.081089998362;4992.625553083606;5021.246812978083;4756.5956893664425;4768.57082862544;4897.3878265216845;4908.337374454546;4954.950450495449;4899.387576552931;4926.162552366035;4944.604431645532;4946.010797840374;5020.146575082172;4814.470408255159;4819.445763737215;4830.589399901048;4851.636127290538;4887.413510378778 19:27:53 INFO - PROCESS | 20188 | |1;ext.html;520.1651349583917;528.8862894477687;550.5320477594046;553.2807350444423;567.4268988321794;28428.43143137136;31958;31996.840015799946;41081.58918410815;42398.78800605993;42188.78905605468;42498.36252456208;42630.57369426305;42695;42738;7542.5097368670995;7607.01108855848;7663.3486153677;7668.233176682343;7673.693052277902;7674.693012279502;7784.688612455495;7790.688372465094;7835.804104897395;7839.960800195991;7790.9610451947665;7802.375809935192;7813.398253358048;7847.489913155627;7877.684892604318;7709.653065612033;7757.340626046765;7773.144954055049;7791.337736292422;7804.531728096333;7667.846643067108;7744.457887947846;7773.766786996399;7789.883151752728;7810.843783124305;38792;39059.80470097631;39114.21771564553;39183.60816391828;39194.41208381877;3335.116194208537;3361.0757041813695;3374.038399056282;3424.2637832866053;3781.3949768037255;27955.301117472023;29147.562786558217;29423;29658.55512167319;30902.381952360825;3148.212946763309;3153.952690709641;3161.762867784925;3201.183698156978;3275.574175357212;235716;235812;236786.99999999828;236924;237295;32977.83511082429;34162.65837341595;34219.486707699405;52873;53006;35414.114647134025;35443.64556354455;35543.11142221465;35543.64456355404;35604.109897252776;12339.197952133261;12349.259044457362;12368.134230603771;12372;12378.504859805687;10717.035466808025;10743.409112498888;10744.677659670222;10786.838197427045;10804.29772064829;8286.129956354496;8295.377846661524;8326.833463330699;8342.082370939192;8376.832463350698;3265.8203798791296;3281.671832816747;3282.8030318180986;3305.2397948471985;3310.2882880180878;895.4850960697624;902.5036230073433;925.9629614815332;931.1107891963159;991.375433476905;27149.321266968484;28562.85718571394;28677.713222867922;29020.27449313784;29146;965.3532133470451;970.3062310447923;984.837501812193;985;992.3004281981185;13987.37056832445;14473.348699308557;14505.782413263809;14692.894820971987;14730;347489;350416;351716;351806;355035;47990.52009479861;48620.756896215295;48628.5137148624;48784.75607621939;48801;1149.9195056345975;1239.659093749208;1264.2856785915928;1296.935153242334;1300.6358219698689;27792.583111253753;28629.856850715612;28952;29183.416331672823;29367.85316073406;40665;41334.79332603318;41563.584364157185;41653.79173104115;41715.58284417117;284780;288410;288836;289017;291318;22413.551728965656;24335.391615209763;24957.500849983262;25037;25320.620190697522;38145.80927095347;38225.23549528938;38548.80725596466;38747.61252387553;38813;33438.331233375684;33527.664723352456;33701.49447758334;34087;34411.65588344185;33561.16097097592;33799;34384.82807585946;34452.31095378028;35074;33465;33788.49317260194;33795.15512112217;34108.658913410545;34281.48577771385;30025.399492010474;30582.694173058873;30582.847085764428;30607;30763.230919226302;23381.649275261225;23655;23950.76049239555;23973.640395394432;24181.516369672157 19:27:53 INFO - PROCESS | 20188 | |2;jquery.html;379.9183175617243;389.4703203643046;395.18096035871775;402.36828179757777;405.86403554809135;12741.299228542426;12788.296643684595;15946.601334966632;17232.483025509257;17313.56716082097;16095.195240238;16646.08446535443;17271.82728172718;17290.481285561422;17765.64468710625;5012.924806127902;5242.501962313574;5252.6323157379;5266.052110620106;5337.946620533803;5019.899602007976;5053.115704751672;5104.412992505853;5215.869603259911;5226.08543504887;5118.232265160219;5122.615803814729;5139.486051394868;5145.382554093495;5156.742162891859;5100.566451851597;5111.309973153632;5188.584913206953;5551.528120109796;5597.244372009767;5381.0583147948955;5381.7578208980685;5392.91910621341;5405.675659460445;5437.157240627669;11256.324620522713;11522.251053681484;11557.88442115585;11583.6524904253;11867.465964031639;1947.1432569669441;2067.9069441875154;2156.5902478529188;2207.6357401028813;2287.9885600572056;8924.241439477652;8955.820883582357;9253.537323133884;9501.524923753785;11806.114541409428;1661.4932445604086;1725.4651058172008;1739.4955462915739;1805.0703887497948;1857.2385322018042;67869.32130678729;70303.64848175777;70585;71503.64248178779;72442.27557724409;10139.290249682452;10260.281780275309;10286.177105831515;10345.37927724339;10360.740981475523;14162.787558186636;14291.142531448146;14292.642683932985;14333.283335833166;14370.425182992773;6902.827429314308;7079.893801592877;7136.964315178495;7146.892796608055;7283.781486555412;6422.903656445157;6432.517561182836;6529.11856899322;6551.80344589663;6558.311377305409;4329.870103896888;4346.869593912188;4421.977890110529;4434.135343607965;4453.487848897354;1823.5258832703328;1849.010779233104;1910.3982245592595;1928.1805232776014;1954.208545539059;806.3226889412921;923.2844545477175;963.1940112289458;972.8297547929226;991.177322822056;15984.040957542584;16760.58098547546;16771.748423773653;17178.570535736457;17300.307987680604;861.8922634670666;881.8721285413708;896.3322324868016;917.8256131335025;927.5825878354767;9093.31800114994;9288.860667089999;9309.953450232706;9498.525073746423;9686;396307;402351;409223;411707;412234;46356;47976.28035579469;78609;79386.44294899417;87359;887.3921363865772;891.233539156325;896.2650626486349;923.5982347678781;926.333040211047;11589.6523104307;14840.480583179537;14978.47575334858;15138.167400793061;15306.464273750365;24670.506589868462;33463.33073338569;34692.47961280532;34788.65211347854;35984.64015359813;44558.10883782371;45159.54840451553;45232.32151517664;45642.087158255985;45864;7395.926040739524;7424;7445.590492522747;7543.396528277615;7587.62061896914;10385.286427739338;11303.7739245213;11720.238184517979;11990.040796736415;12076.335801531;9497.620095196115;9910.554025068745;10328.140934631538;10459.843102353623;10715.035646791826;10521.263511554424;10697.51861166265;10747.247692661438;10761.784764304826;10941.56233750641;10033.19734421259;10105.545250463894;10382.688519344432;11057.944710276397;11092.556297748;7844;7883.329916957123;8017.559034253172;8017.719379821679;8125.918740812516;10382.42896640692;10426.27016108865;10951.890481095086;11012.17408694335;11057.557697692002 19:27:53 INFO - PROCESS | 20188 | |3;mootools.html;1256.9434375453104;1464.524029690351;1523.7638166084257;1692.866987595629;1865.2632210276931;7284.635768211588;7613.961930190355;7664.348530374918;7796.220377962215;7798.103218129902;7621.657025433855;7740.187280335568;7750.92249077509;7768.067831860185;7781.6498257578405;2217.9001944912516;2244.2481768607454;2251.234580242717;2257.6274914639066;2261.163369553268;2231.297141400462;2252.6395776675736;2300.1259521381853;2301.9641161477402;2302.309307207831;2124.59632669793;2134.583756167548;2160.4166874943758;2175.771543987882;2176.967345489811;2137.593857167133;2143.3677065265783;2152.7416709994823;2155.89220538974;2159.5356998245375;2046.8464865135174;2048.528838367169;2049.333966460906;2053.0761157479196;2065.2048961150067;7436.628168591549;7669.271419215165;7713.305802477804;7763.534187948741;7766;4811.831585894477;4954.752262386903;4964.975175124137;4966.801327946914;4980.228064649985;2776.403073339242;2786.0109661070233;2817.619621351112;2920.9707902921123;2967.4955257606043;846.8899043124416;869.6738722978882;878.9033206347297;901.3014913442134;917.8531434970439;10821.83767243492;10830.67507974762;10867.565297388173;10875.619353322674;10906.890931090746;4164.292070348048;4167.1457351242925;4266.935995960063;4292.763897985578;4335.176316499855;8503.404761666749;8531.914680853237;8539.573021348908;8564.143585641386;8592.097829727789;4697.741624210701;4727.030958653468;4762.261849413347;4776.617870570346;4785.760711964388;3746.6440688134585;4160.750354978711;4244.511881133647;4320.049589090395;4450.599446049871;3612.458131280329;3722.739408241397;3752.493413389212;3791.33651610967;3812.9237415252096;1209.5585111434264;1224.20426722631;1229.8893099621077;1239.9504019839105;1248.650377894191;797.7885860247039;821.514530662577;830.3647709502236;853.846307664614;915.9542022898828;5078.796848126108;5344.957733242059;5405.486478784509;5528.253685752452;5550.9453203891135;862.4782006885869;887.5828360670604;892.7054072156172;905.44767691708;919.1268295119663;3756.4365345197893;3825.464434979105;3839.8080095995088;3846.673032792188;3850.8652197173524;9561.139497445263;9843.852342214872;9946.651867184613;9979;9999.750006249757;9514.47670378122;9544.856827147598;9796.412215267106;9898.257630677728;9999.100080992746;7495.962520187364;7563.941048253249;7634;7644.885326720104;7722.536647801037;5089.160288552347;5094.6688465249645;5259.710715910662;5279.313689220377;5324.8935021298585;8768.473891566526;8795.516246606243;8928.464292142493;9196.494192819458;9368.81262374735;9101.954490227507;9182.540872956191;9208.953955230181;9266.768330791514;9284.071592840663;5480.671159730429;6182.227221597301;6267.780627678009;6278.905816412666;6490;7740.612969351622;7784.2215778421705;7835.4123440743315;7849.921500784919;7872.330851877655;6895.34494223048;6971.372576468142;7055.964720176366;7159.391451726661;7184.784456466315;6733.831654208684;6771.28901465339;6925.6190909500465;6962.303769622998;7034.613096279753;6555.410013098844;6604.834879127868;6649.63427011519;6663.400293973566;6691.966540167268;4809.398825146856;4862.489438609036;4873.317735516959;4896.755162241802;4946.925796112989;4764.547368000034;4776.140294746979;4797.40032495938;4864.975675121602;4909.779059942382 19:27:53 INFO - PROCESS | 20188 | |4;prototype.html;311.76306007434346;312.3378437712051;324.6672161034939;326.2561360098974;326.5346880695009;4575.908481830362;4611.354410382545;4625.774169844994;4651.558101980315;4669.206234940059;4366.148601022802;4412.316091005891;4518.231900576901;4521.462702681088;4570.588647021767;3579.284143171363;3674.210044840359;3674.669279764814;3684.09739613795;3698.8890333290046;3688.834002469882;3690.575583807856;3697.0036180462653;3698.3712768829364;3699.2440631729264;3408.7784294020935;3495.4756786481976;3638.9636103639023;3649.781013139207;3670.100825297806;3609.981950090259;3620.619834917322;3621.058524783549;3664.578573464059;3666.816659167058;3279.6884295991845;3291.736661067109;3291.8683252669866;3292.341531693647;3296.241864371184;4323.816941773263;4363.432753742025;4442.4224850769515;4462.464504259509;4465.307877279027;4132.607402296777;4170.332746760504;4172.645325147366;4187.811548480326;4197.538270790211;4784.6889952153;4788.377510923592;4794.592459640935;4804.927926081112;4818.253170758538;3523.1016090896655;3528.9294214115575;3561.6082230954576;3567.6789088981855;3574.6782789549065;4764.880877978044;4987.900241995176;4988.700677959334;5000.849974500771;5006.89986200274;3610.548681414823;3611.638836116367;3618.2451511017134;3633.5284209895035;3657.835397407123;3655.5796083450214;3683.981580092136;3730;3734.981325093357;3748.8125593720206;4053.3919912012843;4169.478815148106;4181.456410666655;4187.832486700498;4210.242156411875;4753.881152971203;4766.713997160182;4789.808407663654;4873.269009648581;4879.4876537963;1221.8900298972958;1264.778663733843;1278.1500302298866;1300.6213413781422;1308.3262120008203;834.4951304460714;840.7771940435789;848.9108643592457;852.4459101583896;852.5822347050025;3717.646823551758;3732.1789206374556;3734.7198960078103;3774.830132644038;3813.9046523836573;4309.547497512779;4319.956800432018;4335.3770968564195;4358.932061644854;4418.447694038246;3732.794696291676;3739.0839244384756;3746.981265093657;3764.6235376462137;3772.1512659651908;2190.1896298369707;2287.782660647236;2294.678744975705;2308.5267520158327;2323.4307594639085;4087.693422993287;4106.630403263721;4116;4119.258533464005;4135.958640413617;4427.424434823517;4427.46870375557;4443.844465443694;4447.554544772897;4469.910601787946;3833.5913699244975;4054.6958978076764;4131.938020929689;4138.730982486129;4160.999999999939;4286.335617979218;4288.198956438366;4294.549072347483;4320.848770293024;4329.718568293177;4182.163567286494;4221.28238199513;4258.212230737324;4260.829566817292;4278.315469524861;4105.384192371168;4117.3618089196225;4135.565765594569;4136.896577585464;4145.54399016114;3192.4572822620676;3203.6956489133495;3260.5924259467565;3265.9673403266615;3327.933441331208;4276.444062271885;4341.761203133858;4350.390945267602;4361.411209486806;4739.4075740532435;4639.976800115978;4753.215719406259;4833.2025215840395;4846.36997190363;4855.320255164209;4838.782254798472;4843.370361852936;4850.296706977539;4851.29656199842;4859.303086786867;4548.727076375428;4746.857594272177;4767.713937163781;4785.688930219525;4798.7360695160705;4815.747905544514;4816.20532612115;4823.384166304328;4855.295982082649;4863.2461968394955;4746.7863946121815;4749.957518267178;4770.809167633256;4777.880552986203;4791.976040119777 19:27:53 INFO - PROCESS | 20188 | |5;yui.html;732.2494443195724;735.981600459988;736.5470235804978;806.040811434393;806.8991376077988;11935.224210426317;12534.811977820316;12621.116521843474;12650.683732906671;12652.746945061104;13693.726125477484;13767.173969561833;13800.654983625414;13833.446662133527;13869.514566990158;679.3274658088507;850.4599579267148;866.0733015673217;874.5190145420025;875.6322344615277;854.5129276312473;857.6998050682242;860.1183786618734;865.6537385045968;866.4584634603373;888.3381880499037;898.9865152022725;900.4282280751704;900.4687234531625;901.486152892848;711.7295427737453;873.9126087391273;895.7939673875013;898.9146031127033;906.302147346546;676.719161547957;677.3971165662564;677.4918810891832;679.9932000679979;680.3162821364547;11241.269317494336;11251.43742812856;11260;11262.831057534144;11301.547938082467;11370.09039276856;11571.95233730521;11789.528418863321;11796.29222246668;12276.140670153092;11248.55005799767;11299.661010169708;11364.212472600893;11435.14236432271;11466.827997580043;4146.792660366969;4181.811818468176;4227.619514243733;4291.7854107294825;4358.738475691437;45726.31410528878;45811.77094114541;45829.54170458286;45904.770476147736;46156.769216153705;9667.758306042264;9731.075547822946;9827.656032038987;10011.549480273405;10202.33684810485;15882.364705411654;16071.035737855766;16071.839281607268;16088.195590220676;16131.274092665624;6496.707648155844;6507.349265073455;6543.411093001558;6600.273969863311;6781.355771201728;3628.7097032237357;3645.1616128290643;3653.908652283661;3672.889813305605;3673.3755261605593;2656.9335766605604;2692.192342297303;2714.9864250679016;2739.1919383781833;2769.709180536055;511.93600799900014;517.1854329431161;519.555779808266;522.6837763153313;524.8425472358277;452.42993827777235;502.3243737173496;550.713628912963;579.8084935457591;583.1631608641613;7191.208967013625;7432.108147022392;7446;7533.096028476618;7549.320561149523;551.9475649813261;557.542814891793;561.6237121128772;565.1579147070917;570.891530609183;3451.0682115829086;3500.2474467989505;3513.4554144107706;3544.4506101554302;3557.5553055868018;48977.17960815433;52149.217761733606;52327.73836130795;52394.738026310384;52680;46588.3011754824;46997.060058798634;47311.268873112414;48285;49103.26345104826;40781.38827917584;41047.384289235684;41369.586304136574;41541.79229103895;42028.57971420308;7107.466939979418;7188.784336469915;7389.963050184715;7401.48189626721;7428.925710742823;27404.451910962067;27621.585676215276;29176.7082329174;29677.258068548457;31490.084419800824;35039.47440788339;35755;42563.7871810639;42787.57212427836;43001.78499107484;11416;11552.133589980953;11602.76794464123;11905.761884762429;11967.64097077089;11268.323900565993;11630.767384652429;12117.515299388278;12869.22784632925;12931.676708081997;11449.771004580029;11750.882491174978;11814.232074915102;12236.449359778653;12323.322217277777;11449.141314401286;11477.713057173303;11866.821997670215;11946.104042197045;12168.817467737814;11270.098392128773;11551.249168804;11551.76896462083;11965.401729913296;11969.281843089442;9090.05279726336;9306.860397094184;9325.720228393158;9421.952890235505;9485.288603354638;9216.354855160074;9830.508474576385;9920.851187232342;9977;10044.748881278027 19:27:53 INFO - PROCESS | 20188 | __end_tp_report 19:27:53 INFO - PROCESS | 20188 | __start_cc_report 19:27:53 INFO - PROCESS | 20188 | _x_x_mozilla_cycle_collect,795 19:27:53 INFO - PROCESS | 20188 | __end_cc_report 19:27:53 INFO - PROCESS | 20188 | __startTimestamp1462933673241__endTimestamp 19:27:53 INFO - PROCESS | 20188 | 19:27:53 INFO - PROCESS | 20188 | ------- Summary: start ------- 19:27:53 INFO - PROCESS | 20188 | Number of tests: 6 19:27:53 INFO - PROCESS | 20188 | 19:27:53 INFO - PROCESS | 20188 | [#0] dojo.html Cycles:165 Average:4682.64 Median:4814.47 stddev:510.53 (10.6%) stddev-sans-first:485.09 19:27:53 INFO - PROCESS | 20188 | Values: 2593.7 2598.7 2634.5 2640.3 2660.3 4621.9 4632.7 4634.9 4682.5 4729.1 4633.5 4675.8 4703.5 4720.2 4749.1 4247.5 4358.5 4372.2 4405.6 4433.7 4312.2 4360.3 4371.1 4375.2 4419.1 4240.2 4240.7 4290.0 4300.8 4360.5 4322.0 4351.2 4404.9 4410.1 4426.9 4372.2 4413.8 4431.8 4432.2 4439.3 4421.0 4618.6 4693.8 4716.0 4742.3 5127.0 5156.3 5160.3 5173.7 5174.6 4926.7 5149.9 5167.5 5190.0 5194.9 4671.5 4693.1 4697.7 4698.8 4739.1 5120.5 5142.2 5156.1 5172.1 5195.5 4774.1 4926.9 4972.3 4978.1 4998.6 5138.8 5143.3 5144.9 5192.2 5237.6 5088.3 5124.2 5144.7 5163.7 5178.7 4907.4 4908.6 4941.7 5126.4 5155.3 4254.8 4280.4 4304.0 4317.7 4328.9 3734.3 3734.3 3762.7 3768.0 3793.3 4185.4 4207.0 4225.0 4241.2 4268.7 4979.7 5017.3 5031.4 5042.7 5065.1 4229.2 4232.0 4239.0 4268.9 4308.5 4486.9 4501.0 4587.9 4625.2 4661.2 4822.4 4826.6 4838.0 4840.8 4886.6 5144.2 5165.6 5195.8 5214.5 5273.9 4639.3 4761.4 4763.2 4801.0 4820.5 5035.9 5051.8 5059.5 5073.0 5115.3 4964.4 5092.6 5137.1 5155.4 5161.5 4893.2 4939.2 4982.0 5013.4 5014.1 4955.7 4961.4 4967.1 4992.6 5021.2 4756.6 4768.6 4897.4 4908.3 4955.0 4899.4 4926.2 4944.6 4946.0 5020.1 4814.5 4819.4 4830.6 4851.6 4887.4 19:27:53 INFO - PROCESS | 20188 | 19:27:53 INFO - PROCESS | 20188 | [#1] ext.html Cycles:180 Average:43451.05 Median:34333.16 stddev:77816.36 (226.7%) stddev-sans-first:77967.89 19:27:53 INFO - PROCESS | 20188 | Values: 520.2 528.9 550.5 553.3 567.4 28428.4 31958.0 31996.8 41081.6 42398.8 42188.8 42498.4 42630.6 42695.0 42738.0 7542.5 7607.0 7663.3 7668.2 7673.7 7674.7 7784.7 7790.7 7835.8 7840.0 7791.0 7802.4 7813.4 7847.5 7877.7 7709.7 7757.3 7773.1 7791.3 7804.5 7667.8 7744.5 7773.8 7789.9 7810.8 38792.0 39059.8 39114.2 39183.6 39194.4 3335.1 3361.1 3374.0 3424.3 3781.4 27955.3 29147.6 29423.0 29658.6 30902.4 3148.2 3154.0 3161.8 3201.2 3275.6 235716.0 235812.0 236787.0 236924.0 237295.0 32977.8 34162.7 34219.5 52873.0 53006.0 35414.1 35443.6 35543.1 35543.6 35604.1 12339.2 12349.3 12368.1 12372.0 12378.5 10717.0 10743.4 10744.7 10786.8 10804.3 8286.1 8295.4 8326.8 8342.1 8376.8 3265.8 3281.7 3282.8 3305.2 3310.3 895.5 902.5 926.0 931.1 991.4 27149.3 28562.9 28677.7 29020.3 29146.0 965.4 970.3 984.8 985.0 992.3 13987.4 14473.3 14505.8 14692.9 14730.0 347489.0 350416.0 351716.0 351806.0 355035.0 47990.5 48620.8 48628.5 48784.8 48801.0 1149.9 1239.7 1264.3 1296.9 1300.6 27792.6 28629.9 28952.0 29183.4 29367.9 40665.0 41334.8 41563.6 41653.8 41715.6 284780.0 288410.0 288836.0 289017.0 291318.0 22413.6 24335.4 24957.5 25037.0 25320.6 38145.8 38225.2 38548.8 38747.6 38813.0 33438.3 33527.7 33701.5 34087.0 34411.7 33561.2 33799.0 34384.8 34452.3 35074.0 33465.0 33788.5 33795.2 34108.7 34281.5 30025.4 30582.7 30582.8 30607.0 30763.2 23381.6 23655.0 23950.8 23973.6 24181.5 19:27:53 INFO - PROCESS | 20188 | 19:27:53 INFO - PROCESS | 20188 | [#2] jquery.html Cycles:180 Average:23991.97 Median:4384.42 stddev:66869.74 (1525.2%) stddev-sans-first:67033.82 19:27:53 INFO - PROCESS | 20188 | Values: 379.9 389.5 395.2 402.4 405.9 12741.3 12788.3 15946.6 17232.5 17313.6 16095.2 16646.1 17271.8 17290.5 17765.6 5012.9 5242.5 5252.6 5266.1 5337.9 5019.9 5053.1 5104.4 5215.9 5226.1 5118.2 5122.6 5139.5 5145.4 5156.7 5100.6 5111.3 5188.6 5551.5 5597.2 5381.1 5381.8 5392.9 5405.7 5437.2 11256.3 11522.3 11557.9 11583.7 11867.5 1947.1 2067.9 2156.6 2207.6 2288.0 8924.2 8955.8 9253.5 9501.5 11806.1 1661.5 1725.5 1739.5 1805.1 1857.2 67869.3 70303.6 70585.0 71503.6 72442.3 10139.3 10260.3 10286.2 10345.4 10360.7 14162.8 14291.1 14292.6 14333.3 14370.4 6902.8 7079.9 7137.0 7146.9 7283.8 6422.9 6432.5 6529.1 6551.8 6558.3 4329.9 4346.9 4422.0 4434.1 4453.5 1823.5 1849.0 1910.4 1928.2 1954.2 806.3 923.3 963.2 972.8 991.2 15984.0 16760.6 16771.7 17178.6 17300.3 861.9 881.9 896.3 917.8 927.6 9093.3 9288.9 9310.0 9498.5 9686.0 396307.0 402351.0 409223.0 411707.0 412234.0 46356.0 47976.3 78609.0 79386.4 87359.0 887.4 891.2 896.3 923.6 926.3 11589.7 14840.5 14978.5 15138.2 15306.5 24670.5 33463.3 34692.5 34788.7 35984.6 44558.1 45159.5 45232.3 45642.1 45864.0 7395.9 7424.0 7445.6 7543.4 7587.6 10385.3 11303.8 11720.2 11990.0 12076.3 9497.6 9910.6 10328.1 10459.8 10715.0 10521.3 10697.5 10747.2 10761.8 10941.6 10033.2 10105.5 10382.7 11057.9 11092.6 7844.0 7883.3 8017.6 8017.7 8125.9 10382.4 10426.3 10951.9 11012.2 11057.6 19:27:53 INFO - PROCESS | 20188 | 19:27:53 INFO - PROCESS | 20188 | [#3] mootools.html Cycles:180 Average:5209.26 Median:5334.93 stddev:2895.09 (54.3%) stddev-sans-first:2887.97 19:27:53 INFO - PROCESS | 20188 | Values: 1256.9 1464.5 1523.8 1692.9 1865.3 7284.6 7614.0 7664.3 7796.2 7798.1 7621.7 7740.2 7750.9 7768.1 7781.6 2217.9 2244.2 2251.2 2257.6 2261.2 2231.3 2252.6 2300.1 2302.0 2302.3 2124.6 2134.6 2160.4 2175.8 2177.0 2137.6 2143.4 2152.7 2155.9 2159.5 2046.8 2048.5 2049.3 2053.1 2065.2 7436.6 7669.3 7713.3 7763.5 7766.0 4811.8 4954.8 4965.0 4966.8 4980.2 2776.4 2786.0 2817.6 2921.0 2967.5 846.9 869.7 878.9 901.3 917.9 10821.8 10830.7 10867.6 10875.6 10906.9 4164.3 4167.1 4266.9 4292.8 4335.2 8503.4 8531.9 8539.6 8564.1 8592.1 4697.7 4727.0 4762.3 4776.6 4785.8 3746.6 4160.8 4244.5 4320.0 4450.6 3612.5 3722.7 3752.5 3791.3 3812.9 1209.6 1224.2 1229.9 1240.0 1248.7 797.8 821.5 830.4 853.8 916.0 5078.8 5345.0 5405.5 5528.3 5550.9 862.5 887.6 892.7 905.4 919.1 3756.4 3825.5 3839.8 3846.7 3850.9 9561.1 9843.9 9946.7 9979.0 9999.8 9514.5 9544.9 9796.4 9898.3 9999.1 7496.0 7563.9 7634.0 7644.9 7722.5 5089.2 5094.7 5259.7 5279.3 5324.9 8768.5 8795.5 8928.5 9196.5 9368.8 9102.0 9182.5 9209.0 9266.8 9284.1 5480.7 6182.2 6267.8 6278.9 6490.0 7740.6 7784.2 7835.4 7849.9 7872.3 6895.3 6971.4 7056.0 7159.4 7184.8 6733.8 6771.3 6925.6 6962.3 7034.6 6555.4 6604.8 6649.6 6663.4 6692.0 4809.4 4862.5 4873.3 4896.8 4946.9 4764.5 4776.1 4797.4 4865.0 4909.8 19:27:53 INFO - PROCESS | 20188 | 19:27:53 INFO - PROCESS | 20188 | [#4] prototype.html Cycles:180 Average:3851.47 Median:4181.81 stddev:1085.63 (26.0%) stddev-sans-first:1055.67 19:27:53 INFO - PROCESS | 20188 | Values: 311.8 312.3 324.7 326.3 326.5 4575.9 4611.4 4625.8 4651.6 4669.2 4366.1 4412.3 4518.2 4521.5 4570.6 3579.3 3674.2 3674.7 3684.1 3698.9 3688.8 3690.6 3697.0 3698.4 3699.2 3408.8 3495.5 3639.0 3649.8 3670.1 3610.0 3620.6 3621.1 3664.6 3666.8 3279.7 3291.7 3291.9 3292.3 3296.2 4323.8 4363.4 4442.4 4462.5 4465.3 4132.6 4170.3 4172.6 4187.8 4197.5 4784.7 4788.4 4794.6 4804.9 4818.3 3523.1 3528.9 3561.6 3567.7 3574.7 4764.9 4987.9 4988.7 5000.8 5006.9 3610.5 3611.6 3618.2 3633.5 3657.8 3655.6 3684.0 3730.0 3735.0 3748.8 4053.4 4169.5 4181.5 4187.8 4210.2 4753.9 4766.7 4789.8 4873.3 4879.5 1221.9 1264.8 1278.2 1300.6 1308.3 834.5 840.8 848.9 852.4 852.6 3717.6 3732.2 3734.7 3774.8 3813.9 4309.5 4320.0 4335.4 4358.9 4418.4 3732.8 3739.1 3747.0 3764.6 3772.2 2190.2 2287.8 2294.7 2308.5 2323.4 4087.7 4106.6 4116.0 4119.3 4136.0 4427.4 4427.5 4443.8 4447.6 4469.9 3833.6 4054.7 4131.9 4138.7 4161.0 4286.3 4288.2 4294.5 4320.8 4329.7 4182.2 4221.3 4258.2 4260.8 4278.3 4105.4 4117.4 4135.6 4136.9 4145.5 3192.5 3203.7 3260.6 3266.0 3327.9 4276.4 4341.8 4350.4 4361.4 4739.4 4640.0 4753.2 4833.2 4846.4 4855.3 4838.8 4843.4 4850.3 4851.3 4859.3 4548.7 4746.9 4767.7 4785.7 4798.7 4815.7 4816.2 4823.4 4855.3 4863.2 4746.8 4750.0 4770.8 4777.9 4792.0 19:27:53 INFO - PROCESS | 20188 | 19:27:53 INFO - PROCESS | 20188 | [#5] yui.html Cycles:180 Average:12843.93 Median:23646.79 stddev:14435.81 (61.0%) stddev-sans-first:14447.65 19:27:53 INFO - PROCESS | 20188 | Values: 732.2 736.0 736.5 806.0 806.9 11935.2 12534.8 12621.1 12650.7 12652.7 13693.7 13767.2 13800.7 13833.4 13869.5 679.3 850.5 866.1 874.5 875.6 854.5 857.7 860.1 865.7 866.5 888.3 899.0 900.4 900.5 901.5 711.7 873.9 895.8 898.9 906.3 676.7 677.4 677.5 680.0 680.3 11241.3 11251.4 11260.0 11262.8 11301.5 11370.1 11572.0 11789.5 11796.3 12276.1 11248.6 11299.7 11364.2 11435.1 11466.8 4146.8 4181.8 4227.6 4291.8 4358.7 45726.3 45811.8 45829.5 45904.8 46156.8 9667.8 9731.1 9827.7 10011.5 10202.3 15882.4 16071.0 16071.8 16088.2 16131.3 6496.7 6507.3 6543.4 6600.3 6781.4 3628.7 3645.2 3653.9 3672.9 3673.4 2656.9 2692.2 2715.0 2739.2 2769.7 511.9 517.2 519.6 522.7 524.8 452.4 502.3 550.7 579.8 583.2 7191.2 7432.1 7446.0 7533.1 7549.3 551.9 557.5 561.6 565.2 570.9 3451.1 3500.2 3513.5 3544.5 3557.6 48977.2 52149.2 52327.7 52394.7 52680.0 46588.3 46997.1 47311.3 48285.0 49103.3 40781.4 41047.4 41369.6 41541.8 42028.6 7107.5 7188.8 7390.0 7401.5 7428.9 27404.5 27621.6 29176.7 29677.3 31490.1 35039.5 35755.0 42563.8 42787.6 43001.8 11416.0 11552.1 11602.8 11905.8 11967.6 11268.3 11630.8 12117.5 12869.2 12931.7 11449.8 11750.9 11814.2 12236.4 12323.3 11449.1 11477.7 11866.8 11946.1 12168.8 11270.1 11551.2 11551.8 11965.4 11969.3 9090.1 9306.9 9325.7 9422.0 9485.3 9216.4 9830.5 9920.9 9977.0 10044.7 19:27:53 INFO - PROCESS | 20188 | -------- Summary: end -------- 19:27:53 INFO - PROCESS | 20188 | 19:27:53 INFO - TEST-INFO | 20188: exit 0 19:27:53 INFO - TEST-OK | dromaeo_css | took 1115998ms 19:27:53 INFO - TEST-START | kraken 19:27:53 INFO - operating with platform_type : linux_ 19:27:53 INFO - Initialising browser for kraken test... 19:27:53 INFO - TEST-INFO | started process 20367 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpbqLVG9/profile http://localhost:58996/getInfo.html) 19:27:57 INFO - PROCESS | 20367 | __metrics Screen width/height:1600/1200 19:27:57 INFO - PROCESS | 20367 | colorDepth:24 19:27:57 INFO - PROCESS | 20367 | Browser inner width/height: 1024/697 19:27:57 INFO - PROCESS | 20367 | __metrics 19:27:58 ERROR - PROCESS | 20367 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 19:27:59 INFO - TEST-INFO | 20367: exit 0 19:27:59 INFO - Browser initialized. 19:27:59 INFO - Running cycle 1/1 for kraken test... 19:27:59 INFO - TEST-INFO | started process 20445 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpbqLVG9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:28:10 INFO - PROCESS | 20445 | RSS: Main: 153137152 19:28:10 INFO - PROCESS | 20445 | 19:28:13 INFO - PROCESS | 20445 | Cycle 1(1): loaded http://localhost:58996/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:58996/tests/kraken/driver.html?testName=audio-beat-detection) 19:28:14 INFO - PROCESS | 20445 | RSS: Main: 208531456 19:28:14 INFO - PROCESS | 20445 | 19:28:17 INFO - PROCESS | 20445 | Cycle 1(1): loaded http://localhost:58996/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:58996/tests/kraken/driver.html?testName=audio-dft) 19:28:17 INFO - PROCESS | 20445 | RSS: Main: 197390336 19:28:17 INFO - PROCESS | 20445 | 19:28:21 INFO - PROCESS | 20445 | Cycle 1(1): loaded http://localhost:58996/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:58996/tests/kraken/driver.html?testName=audio-fft) 19:28:21 INFO - PROCESS | 20445 | RSS: Main: 266100736 19:28:21 INFO - PROCESS | 20445 | 19:28:23 INFO - PROCESS | 20445 | Cycle 1(1): loaded http://localhost:58996/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:58996/tests/kraken/driver.html?testName=audio-oscillator) 19:28:24 INFO - PROCESS | 20445 | RSS: Main: 175226880 19:28:24 INFO - PROCESS | 20445 | 19:28:26 INFO - PROCESS | 20445 | Cycle 1(1): loaded http://localhost:58996/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:58996/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:28:27 INFO - PROCESS | 20445 | RSS: Main: 178724864 19:28:27 INFO - PROCESS | 20445 | 19:28:30 INFO - PROCESS | 20445 | Cycle 1(1): loaded http://localhost:58996/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:58996/tests/kraken/driver.html?testName=imaging-darkroom) 19:28:31 INFO - PROCESS | 20445 | RSS: Main: 215146496 19:28:31 INFO - PROCESS | 20445 | 19:28:34 INFO - PROCESS | 20445 | Cycle 1(1): loaded http://localhost:58996/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:58996/tests/kraken/driver.html?testName=imaging-desaturate) 19:28:35 INFO - PROCESS | 20445 | RSS: Main: 213499904 19:28:35 INFO - PROCESS | 20445 | 19:28:38 INFO - PROCESS | 20445 | Cycle 1(1): loaded http://localhost:58996/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:58996/tests/kraken/driver.html?testName=json-parse-financial) 19:28:39 INFO - PROCESS | 20445 | RSS: Main: 214458368 19:28:39 INFO - PROCESS | 20445 | 19:28:41 INFO - PROCESS | 20445 | Cycle 1(1): loaded http://localhost:58996/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:58996/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:28:41 INFO - PROCESS | 20445 | RSS: Main: 206802944 19:28:41 INFO - PROCESS | 20445 | 19:28:43 INFO - PROCESS | 20445 | Cycle 1(1): loaded http://localhost:58996/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:58996/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:28:44 INFO - PROCESS | 20445 | RSS: Main: 209522688 19:28:44 INFO - PROCESS | 20445 | 19:28:46 INFO - PROCESS | 20445 | Cycle 1(1): loaded http://localhost:58996/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:58996/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:28:47 INFO - PROCESS | 20445 | RSS: Main: 214499328 19:28:47 INFO - PROCESS | 20445 | 19:28:50 INFO - PROCESS | 20445 | Cycle 1(1): loaded http://localhost:58996/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:58996/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:28:50 INFO - PROCESS | 20445 | RSS: Main: 219213824 19:28:50 INFO - PROCESS | 20445 | 19:28:53 INFO - PROCESS | 20445 | Cycle 1(1): loaded http://localhost:58996/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:58996/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:28:54 INFO - PROCESS | 20445 | RSS: Main: 219377664 19:28:54 INFO - PROCESS | 20445 | 19:28:56 INFO - PROCESS | 20445 | Cycle 1(1): loaded http://localhost:58996/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:58996/tests/kraken/driver.html?testName=ai-astar) 19:28:56 INFO - PROCESS | 20445 | RSS: Main: 264962048 19:28:56 INFO - PROCESS | 20445 | 19:28:56 INFO - PROCESS | 20445 | __start_tp_report 19:28:56 INFO - PROCESS | 20445 | _x_x_mozilla_page_load 19:28:56 INFO - PROCESS | 20445 | _x_x_mozilla_page_load_details 19:28:56 INFO - PROCESS | 20445 | |i|pagename|runs| 19:28:56 INFO - PROCESS | 20445 | |0;ai-astar;115;112;115;117;114;117;115;114;115;113 19:28:56 INFO - PROCESS | 20445 | |1;audio-beat-detection;166;141;160;216;141;158;160;136;186;134 19:28:56 INFO - PROCESS | 20445 | |2;audio-dft;163;161;162;162;163;162;163;164;162;161 19:28:56 INFO - PROCESS | 20445 | |3;audio-fft;77;77;77;151;77;76;77;77;127;77 19:28:56 INFO - PROCESS | 20445 | |4;audio-oscillator;96;94;148;94;94;94;123;94;94;117 19:28:56 INFO - PROCESS | 20445 | |5;imaging-gaussian-blur;118;117;118;118;118;118;118;118;118;119 19:28:56 INFO - PROCESS | 20445 | |6;imaging-darkroom;150;150;150;148;148;150;149;148;148;149 19:28:56 INFO - PROCESS | 20445 | |7;imaging-desaturate;96;96;95;96;98;96;96;97;96;96 19:28:56 INFO - PROCESS | 20445 | |8;json-parse-financial;68;68;69;68;69;69;69;69;68;68 19:28:56 INFO - PROCESS | 20445 | |9;json-stringify-tinderbox;65;65;64;64;65;65;65;64;65;64 19:28:56 INFO - PROCESS | 20445 | |10;stanford-crypto-aes;87;91;86;87;87;92;88;91;167;92 19:28:56 INFO - PROCESS | 20445 | |11;stanford-crypto-ccm;163;143;142;149;150;146;141;150;172;141 19:28:56 INFO - PROCESS | 20445 | |12;stanford-crypto-pbkdf2;182;189;185;186;181;183;187;180;181;184 19:28:56 INFO - PROCESS | 20445 | |13;stanford-crypto-sha256-iterative;72;71;71;70;71;70;71;69;70;69 19:28:56 INFO - PROCESS | 20445 | __end_tp_report 19:28:56 INFO - PROCESS | 20445 | __start_cc_report 19:28:56 INFO - PROCESS | 20445 | _x_x_mozilla_cycle_collect,1948 19:28:56 INFO - PROCESS | 20445 | __end_cc_report 19:28:56 INFO - PROCESS | 20445 | __startTimestamp1462933736482__endTimestamp 19:28:56 INFO - PROCESS | 20445 | 19:28:56 INFO - PROCESS | 20445 | ------- Summary: start ------- 19:28:56 INFO - PROCESS | 20445 | Number of tests: 14 19:28:56 INFO - PROCESS | 20445 | 19:28:56 INFO - PROCESS | 20445 | [#0] ai-astar Cycles:10 Average:114.70 Median:115.00 stddev:1.57 (1.4%) stddev-sans-first:1.66 19:28:56 INFO - PROCESS | 20445 | Values: 115.0 112.0 115.0 117.0 114.0 117.0 115.0 114.0 115.0 113.0 19:28:56 INFO - PROCESS | 20445 | 19:28:56 INFO - PROCESS | 20445 | [#1] audio-beat-detection Cycles:10 Average:159.80 Median:160.00 stddev:25.44 (15.9%) stddev-sans-first:26.89 19:28:56 INFO - PROCESS | 20445 | Values: 166.0 141.0 160.0 216.0 141.0 158.0 160.0 136.0 186.0 134.0 19:28:56 INFO - PROCESS | 20445 | 19:28:56 INFO - PROCESS | 20445 | [#2] audio-dft Cycles:10 Average:162.30 Median:162.50 stddev:0.95 (0.6%) stddev-sans-first:0.97 19:28:56 INFO - PROCESS | 20445 | Values: 163.0 161.0 162.0 162.0 163.0 162.0 163.0 164.0 162.0 161.0 19:28:56 INFO - PROCESS | 20445 | 19:28:56 INFO - PROCESS | 20445 | [#3] audio-fft Cycles:10 Average:89.30 Median:77.00 stddev:26.80 (34.8%) stddev-sans-first:28.05 19:28:56 INFO - PROCESS | 20445 | Values: 77.0 77.0 77.0 151.0 77.0 76.0 77.0 77.0 127.0 77.0 19:28:56 INFO - PROCESS | 20445 | 19:28:56 INFO - PROCESS | 20445 | [#4] audio-oscillator Cycles:10 Average:104.80 Median:94.00 stddev:18.63 (19.8%) stddev-sans-first:19.49 19:28:56 INFO - PROCESS | 20445 | Values: 96.0 94.0 148.0 94.0 94.0 94.0 123.0 94.0 94.0 117.0 19:28:56 INFO - PROCESS | 20445 | 19:28:56 INFO - PROCESS | 20445 | [#5] imaging-gaussian-blur Cycles:10 Average:118.00 Median:118.00 stddev:0.47 (0.4%) stddev-sans-first:0.50 19:28:56 INFO - PROCESS | 20445 | Values: 118.0 117.0 118.0 118.0 118.0 118.0 118.0 118.0 118.0 119.0 19:28:56 INFO - PROCESS | 20445 | 19:28:56 INFO - PROCESS | 20445 | [#6] imaging-darkroom Cycles:10 Average:149.00 Median:149.50 stddev:0.94 (0.6%) stddev-sans-first:0.93 19:28:56 INFO - PROCESS | 20445 | Values: 150.0 150.0 150.0 148.0 148.0 150.0 149.0 148.0 148.0 149.0 19:28:56 INFO - PROCESS | 20445 | 19:28:56 INFO - PROCESS | 20445 | [#7] imaging-desaturate Cycles:10 Average:96.20 Median:96.00 stddev:0.79 (0.8%) stddev-sans-first:0.83 19:28:56 INFO - PROCESS | 20445 | Values: 96.0 96.0 95.0 96.0 98.0 96.0 96.0 97.0 96.0 96.0 19:28:56 INFO - PROCESS | 20445 | 19:28:56 INFO - PROCESS | 20445 | [#8] json-parse-financial Cycles:10 Average:68.50 Median:69.00 stddev:0.53 (0.8%) stddev-sans-first:0.53 19:28:56 INFO - PROCESS | 20445 | Values: 68.0 68.0 69.0 68.0 69.0 69.0 69.0 69.0 68.0 68.0 19:28:56 INFO - PROCESS | 20445 | 19:28:56 INFO - PROCESS | 20445 | [#9] json-stringify-tinderbox Cycles:10 Average:64.60 Median:65.00 stddev:0.52 (0.8%) stddev-sans-first:0.53 19:28:56 INFO - PROCESS | 20445 | Values: 65.0 65.0 64.0 64.0 65.0 65.0 65.0 64.0 65.0 64.0 19:28:56 INFO - PROCESS | 20445 | 19:28:56 INFO - PROCESS | 20445 | [#10] stanford-crypto-aes Cycles:10 Average:96.80 Median:89.50 stddev:24.77 (27.7%) stddev-sans-first:26.02 19:28:56 INFO - PROCESS | 20445 | Values: 87.0 91.0 86.0 87.0 87.0 92.0 88.0 91.0 167.0 92.0 19:28:56 INFO - PROCESS | 20445 | 19:28:56 INFO - PROCESS | 20445 | [#11] stanford-crypto-ccm Cycles:10 Average:149.70 Median:149.50 stddev:10.24 (6.9%) stddev-sans-first:9.67 19:28:56 INFO - PROCESS | 20445 | Values: 163.0 143.0 142.0 149.0 150.0 146.0 141.0 150.0 172.0 141.0 19:28:56 INFO - PROCESS | 20445 | 19:28:56 INFO - PROCESS | 20445 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:183.80 Median:184.50 stddev:2.94 (1.6%) stddev-sans-first:3.04 19:28:56 INFO - PROCESS | 20445 | Values: 182.0 189.0 185.0 186.0 181.0 183.0 187.0 180.0 181.0 184.0 19:28:56 INFO - PROCESS | 20445 | 19:28:56 INFO - PROCESS | 20445 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.40 Median:71.00 stddev:0.97 (1.4%) stddev-sans-first:0.83 19:28:56 INFO - PROCESS | 20445 | Values: 72.0 71.0 71.0 70.0 71.0 70.0 71.0 69.0 70.0 69.0 19:28:56 INFO - PROCESS | 20445 | -------- Summary: end -------- 19:28:56 INFO - PROCESS | 20445 | 19:28:57 INFO - TEST-INFO | 20445: exit 0 19:28:57 INFO - TEST-OK | kraken | took 63517ms 19:28:57 INFO - SUITE-END | took 1179s 19:28:57 INFO - Completed test suite (00:19:39) 19:28:57 INFO - javascript benchmark 19:28:57 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2593.701724301706, 2598.7401259874014, 2634.538955682755, 2640.2871224769306, 2660.2950218192173, 4621.930671039937, 4632.698874573159, 4634.884127896804, 4682.531746825316, 4729.12511185431, 4633.536646335365, 4675.789589468473, 4703.529647035295, 4720.197566413709, 4749.121412538674, 4247.469066366704, 4358.5423530529315, 4372.23485889968, 4405.625521830632, 4433.689641725065, 4312.202242585116, 4360.258756011485, 4371.060222052258, 4375.168717943597, 4419.11617676466, 4240.17316623257, 4240.703150779446, 4289.978550107261, 4300.763458009808, 4360.498542667602, 4321.978390108061, 4351.238533256667, 4404.911901761979, 4410.05183885463, 4426.889327766799, 4372.2130016597, 4413.801378937956, 4431.7784110794655, 4432.20220360335, 4439.289713645801, 4421.0, 4618.5843274105155, 4693.812247510095, 4715.976420117878, 4742.2886567014875, 5127.0258650856595, 5156.3296771419855, 5160.277561141444, 5173.741312934376, 5174.560162386202, 4926.679765815211, 5149.897002059975, 5167.457416971201, 5190.013897359489, 5194.896102077975, 4671.48613652498, 4693.084848454587, 4697.694649847749, 4698.835540756091, 4739.099571081483, 5120.462351453044, 5142.151544995034, 5156.1492353761205, 5172.094883395391, 5195.48045195485, 4774.140654682121, 4926.8768280793265, 4972.303877457159, 4978.128827455181, 4998.550130488201, 5138.820141295037, 5143.305653736773, 5144.897102058013, 5192.247124166975, 5237.580993520509, 5088.338515992971, 5124.205748109049, 5144.71704056273, 5163.741812909339, 5178.663386879841, 4907.3620429343955, 4908.558229759339, 4941.728204948762, 5126.38483381989, 5155.252488389237, 4254.787260636956, 4280.422143010716, 4304.0, 4317.740935543878, 4328.9134217316105, 3734.2718169956597, 3734.3278209922473, 3762.680172185395, 3767.981160094182, 3793.279276937373, 4185.393117997874, 4206.978965105155, 4225.049363893161, 4241.172971270635, 4268.658507319407, 4979.726115063706, 5017.347744793154, 5031.371078615173, 5042.722650254198, 5065.062963351792, 4229.175310814423, 4231.957680423218, 4238.978805105955, 4268.935965960513, 4308.504521980012, 4486.910261794746, 4501.0, 4587.8759703872765, 4625.236835922035, 4661.207594708908, 4822.397200349957, 4826.58973987215, 4837.984023355135, 4840.782164802592, 4886.560209581155, 5144.228365745168, 5165.560927321219, 5195.766190521511, 5214.530692237717, 5273.868153296198, 4639.280911458729, 4761.40482439695, 4763.16644587189, 4800.951990480051, 4820.493848145894, 5035.949640503548, 5051.848444546523, 5059.46875578059, 5073.0, 5115.3094332266155, 4964.429090654621, 5092.592592592658, 5137.075326441276, 5155.407128180157, 5161.483851614808, 4893.241547560134, 4939.209726443752, 4981.953789704203, 5013.448520662797, 5014.122528557561, 4955.6531042826655, 4961.404631444106, 4967.081089998362, 4992.625553083606, 5021.246812978083, 4756.5956893664425, 4768.57082862544, 4897.3878265216845, 4908.337374454546, 4954.950450495449, 4899.387576552931, 4926.162552366035, 4944.604431645532, 4946.010797840374, 5020.146575082172, 4814.470408255159, 4819.445763737215, 4830.589399901048, 4851.636127290538, 4887.413510378778], "value": 4649.116376070779, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [520.1651349583917, 528.8862894477687, 550.5320477594046, 553.2807350444423, 567.4268988321794, 28428.43143137136, 31958.0, 31996.840015799946, 41081.58918410815, 42398.78800605993, 42188.78905605468, 42498.36252456208, 42630.57369426305, 42695.0, 42738.0, 7542.5097368670995, 7607.01108855848, 7663.3486153677, 7668.233176682343, 7673.693052277902, 7674.693012279502, 7784.688612455495, 7790.688372465094, 7835.804104897395, 7839.960800195991, 7790.9610451947665, 7802.375809935192, 7813.398253358048, 7847.489913155627, 7877.684892604318, 7709.653065612033, 7757.340626046765, 7773.144954055049, 7791.337736292422, 7804.531728096333, 7667.846643067108, 7744.457887947846, 7773.766786996399, 7789.883151752728, 7810.843783124305, 38792.0, 39059.80470097631, 39114.21771564553, 39183.60816391828, 39194.41208381877, 3335.116194208537, 3361.0757041813695, 3374.038399056282, 3424.2637832866053, 3781.3949768037255, 27955.301117472023, 29147.562786558217, 29423.0, 29658.55512167319, 30902.381952360825, 3148.212946763309, 3153.952690709641, 3161.762867784925, 3201.183698156978, 3275.574175357212, 235716.0, 235812.0, 236786.99999999828, 236924.0, 237295.0, 32977.83511082429, 34162.65837341595, 34219.486707699405, 52873.0, 53006.0, 35414.114647134025, 35443.64556354455, 35543.11142221465, 35543.64456355404, 35604.109897252776, 12339.197952133261, 12349.259044457362, 12368.134230603771, 12372.0, 12378.504859805687, 10717.035466808025, 10743.409112498888, 10744.677659670222, 10786.838197427045, 10804.29772064829, 8286.129956354496, 8295.377846661524, 8326.833463330699, 8342.082370939192, 8376.832463350698, 3265.8203798791296, 3281.671832816747, 3282.8030318180986, 3305.2397948471985, 3310.2882880180878, 895.4850960697624, 902.5036230073433, 925.9629614815332, 931.1107891963159, 991.375433476905, 27149.321266968484, 28562.85718571394, 28677.713222867922, 29020.27449313784, 29146.0, 965.3532133470451, 970.3062310447923, 984.837501812193, 985.0, 992.3004281981185, 13987.37056832445, 14473.348699308557, 14505.782413263809, 14692.894820971987, 14730.0, 347489.0, 350416.0, 351716.0, 351806.0, 355035.0, 47990.52009479861, 48620.756896215295, 48628.5137148624, 48784.75607621939, 48801.0, 1149.9195056345975, 1239.659093749208, 1264.2856785915928, 1296.935153242334, 1300.6358219698689, 27792.583111253753, 28629.856850715612, 28952.0, 29183.416331672823, 29367.85316073406, 40665.0, 41334.79332603318, 41563.584364157185, 41653.79173104115, 41715.58284417117, 284780.0, 288410.0, 288836.0, 289017.0, 291318.0, 22413.551728965656, 24335.391615209763, 24957.500849983262, 25037.0, 25320.620190697522, 38145.80927095347, 38225.23549528938, 38548.80725596466, 38747.61252387553, 38813.0, 33438.331233375684, 33527.664723352456, 33701.49447758334, 34087.0, 34411.65588344185, 33561.16097097592, 33799.0, 34384.82807585946, 34452.31095378028, 35074.0, 33465.0, 33788.49317260194, 33795.15512112217, 34108.658913410545, 34281.48577771385, 30025.399492010474, 30582.694173058873, 30582.847085764428, 30607.0, 30763.230919226302, 23381.649275261225, 23655.0, 23950.76049239555, 23973.640395394432, 24181.516369672157], "value": 16088.437621227295, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [379.9183175617243, 389.4703203643046, 395.18096035871775, 402.36828179757777, 405.86403554809135, 12741.299228542426, 12788.296643684595, 15946.601334966632, 17232.483025509257, 17313.56716082097, 16095.195240238, 16646.08446535443, 17271.82728172718, 17290.481285561422, 17765.64468710625, 5012.924806127902, 5242.501962313574, 5252.6323157379, 5266.052110620106, 5337.946620533803, 5019.899602007976, 5053.115704751672, 5104.412992505853, 5215.869603259911, 5226.08543504887, 5118.232265160219, 5122.615803814729, 5139.486051394868, 5145.382554093495, 5156.742162891859, 5100.566451851597, 5111.309973153632, 5188.584913206953, 5551.528120109796, 5597.244372009767, 5381.0583147948955, 5381.7578208980685, 5392.91910621341, 5405.675659460445, 5437.157240627669, 11256.324620522713, 11522.251053681484, 11557.88442115585, 11583.6524904253, 11867.465964031639, 1947.1432569669441, 2067.9069441875154, 2156.5902478529188, 2207.6357401028813, 2287.9885600572056, 8924.241439477652, 8955.820883582357, 9253.537323133884, 9501.524923753785, 11806.114541409428, 1661.4932445604086, 1725.4651058172008, 1739.4955462915739, 1805.0703887497948, 1857.2385322018042, 67869.32130678729, 70303.64848175777, 70585.0, 71503.64248178779, 72442.27557724409, 10139.290249682452, 10260.281780275309, 10286.177105831515, 10345.37927724339, 10360.740981475523, 14162.787558186636, 14291.142531448146, 14292.642683932985, 14333.283335833166, 14370.425182992773, 6902.827429314308, 7079.893801592877, 7136.964315178495, 7146.892796608055, 7283.781486555412, 6422.903656445157, 6432.517561182836, 6529.11856899322, 6551.80344589663, 6558.311377305409, 4329.870103896888, 4346.869593912188, 4421.977890110529, 4434.135343607965, 4453.487848897354, 1823.5258832703328, 1849.010779233104, 1910.3982245592595, 1928.1805232776014, 1954.208545539059, 806.3226889412921, 923.2844545477175, 963.1940112289458, 972.8297547929226, 991.177322822056, 15984.040957542584, 16760.58098547546, 16771.748423773653, 17178.570535736457, 17300.307987680604, 861.8922634670666, 881.8721285413708, 896.3322324868016, 917.8256131335025, 927.5825878354767, 9093.31800114994, 9288.860667089999, 9309.953450232706, 9498.525073746423, 9686.0, 396307.0, 402351.0, 409223.0, 411707.0, 412234.0, 46356.0, 47976.28035579469, 78609.0, 79386.44294899417, 87359.0, 887.3921363865772, 891.233539156325, 896.2650626486349, 923.5982347678781, 926.333040211047, 11589.6523104307, 14840.480583179537, 14978.47575334858, 15138.167400793061, 15306.464273750365, 24670.506589868462, 33463.33073338569, 34692.47961280532, 34788.65211347854, 35984.64015359813, 44558.10883782371, 45159.54840451553, 45232.32151517664, 45642.087158255985, 45864.0, 7395.926040739524, 7424.0, 7445.590492522747, 7543.396528277615, 7587.62061896914, 10385.286427739338, 11303.7739245213, 11720.238184517979, 11990.040796736415, 12076.335801531, 9497.620095196115, 9910.554025068745, 10328.140934631538, 10459.843102353623, 10715.035646791826, 10521.263511554424, 10697.51861166265, 10747.247692661438, 10761.784764304826, 10941.56233750641, 10033.19734421259, 10105.545250463894, 10382.688519344432, 11057.944710276397, 11092.556297748, 7844.0, 7883.329916957123, 8017.559034253172, 8017.719379821679, 8125.918740812516, 10382.42896640692, 10426.27016108865, 10951.890481095086, 11012.17408694335, 11057.557697692002], "value": 8048.309710139206, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1256.9434375453104, 1464.524029690351, 1523.7638166084257, 1692.866987595629, 1865.2632210276931, 7284.635768211588, 7613.961930190355, 7664.348530374918, 7796.220377962215, 7798.103218129902, 7621.657025433855, 7740.187280335568, 7750.92249077509, 7768.067831860185, 7781.6498257578405, 2217.9001944912516, 2244.2481768607454, 2251.234580242717, 2257.6274914639066, 2261.163369553268, 2231.297141400462, 2252.6395776675736, 2300.1259521381853, 2301.9641161477402, 2302.309307207831, 2124.59632669793, 2134.583756167548, 2160.4166874943758, 2175.771543987882, 2176.967345489811, 2137.593857167133, 2143.3677065265783, 2152.7416709994823, 2155.89220538974, 2159.5356998245375, 2046.8464865135174, 2048.528838367169, 2049.333966460906, 2053.0761157479196, 2065.2048961150067, 7436.628168591549, 7669.271419215165, 7713.305802477804, 7763.534187948741, 7766.0, 4811.831585894477, 4954.752262386903, 4964.975175124137, 4966.801327946914, 4980.228064649985, 2776.403073339242, 2786.0109661070233, 2817.619621351112, 2920.9707902921123, 2967.4955257606043, 846.8899043124416, 869.6738722978882, 878.9033206347297, 901.3014913442134, 917.8531434970439, 10821.83767243492, 10830.67507974762, 10867.565297388173, 10875.619353322674, 10906.890931090746, 4164.292070348048, 4167.1457351242925, 4266.935995960063, 4292.763897985578, 4335.176316499855, 8503.404761666749, 8531.914680853237, 8539.573021348908, 8564.143585641386, 8592.097829727789, 4697.741624210701, 4727.030958653468, 4762.261849413347, 4776.617870570346, 4785.760711964388, 3746.6440688134585, 4160.750354978711, 4244.511881133647, 4320.049589090395, 4450.599446049871, 3612.458131280329, 3722.739408241397, 3752.493413389212, 3791.33651610967, 3812.9237415252096, 1209.5585111434264, 1224.20426722631, 1229.8893099621077, 1239.9504019839105, 1248.650377894191, 797.7885860247039, 821.514530662577, 830.3647709502236, 853.846307664614, 915.9542022898828, 5078.796848126108, 5344.957733242059, 5405.486478784509, 5528.253685752452, 5550.9453203891135, 862.4782006885869, 887.5828360670604, 892.7054072156172, 905.44767691708, 919.1268295119663, 3756.4365345197893, 3825.464434979105, 3839.8080095995088, 3846.673032792188, 3850.8652197173524, 9561.139497445263, 9843.852342214872, 9946.651867184613, 9979.0, 9999.750006249757, 9514.47670378122, 9544.856827147598, 9796.412215267106, 9898.257630677728, 9999.100080992746, 7495.962520187364, 7563.941048253249, 7634.0, 7644.885326720104, 7722.536647801037, 5089.160288552347, 5094.6688465249645, 5259.710715910662, 5279.313689220377, 5324.8935021298585, 8768.473891566526, 8795.516246606243, 8928.464292142493, 9196.494192819458, 9368.81262374735, 9101.954490227507, 9182.540872956191, 9208.953955230181, 9266.768330791514, 9284.071592840663, 5480.671159730429, 6182.227221597301, 6267.780627678009, 6278.905816412666, 6490.0, 7740.612969351622, 7784.2215778421705, 7835.4123440743315, 7849.921500784919, 7872.330851877655, 6895.34494223048, 6971.372576468142, 7055.964720176366, 7159.391451726661, 7184.784456466315, 6733.831654208684, 6771.28901465339, 6925.6190909500465, 6962.303769622998, 7034.613096279753, 6555.410013098844, 6604.834879127868, 6649.63427011519, 6663.400293973566, 6691.966540167268, 4809.398825146856, 4862.489438609036, 4873.317735516959, 4896.755162241802, 4946.925796112989, 4764.547368000034, 4776.140294746979, 4797.40032495938, 4864.975675121602, 4909.779059942382], "value": 4200.993664304923, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [311.76306007434346, 312.3378437712051, 324.6672161034939, 326.2561360098974, 326.5346880695009, 4575.908481830362, 4611.354410382545, 4625.774169844994, 4651.558101980315, 4669.206234940059, 4366.148601022802, 4412.316091005891, 4518.231900576901, 4521.462702681088, 4570.588647021767, 3579.284143171363, 3674.210044840359, 3674.669279764814, 3684.09739613795, 3698.8890333290046, 3688.834002469882, 3690.575583807856, 3697.0036180462653, 3698.3712768829364, 3699.2440631729264, 3408.7784294020935, 3495.4756786481976, 3638.9636103639023, 3649.781013139207, 3670.100825297806, 3609.981950090259, 3620.619834917322, 3621.058524783549, 3664.578573464059, 3666.816659167058, 3279.6884295991845, 3291.736661067109, 3291.8683252669866, 3292.341531693647, 3296.241864371184, 4323.816941773263, 4363.432753742025, 4442.4224850769515, 4462.464504259509, 4465.307877279027, 4132.607402296777, 4170.332746760504, 4172.645325147366, 4187.811548480326, 4197.538270790211, 4784.6889952153, 4788.377510923592, 4794.592459640935, 4804.927926081112, 4818.253170758538, 3523.1016090896655, 3528.9294214115575, 3561.6082230954576, 3567.6789088981855, 3574.6782789549065, 4764.880877978044, 4987.900241995176, 4988.700677959334, 5000.849974500771, 5006.89986200274, 3610.548681414823, 3611.638836116367, 3618.2451511017134, 3633.5284209895035, 3657.835397407123, 3655.5796083450214, 3683.981580092136, 3730.0, 3734.981325093357, 3748.8125593720206, 4053.3919912012843, 4169.478815148106, 4181.456410666655, 4187.832486700498, 4210.242156411875, 4753.881152971203, 4766.713997160182, 4789.808407663654, 4873.269009648581, 4879.4876537963, 1221.8900298972958, 1264.778663733843, 1278.1500302298866, 1300.6213413781422, 1308.3262120008203, 834.4951304460714, 840.7771940435789, 848.9108643592457, 852.4459101583896, 852.5822347050025, 3717.646823551758, 3732.1789206374556, 3734.7198960078103, 3774.830132644038, 3813.9046523836573, 4309.547497512779, 4319.956800432018, 4335.3770968564195, 4358.932061644854, 4418.447694038246, 3732.794696291676, 3739.0839244384756, 3746.981265093657, 3764.6235376462137, 3772.1512659651908, 2190.1896298369707, 2287.782660647236, 2294.678744975705, 2308.5267520158327, 2323.4307594639085, 4087.693422993287, 4106.630403263721, 4116.0, 4119.258533464005, 4135.958640413617, 4427.424434823517, 4427.46870375557, 4443.844465443694, 4447.554544772897, 4469.910601787946, 3833.5913699244975, 4054.6958978076764, 4131.938020929689, 4138.730982486129, 4160.999999999939, 4286.335617979218, 4288.198956438366, 4294.549072347483, 4320.848770293024, 4329.718568293177, 4182.163567286494, 4221.28238199513, 4258.212230737324, 4260.829566817292, 4278.315469524861, 4105.384192371168, 4117.3618089196225, 4135.565765594569, 4136.896577585464, 4145.54399016114, 3192.4572822620676, 3203.6956489133495, 3260.5924259467565, 3265.9673403266615, 3327.933441331208, 4276.444062271885, 4341.761203133858, 4350.390945267602, 4361.411209486806, 4739.4075740532435, 4639.976800115978, 4753.215719406259, 4833.2025215840395, 4846.36997190363, 4855.320255164209, 4838.782254798472, 4843.370361852936, 4850.296706977539, 4851.29656199842, 4859.303086786867, 4548.727076375428, 4746.857594272177, 4767.713937163781, 4785.688930219525, 4798.7360695160705, 4815.747905544514, 4816.20532612115, 4823.384166304328, 4855.295982082649, 4863.2461968394955, 4746.7863946121815, 4749.957518267178, 4770.809167633256, 4777.880552986203, 4791.976040119777], "value": 3523.8049664943073, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [732.2494443195724, 735.981600459988, 736.5470235804978, 806.040811434393, 806.8991376077988, 11935.224210426317, 12534.811977820316, 12621.116521843474, 12650.683732906671, 12652.746945061104, 13693.726125477484, 13767.173969561833, 13800.654983625414, 13833.446662133527, 13869.514566990158, 679.3274658088507, 850.4599579267148, 866.0733015673217, 874.5190145420025, 875.6322344615277, 854.5129276312473, 857.6998050682242, 860.1183786618734, 865.6537385045968, 866.4584634603373, 888.3381880499037, 898.9865152022725, 900.4282280751704, 900.4687234531625, 901.486152892848, 711.7295427737453, 873.9126087391273, 895.7939673875013, 898.9146031127033, 906.302147346546, 676.719161547957, 677.3971165662564, 677.4918810891832, 679.9932000679979, 680.3162821364547, 11241.269317494336, 11251.43742812856, 11260.0, 11262.831057534144, 11301.547938082467, 11370.09039276856, 11571.95233730521, 11789.528418863321, 11796.29222246668, 12276.140670153092, 11248.55005799767, 11299.661010169708, 11364.212472600893, 11435.14236432271, 11466.827997580043, 4146.792660366969, 4181.811818468176, 4227.619514243733, 4291.7854107294825, 4358.738475691437, 45726.31410528878, 45811.77094114541, 45829.54170458286, 45904.770476147736, 46156.769216153705, 9667.758306042264, 9731.075547822946, 9827.656032038987, 10011.549480273405, 10202.33684810485, 15882.364705411654, 16071.035737855766, 16071.839281607268, 16088.195590220676, 16131.274092665624, 6496.707648155844, 6507.349265073455, 6543.411093001558, 6600.273969863311, 6781.355771201728, 3628.7097032237357, 3645.1616128290643, 3653.908652283661, 3672.889813305605, 3673.3755261605593, 2656.9335766605604, 2692.192342297303, 2714.9864250679016, 2739.1919383781833, 2769.709180536055, 511.93600799900014, 517.1854329431161, 519.555779808266, 522.6837763153313, 524.8425472358277, 452.42993827777235, 502.3243737173496, 550.713628912963, 579.8084935457591, 583.1631608641613, 7191.208967013625, 7432.108147022392, 7446.0, 7533.096028476618, 7549.320561149523, 551.9475649813261, 557.542814891793, 561.6237121128772, 565.1579147070917, 570.891530609183, 3451.0682115829086, 3500.2474467989505, 3513.4554144107706, 3544.4506101554302, 3557.5553055868018, 48977.17960815433, 52149.217761733606, 52327.73836130795, 52394.738026310384, 52680.0, 46588.3011754824, 46997.060058798634, 47311.268873112414, 48285.0, 49103.26345104826, 40781.38827917584, 41047.384289235684, 41369.586304136574, 41541.79229103895, 42028.57971420308, 7107.466939979418, 7188.784336469915, 7389.963050184715, 7401.48189626721, 7428.925710742823, 27404.451910962067, 27621.585676215276, 29176.7082329174, 29677.258068548457, 31490.084419800824, 35039.47440788339, 35755.0, 42563.7871810639, 42787.57212427836, 43001.78499107484, 11416.0, 11552.133589980953, 11602.76794464123, 11905.761884762429, 11967.64097077089, 11268.323900565993, 11630.767384652429, 12117.515299388278, 12869.22784632925, 12931.676708081997, 11449.771004580029, 11750.882491174978, 11814.232074915102, 12236.449359778653, 12323.322217277777, 11449.141314401286, 11477.713057173303, 11866.821997670215, 11946.104042197045, 12168.817467737814, 11270.098392128773, 11551.249168804, 11551.76896462083, 11965.401729913296, 11969.281843089442, 9090.05279726336, 9306.860397094184, 9325.720228393158, 9421.952890235505, 9485.288603354638, 9216.354855160074, 9830.508474576385, 9920.851187232342, 9977.0, 10044.748881278027], "value": 6013.582511676971, "unit": "score"}], "name": "dromaeo_css", "value": 6140.293915263738, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 112.0, 115.0, 117.0, 114.0, 117.0, 115.0, 114.0, 115.0, 113.0], "value": 114.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 141.0, 160.0, 216.0, 141.0, 158.0, 160.0, 136.0, 186.0, 134.0], "value": 159.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 161.0, 162.0, 162.0, 163.0, 162.0, 163.0, 164.0, 162.0, 161.0], "value": 162.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 77.0, 77.0, 151.0, 77.0, 76.0, 77.0, 77.0, 127.0, 77.0], "value": 89.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 94.0, 148.0, 94.0, 94.0, 94.0, 123.0, 94.0, 94.0, 117.0], "value": 104.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 117.0, 118.0, 118.0, 118.0, 118.0, 118.0, 118.0, 118.0, 119.0], "value": 118.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 150.0, 150.0, 148.0, 148.0, 150.0, 149.0, 148.0, 148.0, 149.0], "value": 149.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 95.0, 96.0, 98.0, 96.0, 96.0, 97.0, 96.0, 96.0], "value": 96.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 69.0, 68.0, 69.0, 69.0, 69.0, 69.0, 68.0, 68.0], "value": 68.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 64.0, 64.0, 65.0, 65.0, 65.0, 64.0, 65.0, 64.0], "value": 64.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 91.0, 86.0, 87.0, 87.0, 92.0, 88.0, 91.0, 167.0, 92.0], "value": 96.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 143.0, 142.0, 149.0, 150.0, 146.0, 141.0, 150.0, 172.0, 141.0], "value": 149.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 189.0, 185.0, 186.0, 181.0, 183.0, 187.0, 180.0, 181.0, 184.0], "value": 183.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 71.0, 71.0, 70.0, 71.0, 70.0, 71.0, 69.0, 70.0, 69.0], "value": 70.4, "unit": "score"}], "name": "kraken", "value": 1627.8999999999999, "alertThreshold": 2.0}]} 19:28:57 INFO - Return code: 0 19:28:57 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 19:28:57 ERROR - # TBPL SUCCESS # 19:28:57 INFO - Running post-action listener: _resource_record_post_action 19:28:57 INFO - ##### 19:28:57 INFO - ##### Finished run-tests step (success) 19:28:57 INFO - ##### 19:28:57 INFO - Running post-run listener: _resource_record_post_run 19:28:58 INFO - Total resource usage - Wall time: 1197s; CPU: 26.0%; Read bytes: 8192; Write bytes: 538021888; Read time: 156; Write time: 453192 19:28:58 INFO - install - Wall time: 16s; CPU: 26.0%; Read bytes: 0; Write bytes: 234123264; Read time: 0; Write time: 255532 19:28:58 INFO - run-tests - Wall time: 1181s; CPU: 26.0%; Read bytes: 8192; Write bytes: 285913088; Read time: 156; Write time: 190564 19:28:58 INFO - Running post-run listener: _upload_blobber_files 19:28:58 INFO - Blob upload gear active. 19:28:58 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:28:58 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 19:28:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 19:28:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 19:28:59 INFO - (blobuploader) - INFO - Open directory for files ... 19:28:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 19:28:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:28:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:29:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 19:29:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:29:00 INFO - (blobuploader) - INFO - Done attempting. 19:29:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 19:29:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:29:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:29:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 19:29:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:29:00 INFO - (blobuploader) - INFO - Done attempting. 19:29:00 INFO - (blobuploader) - INFO - Iteration through files over. 19:29:00 INFO - Return code: 0 19:29:00 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:29:00 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:29:00 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/87acad89e8951b17caf21f9559169fcb4d1b43702cb1b0707c62d7aaf460b3af2f54f8424a82e5d30ca3bf1c0ea2fb544c26c552caf4d526091b786fd46f4add", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/d6d94df9aea0a3f1a384d1eb248d722af1a020bd8915f6315cbcc7621000896da4ca875ee95ec9d9918c1d6fe450747292aed8e3ab2954dde0992dac8836c4ae"} 19:29:00 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:29:00 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:29:00 INFO - Contents: 19:29:00 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/87acad89e8951b17caf21f9559169fcb4d1b43702cb1b0707c62d7aaf460b3af2f54f8424a82e5d30ca3bf1c0ea2fb544c26c552caf4d526091b786fd46f4add", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/d6d94df9aea0a3f1a384d1eb248d722af1a020bd8915f6315cbcc7621000896da4ca875ee95ec9d9918c1d6fe450747292aed8e3ab2954dde0992dac8836c4ae"} 19:29:00 INFO - Running post-run listener: copy_logs_to_upload_dir 19:29:00 INFO - Copying logs to upload dir... 19:29:00 INFO - mkdir: /builds/slave/test/build/upload/logs 19:29:00 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1239.103257 ========= master_lag: 13.71 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2016-05-10 19:29:13.907203) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-10 19:29:13.912204) ========= 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=82e07478c745e68b1fe4678a000001bc-1462931301.948880-1036685957 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/87acad89e8951b17caf21f9559169fcb4d1b43702cb1b0707c62d7aaf460b3af2f54f8424a82e5d30ca3bf1c0ea2fb544c26c552caf4d526091b786fd46f4add", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/d6d94df9aea0a3f1a384d1eb248d722af1a020bd8915f6315cbcc7621000896da4ca875ee95ec9d9918c1d6fe450747292aed8e3ab2954dde0992dac8836c4ae"} build_url:https://queue.taskcluster.net/v1/task/D1-qap_SQMueqU6K-n7cSg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.005458 build_url: 'https://queue.taskcluster.net/v1/task/D1-qap_SQMueqU6K-n7cSg/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/Fx-Team-Non-PGO/sha512/87acad89e8951b17caf21f9559169fcb4d1b43702cb1b0707c62d7aaf460b3af2f54f8424a82e5d30ca3bf1c0ea2fb544c26c552caf4d526091b786fd46f4add", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/d6d94df9aea0a3f1a384d1eb248d722af1a020bd8915f6315cbcc7621000896da4ca875ee95ec9d9918c1d6fe450747292aed8e3ab2954dde0992dac8836c4ae"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-10 19:29:13.935045) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 19:29:13.935403) ========= 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=82e07478c745e68b1fe4678a000001bc-1462931301.948880-1036685957 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.002126 ========= master_lag: 0.24 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 19:29:14.174196) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-10 19:29:14.174526) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-10 19:29:14.332781) ========= ========= Total master_lag: 14.02 =========