builder: fx-team_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-009 starttime: 1462928795.56 results: success (0) buildid: 20160510174715 builduid: b1db30ce115e4403ad2a90eb4ace112c revision: 7fc6b24beea495728bc5070a5b621b31e403b82d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-10 18:06:35.564580) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-10 18:06:35.565031) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-10 18:06:35.565309) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-10 18:06:35.587705) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 18:06:35.588092) ========= 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=aa52b8aba932b19cc50b5275000001de-1462927875.699878-1663118001 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-10 18:06:35-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.7M=0s 2016-05-10 18:06:35 (46.7 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.117663 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 18:06:35.720641) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 18:06:35.724200) ========= 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=aa52b8aba932b19cc50b5275000001de-1462927875.699878-1663118001 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016745 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 18:06:35.778382) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 18:06:35.781749) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 7fc6b24beea495728bc5070a5b621b31e403b82d --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 7fc6b24beea495728bc5070a5b621b31e403b82d --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=aa52b8aba932b19cc50b5275000001de-1462927875.699878-1663118001 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-10 18:06:35,855 truncating revision to first 12 chars 2016-05-10 18:06:35,855 Setting DEBUG logging. 2016-05-10 18:06:35,855 attempt 1/10 2016-05-10 18:06:35,855 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/7fc6b24beea4?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-10 18:06:36,219 unpacking tar archive at: fx-team-7fc6b24beea4/testing/mozharness/ program finished with exit code 0 elapsedTime=0.649148 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 18:06:36.443810) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-10 18:06:36.444117) ========= script_repo_revision: 7fc6b24beea495728bc5070a5b621b31e403b82d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-10 18:06:36.444519) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-10 18:06:36.444801) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-10 18:06:36.453833) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2016-05-10 18:06:36.454239) ========= /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=aa52b8aba932b19cc50b5275000001de-1462927875.699878-1663118001 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:06:36 INFO - MultiFileLogger online at 20160510 18:06:36 in /builds/slave/test 18:06:36 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 18:06:36 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:06:36 INFO - {'append_to_log': False, 18:06:36 INFO - 'base_work_dir': '/builds/slave/test', 18:06:36 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 18:06:36 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:06:36 INFO - 'branch': 'Fx-Team-Non-PGO', 18:06:36 INFO - 'buildbot_json_path': 'buildprops.json', 18:06:36 INFO - 'config_files': ('talos/linux_config.py',), 18:06:36 INFO - 'default_actions': ('clobber', 18:06:36 INFO - 'read-buildbot-config', 18:06:36 INFO - 'download-and-extract', 18:06:36 INFO - 'populate-webroot', 18:06:36 INFO - 'create-virtualenv', 18:06:36 INFO - 'install', 18:06:36 INFO - 'run-tests'), 18:06:36 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:06:36 INFO - 'download_minidump_stackwalk': True, 18:06:36 INFO - 'download_symbols': 'ondemand', 18:06:36 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:06:36 INFO - 'tooltool.py': '/tools/tooltool.py', 18:06:36 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:06:36 INFO - '/tools/misc-python/virtualenv.py')}, 18:06:36 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:06:36 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:06:36 INFO - 'installer_path': 'installer.exe', 18:06:36 INFO - 'log_level': 'info', 18:06:36 INFO - 'log_name': 'talos', 18:06:36 INFO - 'log_to_console': True, 18:06:36 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:06:36 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:06:36 INFO - 'opt_config_files': (), 18:06:36 INFO - 'pip_index': False, 18:06:36 INFO - 'sps_profile': False, 18:06:36 INFO - 'sps_profile_interval': 0, 18:06:36 INFO - 'suite': 'dromaeojs', 18:06:36 INFO - 'system_bits': '32', 18:06:36 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:06:36 INFO - 'title': 'talos-linux64-ix-009', 18:06:36 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:06:36 INFO - 'use_talos_json': True, 18:06:36 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:06:36 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:06:36 INFO - 'work_dir': 'build'} 18:06:36 INFO - ##### 18:06:36 INFO - ##### Running clobber step. 18:06:36 INFO - ##### 18:06:36 INFO - Running pre-action listener: _resource_record_pre_action 18:06:36 INFO - Running main action method: clobber 18:06:36 INFO - rmtree: /builds/slave/test/build 18:06:36 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:06:37 INFO - Running post-action listener: _resource_record_post_action 18:06:37 INFO - ##### 18:06:37 INFO - ##### Finished clobber step (success) 18:06:37 INFO - ##### 18:06:37 INFO - ##### 18:06:37 INFO - ##### Running read-buildbot-config step. 18:06:37 INFO - ##### 18:06:37 INFO - Running pre-action listener: _resource_record_pre_action 18:06:37 INFO - Running main action method: read_buildbot_config 18:06:37 INFO - Using buildbot properties: 18:06:37 INFO - { 18:06:37 INFO - "project": "", 18:06:37 INFO - "product": "firefox", 18:06:37 INFO - "script_repo_revision": "production", 18:06:37 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 18:06:37 INFO - "repository": "", 18:06:37 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs", 18:06:37 INFO - "buildid": "20160510174715", 18:06:37 INFO - "pgo_build": "False", 18:06:37 INFO - "basedir": "/builds/slave/test", 18:06:37 INFO - "buildnumber": 2957, 18:06:37 INFO - "slavename": "talos-linux64-ix-009", 18:06:37 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 18:06:37 INFO - "platform": "ubuntu64_hw", 18:06:37 INFO - "scheduler": "tests-fx-team-linux64-talos", 18:06:37 INFO - "branch": "fx-team", 18:06:37 INFO - "repo_path": "integration/fx-team", 18:06:37 INFO - "revision": "7fc6b24beea495728bc5070a5b621b31e403b82d", 18:06:37 INFO - "stage_platform": "linux64", 18:06:37 INFO - "builduid": "b1db30ce115e4403ad2a90eb4ace112c", 18:06:37 INFO - "slavebuilddir": "test" 18:06:37 INFO - } 18:06:37 INFO - Found installer url https://queue.taskcluster.net/v1/task/e7E-Wzd0TLKoFsJ1WLPbvw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 18:06:37 INFO - Running post-action listener: _resource_record_post_action 18:06:37 INFO - ##### 18:06:37 INFO - ##### Finished read-buildbot-config step (success) 18:06:37 INFO - ##### 18:06:37 INFO - ##### 18:06:37 INFO - ##### Running download-and-extract step. 18:06:37 INFO - ##### 18:06:37 INFO - Running pre-action listener: _resource_record_pre_action 18:06:37 INFO - Running main action method: download_and_extract 18:06:37 INFO - mkdir: /builds/slave/test/build/tests 18:06:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:06:37 INFO - https://queue.taskcluster.net/v1/task/e7E-Wzd0TLKoFsJ1WLPbvw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 18:06:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e7E-Wzd0TLKoFsJ1WLPbvw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 18:06:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e7E-Wzd0TLKoFsJ1WLPbvw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 18:06:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e7E-Wzd0TLKoFsJ1WLPbvw/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 18:06:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e7E-Wzd0TLKoFsJ1WLPbvw/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 18:06:38 INFO - Downloaded 1361 bytes. 18:06:38 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 18:06:38 INFO - Using the following test package requirements: 18:06:38 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 18:06:38 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 18:06:38 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 18:06:38 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 18:06:38 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 18:06:38 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 18:06:38 INFO - u'jsshell-linux-x86_64.zip'], 18:06:38 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 18:06:38 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 18:06:38 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 18:06:38 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 18:06:38 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 18:06:38 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 18:06:38 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 18:06:38 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 18:06:38 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 18:06:38 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 18:06:38 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 18:06:38 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 18:06:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:06:38 INFO - https://queue.taskcluster.net/v1/task/e7E-Wzd0TLKoFsJ1WLPbvw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 18:06:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e7E-Wzd0TLKoFsJ1WLPbvw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 18:06:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e7E-Wzd0TLKoFsJ1WLPbvw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 18:06:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e7E-Wzd0TLKoFsJ1WLPbvw/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 18:06:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e7E-Wzd0TLKoFsJ1WLPbvw/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 18:06:42 INFO - Downloaded 22329481 bytes. 18:06:42 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'] 18:06:42 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 18:06:43 INFO - Return code: 0 18:06:43 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 18:06: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')]} 18:06:43 INFO - https://queue.taskcluster.net/v1/task/e7E-Wzd0TLKoFsJ1WLPbvw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 18:06:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e7E-Wzd0TLKoFsJ1WLPbvw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 18:06:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e7E-Wzd0TLKoFsJ1WLPbvw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 18:06:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e7E-Wzd0TLKoFsJ1WLPbvw/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 18:06:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e7E-Wzd0TLKoFsJ1WLPbvw/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 18:06:46 INFO - Downloaded 22329481 bytes. 18:06:46 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 18:06:46 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 18:06:48 INFO - Return code: 0 18:06: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')]} 18:06:48 INFO - https://queue.taskcluster.net/v1/task/e7E-Wzd0TLKoFsJ1WLPbvw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 18:06:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e7E-Wzd0TLKoFsJ1WLPbvw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 18:06:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e7E-Wzd0TLKoFsJ1WLPbvw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 18:06:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e7E-Wzd0TLKoFsJ1WLPbvw/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 18:06:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e7E-Wzd0TLKoFsJ1WLPbvw/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 18:06:48 INFO - Downloaded 10840152 bytes. 18:06:48 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 18:06:48 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 18:06:49 INFO - Return code: 0 18:06:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:06:49 INFO - https://queue.taskcluster.net/v1/task/e7E-Wzd0TLKoFsJ1WLPbvw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 18:06:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e7E-Wzd0TLKoFsJ1WLPbvw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 18:06:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e7E-Wzd0TLKoFsJ1WLPbvw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 18:06:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e7E-Wzd0TLKoFsJ1WLPbvw/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 18:06:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e7E-Wzd0TLKoFsJ1WLPbvw/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 18:06:53 INFO - Downloaded 54677196 bytes. 18:06:53 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/e7E-Wzd0TLKoFsJ1WLPbvw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 18:06:53 INFO - mkdir: /builds/slave/test/properties 18:06:53 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:06:53 INFO - Writing to file /builds/slave/test/properties/build_url 18:06:53 INFO - Contents: 18:06:53 INFO - build_url:https://queue.taskcluster.net/v1/task/e7E-Wzd0TLKoFsJ1WLPbvw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 18:06:54 INFO - Running post-action listener: _resource_record_post_action 18:06:54 INFO - Running post-action listener: set_extra_try_arguments 18:06:54 INFO - ##### 18:06:54 INFO - ##### Finished download-and-extract step (success) 18:06:54 INFO - ##### 18:06:54 INFO - ##### 18:06:54 INFO - ##### Running populate-webroot step. 18:06:54 INFO - ##### 18:06:54 INFO - Running pre-action listener: _resource_record_pre_action 18:06:54 INFO - Running main action method: populate_webroot 18:06:54 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:06:54 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:06:54 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:06:54 INFO - '--tppagecycles', 18:06:54 INFO - '7'], 18:06:54 INFO - 'tests': ['tsvgm']}}, 18:06:54 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:06:54 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:06:54 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:06:54 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:06:54 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:06:54 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:06:54 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:06:54 INFO - 'talos_options': ['--disable-e10s'], 18:06:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:06:54 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:06:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:06:54 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:06:54 INFO - 'talos_options': ['--disable-e10s'], 18:06:54 INFO - 'tests': ['damp', 'tps']}, 18:06:54 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:06:54 INFO - 'tests': ['damp', 'tps']}, 18:06:54 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:06:54 INFO - 'tests': ['dromaeo_dom']}, 18:06:54 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:06:54 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:06:54 INFO - 'tests': ['a11yr', 18:06:54 INFO - 'ts_paint', 18:06:54 INFO - 'tpaint', 18:06:54 INFO - 'sessionrestore', 18:06:54 INFO - 'sessionrestore_no_auto_restore', 18:06:54 INFO - 'tabpaint']}, 18:06:54 INFO - 'other-e10s': {'tests': ['a11yr', 18:06:54 INFO - 'ts_paint', 18:06:54 INFO - 'tpaint', 18:06:54 INFO - 'sessionrestore', 18:06:54 INFO - 'sessionrestore_no_auto_restore', 18:06:54 INFO - 'tabpaint']}, 18:06:54 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:06:54 INFO - 'ts_paint', 18:06:54 INFO - 'tpaint', 18:06:54 INFO - 'sessionrestore', 18:06:54 INFO - 'sessionrestore_no_auto_restore', 18:06:54 INFO - 'tabpaint']}, 18:06:54 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:06:54 INFO - 'ts_paint', 18:06:54 INFO - 'tpaint', 18:06:54 INFO - 'sessionrestore', 18:06:54 INFO - 'sessionrestore_no_auto_restore', 18:06:54 INFO - 'tabpaint']}, 18:06:54 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:06:54 INFO - 'tests': ['a11yr', 18:06:54 INFO - 'ts_paint', 18:06:54 INFO - 'tpaint', 18:06:54 INFO - 'sessionrestore', 18:06:54 INFO - 'sessionrestore_no_auto_restore', 18:06:54 INFO - 'tabpaint']}, 18:06:54 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:06:54 INFO - 'tests': ['a11yr', 18:06:54 INFO - 'ts_paint', 18:06:54 INFO - 'tpaint', 18:06:54 INFO - 'sessionrestore', 18:06:54 INFO - 'sessionrestore_no_auto_restore', 18:06:54 INFO - 'tabpaint']}, 18:06:54 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:06:54 INFO - 'tests': ['tsvgx', 18:06:54 INFO - 'tsvgr_opacity', 18:06:54 INFO - 'tart', 18:06:54 INFO - 'tscrollx', 18:06:54 INFO - 'cart']}, 18:06:54 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:06:54 INFO - 'tsvgr_opacity', 18:06:54 INFO - 'tart', 18:06:54 INFO - 'tscrollx', 18:06:54 INFO - 'cart']}, 18:06:54 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:06:54 INFO - 'talos_options': ['--disable-e10s'], 18:06:54 INFO - 'tests': ['tp5o']}, 18:06:54 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:06:54 INFO - 'tests': ['tp5o']}, 18:06:54 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:06:54 INFO - 'talos_options': ['--disable-e10s', 18:06:54 INFO - '--xperf_path', 18:06:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:06:54 INFO - 'tests': ['tp5n']}, 18:06:54 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:06:54 INFO - 'talos_options': ['--xperf_path', 18:06:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:06:54 INFO - 'tests': ['tp5n']}}, 18:06:54 INFO - 'talos.zip': {'path': '', 18:06:54 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:06:54 INFO - Running post-action listener: _resource_record_post_action 18:06:54 INFO - ##### 18:06:54 INFO - ##### Finished populate-webroot step (success) 18:06:54 INFO - ##### 18:06:54 INFO - ##### 18:06:54 INFO - ##### Running create-virtualenv step. 18:06:54 INFO - ##### 18:06:54 INFO - Running pre-action listener: _resource_record_pre_action 18:06:54 INFO - Running main action method: create_virtualenv 18:06:54 INFO - Creating virtualenv /builds/slave/test/build/venv 18:06:54 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 18:06:54 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:06:54 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:06:54 INFO - Using real prefix '/usr' 18:06:54 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:06:54 INFO - Installing distribute.............................................................................................................................................................................................done. 18:06:59 INFO - Installing pip.................done. 18:06:59 INFO - Return code: 0 18:06:59 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:06:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:06:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:06:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:06:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:06:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:06:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:06:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f62fdeeff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11808a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x124b770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12da9c0>, '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 0x12db8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12dbd50>, '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': 'aa52b8aba932b19cc50b5275000001de-1462927875.699878-1663118001', '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 18:06:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 18:06:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 18:06:59 INFO - Using env: {'DISPLAY': ':0', 18:06:59 INFO - 'HOME': '/home/cltbld', 18:06:59 INFO - 'LANG': 'en_US.UTF-8', 18:06:59 INFO - 'LANGUAGE': 'en_US:en', 18:06:59 INFO - 'LOGNAME': 'cltbld', 18:06:59 INFO - 'MAIL': '/var/mail/cltbld', 18:06:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:06:59 INFO - 'MOZ_NO_REMOTE': '1', 18:06:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:06:59 INFO - 'NO_EM_RESTART': '1', 18:06:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:06:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:06:59 INFO - 'PWD': '/builds/slave/test', 18:06:59 INFO - 'SHELL': '/bin/bash', 18:06:59 INFO - 'SHLVL': '1', 18:06:59 INFO - 'TERM': 'linux', 18:06:59 INFO - 'TMOUT': '86400', 18:06:59 INFO - 'USER': 'cltbld', 18:06:59 INFO - 'XDG_SESSION_COOKIE': 'aa52b8aba932b19cc50b5275000001de-1462927875.699878-1663118001', 18:06:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:06:59 INFO - '_': '/tools/buildbot/bin/python'} 18:06:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:06:59 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 18:06:59 INFO - Cleaning up... 18:06:59 INFO - Return code: 0 18:06:59 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 18:06:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:06:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:06:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:06:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:06:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:06:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:06:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f62fdeeff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11808a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x124b770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12da9c0>, '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 0x12db8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12dbd50>, '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': 'aa52b8aba932b19cc50b5275000001de-1462927875.699878-1663118001', '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 18:06:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 18:06:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 18:06:59 INFO - Using env: {'DISPLAY': ':0', 18:06:59 INFO - 'HOME': '/home/cltbld', 18:06:59 INFO - 'LANG': 'en_US.UTF-8', 18:06:59 INFO - 'LANGUAGE': 'en_US:en', 18:06:59 INFO - 'LOGNAME': 'cltbld', 18:06:59 INFO - 'MAIL': '/var/mail/cltbld', 18:06:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:06:59 INFO - 'MOZ_NO_REMOTE': '1', 18:06:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:06:59 INFO - 'NO_EM_RESTART': '1', 18:06:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:06:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:06:59 INFO - 'PWD': '/builds/slave/test', 18:06:59 INFO - 'SHELL': '/bin/bash', 18:06:59 INFO - 'SHLVL': '1', 18:06:59 INFO - 'TERM': 'linux', 18:06:59 INFO - 'TMOUT': '86400', 18:06:59 INFO - 'USER': 'cltbld', 18:06:59 INFO - 'XDG_SESSION_COOKIE': 'aa52b8aba932b19cc50b5275000001de-1462927875.699878-1663118001', 18:06:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:06:59 INFO - '_': '/tools/buildbot/bin/python'} 18:06:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:06:59 INFO - Downloading/unpacking psutil>=0.7.1 18:06: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 18:06: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 18:06: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 18:06: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 18:06: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 18:06: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 18:07:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:07:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:07:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:07:01 INFO - Installing collected packages: psutil 18:07:01 INFO - Running setup.py install for psutil 18:07:01 INFO - building 'psutil._psutil_linux' extension 18:07:01 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 18:07:01 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 18:07:01 INFO - building 'psutil._psutil_posix' extension 18:07:01 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 18:07:01 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 18:07:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:07:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:07:02 INFO - Successfully installed psutil 18:07:02 INFO - Cleaning up... 18:07:02 INFO - Return code: 0 18:07:02 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 18:07:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:07:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:07:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:07:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:07:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:07:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:07:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f62fdeeff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11808a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x124b770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12da9c0>, '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 0x12db8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12dbd50>, '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': 'aa52b8aba932b19cc50b5275000001de-1462927875.699878-1663118001', '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 18:07:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 18:07:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 18:07:02 INFO - Using env: {'DISPLAY': ':0', 18:07:02 INFO - 'HOME': '/home/cltbld', 18:07:02 INFO - 'LANG': 'en_US.UTF-8', 18:07:02 INFO - 'LANGUAGE': 'en_US:en', 18:07:02 INFO - 'LOGNAME': 'cltbld', 18:07:02 INFO - 'MAIL': '/var/mail/cltbld', 18:07:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:07:02 INFO - 'MOZ_NO_REMOTE': '1', 18:07:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:07:02 INFO - 'NO_EM_RESTART': '1', 18:07:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:07:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:07:02 INFO - 'PWD': '/builds/slave/test', 18:07:02 INFO - 'SHELL': '/bin/bash', 18:07:02 INFO - 'SHLVL': '1', 18:07:02 INFO - 'TERM': 'linux', 18:07:02 INFO - 'TMOUT': '86400', 18:07:02 INFO - 'USER': 'cltbld', 18:07:02 INFO - 'XDG_SESSION_COOKIE': 'aa52b8aba932b19cc50b5275000001de-1462927875.699878-1663118001', 18:07:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:07:02 INFO - '_': '/tools/buildbot/bin/python'} 18:07:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:07:02 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 18:07:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:07:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:07:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:07:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:07:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:07:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:07:04 INFO - Downloading mozsystemmonitor-0.0.tar.gz 18:07:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:07:04 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 18:07:04 INFO - Installing collected packages: mozsystemmonitor 18:07:04 INFO - Running setup.py install for mozsystemmonitor 18:07:04 INFO - Successfully installed mozsystemmonitor 18:07:04 INFO - Cleaning up... 18:07:04 INFO - Return code: 0 18:07:04 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:07:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:07:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:07:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:07:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:07:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:07:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:07:04 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 0x7f62fdeeff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11808a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x124b770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12da9c0>, '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 0x12db8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12dbd50>, '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': 'aa52b8aba932b19cc50b5275000001de-1462927875.699878-1663118001', '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 18:07:04 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 18:07:04 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 18:07:04 INFO - Using env: {'DISPLAY': ':0', 18:07:04 INFO - 'HOME': '/home/cltbld', 18:07:04 INFO - 'LANG': 'en_US.UTF-8', 18:07:04 INFO - 'LANGUAGE': 'en_US:en', 18:07:04 INFO - 'LOGNAME': 'cltbld', 18:07:04 INFO - 'MAIL': '/var/mail/cltbld', 18:07:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:07:04 INFO - 'MOZ_NO_REMOTE': '1', 18:07:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:07:04 INFO - 'NO_EM_RESTART': '1', 18:07:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:07:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:07:04 INFO - 'PWD': '/builds/slave/test', 18:07:04 INFO - 'SHELL': '/bin/bash', 18:07:04 INFO - 'SHLVL': '1', 18:07:04 INFO - 'TERM': 'linux', 18:07:04 INFO - 'TMOUT': '86400', 18:07:04 INFO - 'USER': 'cltbld', 18:07:04 INFO - 'XDG_SESSION_COOKIE': 'aa52b8aba932b19cc50b5275000001de-1462927875.699878-1663118001', 18:07:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:07:04 INFO - '_': '/tools/buildbot/bin/python'} 18:07:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:07:04 INFO - Downloading/unpacking blobuploader==1.2.4 18:07:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:07:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:07:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:07:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:07:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:07:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:07:06 INFO - Downloading blobuploader-1.2.4.tar.gz 18:07:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:07:06 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:07:06 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 18:07:06 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 18:07:06 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 18:07:06 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 18:07:06 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 18:07:06 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 18:07:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:07:06 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:07:06 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 18:07:06 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 18:07:06 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 18:07:06 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 18:07:06 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 18:07:06 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 18:07:07 INFO - Downloading docopt-0.6.1.tar.gz 18:07:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:07:07 INFO - Installing collected packages: blobuploader, requests, docopt 18:07:07 INFO - Running setup.py install for blobuploader 18:07:07 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:07:07 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:07:07 INFO - Running setup.py install for requests 18:07:07 INFO - Running setup.py install for docopt 18:07:07 INFO - Successfully installed blobuploader requests docopt 18:07:07 INFO - Cleaning up... 18:07:07 INFO - Return code: 0 18:07:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:07:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:07:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:07:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:07:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:07:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:07:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:07:07 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 0x7f62fdeeff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11808a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x124b770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12da9c0>, '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 0x12db8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12dbd50>, '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': 'aa52b8aba932b19cc50b5275000001de-1462927875.699878-1663118001', '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 18:07:07 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 18:07:07 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 18:07:07 INFO - Using env: {'DISPLAY': ':0', 18:07:07 INFO - 'HOME': '/home/cltbld', 18:07:07 INFO - 'LANG': 'en_US.UTF-8', 18:07:07 INFO - 'LANGUAGE': 'en_US:en', 18:07:07 INFO - 'LOGNAME': 'cltbld', 18:07:07 INFO - 'MAIL': '/var/mail/cltbld', 18:07:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:07:07 INFO - 'MOZ_NO_REMOTE': '1', 18:07:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:07:07 INFO - 'NO_EM_RESTART': '1', 18:07:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:07:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:07:07 INFO - 'PWD': '/builds/slave/test', 18:07:07 INFO - 'SHELL': '/bin/bash', 18:07:07 INFO - 'SHLVL': '1', 18:07:07 INFO - 'TERM': 'linux', 18:07:07 INFO - 'TMOUT': '86400', 18:07:07 INFO - 'USER': 'cltbld', 18:07:07 INFO - 'XDG_SESSION_COOKIE': 'aa52b8aba932b19cc50b5275000001de-1462927875.699878-1663118001', 18:07:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:07:07 INFO - '_': '/tools/buildbot/bin/python'} 18:07:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:07:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:07:07 INFO - Running setup.py (path:/tmp/pip-e4FS_C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:07:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:07:08 INFO - Running setup.py (path:/tmp/pip-0t12GO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:07:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:07:08 INFO - Running setup.py (path:/tmp/pip-tD3L96-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:07:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:07:08 INFO - Running setup.py (path:/tmp/pip-gjIDl8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:07:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:07:08 INFO - Running setup.py (path:/tmp/pip-VJc2MP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:07:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:07:08 INFO - Running setup.py (path:/tmp/pip-gA2yuE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:07:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:07:08 INFO - Running setup.py (path:/tmp/pip-Dgu9ll-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:07:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:07:08 INFO - Running setup.py (path:/tmp/pip-WKPa65-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:07:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:07:08 INFO - Running setup.py (path:/tmp/pip-ZZThWZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:07:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:07:08 INFO - Running setup.py (path:/tmp/pip-mo8NLB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:07:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:07:08 INFO - Running setup.py (path:/tmp/pip-rT_AuN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:07:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:07:08 INFO - Running setup.py (path:/tmp/pip-2DCV4H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:07:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:07:08 INFO - Running setup.py (path:/tmp/pip-jT51hz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:07:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:07:08 INFO - Running setup.py (path:/tmp/pip-CTvPEH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:07:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:07:08 INFO - Running setup.py (path:/tmp/pip-kp9nOU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:07:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:07:09 INFO - Running setup.py (path:/tmp/pip-_7g45y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:07:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:07:09 INFO - Running setup.py (path:/tmp/pip-sDmSYW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:07:09 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:07:09 INFO - Running setup.py install for manifestparser 18:07:09 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:07:09 INFO - Running setup.py install for mozcrash 18:07:09 INFO - Running setup.py install for mozdebug 18:07:09 INFO - Running setup.py install for mozdevice 18:07:09 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:07:09 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:07:09 INFO - Running setup.py install for mozfile 18:07:09 INFO - Running setup.py install for mozhttpd 18:07:09 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:07:09 INFO - Running setup.py install for mozinfo 18:07:09 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:07:09 INFO - Running setup.py install for mozInstall 18:07:09 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:07:09 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:07:09 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:07:09 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:07:09 INFO - Running setup.py install for mozleak 18:07:10 INFO - Running setup.py install for mozlog 18:07:10 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:07:10 INFO - Running setup.py install for moznetwork 18:07:10 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:07:10 INFO - Running setup.py install for mozprocess 18:07:10 INFO - Running setup.py install for mozprofile 18:07:10 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:07:10 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:07:10 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:07:10 INFO - Running setup.py install for mozrunner 18:07:10 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:07:10 INFO - Running setup.py install for mozscreenshot 18:07:10 INFO - Running setup.py install for moztest 18:07:10 INFO - Running setup.py install for mozversion 18:07:10 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:07:10 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:07:10 INFO - Cleaning up... 18:07:10 INFO - Return code: 0 18:07:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:07:10 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')]} 18:07:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:07:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:07:10 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')]} 18:07:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:07:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:07:10 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 0x7f62fdeeff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11808a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x124b770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12da9c0>, '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 0x12db8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12dbd50>, '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': 'aa52b8aba932b19cc50b5275000001de-1462927875.699878-1663118001', '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 18:07:10 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 18:07:10 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 18:07:10 INFO - Using env: {'DISPLAY': ':0', 18:07:10 INFO - 'HOME': '/home/cltbld', 18:07:10 INFO - 'LANG': 'en_US.UTF-8', 18:07:10 INFO - 'LANGUAGE': 'en_US:en', 18:07:10 INFO - 'LOGNAME': 'cltbld', 18:07:10 INFO - 'MAIL': '/var/mail/cltbld', 18:07:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:07:10 INFO - 'MOZ_NO_REMOTE': '1', 18:07:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:07:10 INFO - 'NO_EM_RESTART': '1', 18:07:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:07:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:07:10 INFO - 'PWD': '/builds/slave/test', 18:07:10 INFO - 'SHELL': '/bin/bash', 18:07:10 INFO - 'SHLVL': '1', 18:07:10 INFO - 'TERM': 'linux', 18:07:10 INFO - 'TMOUT': '86400', 18:07:10 INFO - 'USER': 'cltbld', 18:07:10 INFO - 'XDG_SESSION_COOKIE': 'aa52b8aba932b19cc50b5275000001de-1462927875.699878-1663118001', 18:07:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:07:10 INFO - '_': '/tools/buildbot/bin/python'} 18:07:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:07:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:07:11 INFO - Running setup.py (path:/tmp/pip-rlyXY1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:07:11 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 18:07:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:07:11 INFO - Running setup.py (path:/tmp/pip-QvSCGB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:07:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:07:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:07:11 INFO - Running setup.py (path:/tmp/pip-5X1398-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:07:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 18:07:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:07:11 INFO - Running setup.py (path:/tmp/pip-CjnsKw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:07:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:07:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:07:11 INFO - Running setup.py (path:/tmp/pip-uhTh6R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:07:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 18:07:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:07:11 INFO - Running setup.py (path:/tmp/pip-cuqqKv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:07:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 18:07:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:07:11 INFO - Running setup.py (path:/tmp/pip-Xhzvg3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:07:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 18:07:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:07:11 INFO - Running setup.py (path:/tmp/pip-VgN74I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:07:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 18:07:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:07:11 INFO - Running setup.py (path:/tmp/pip-c1DJ1T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:07:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 18:07:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:07:11 INFO - Running setup.py (path:/tmp/pip-cU_7Vg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:07:11 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)) 18:07:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:07:11 INFO - Running setup.py (path:/tmp/pip-HtqBJs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:07:11 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 18:07:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:07:11 INFO - Running setup.py (path:/tmp/pip-ays7oZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:07:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 18:07:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:07:12 INFO - Running setup.py (path:/tmp/pip-LcnuiW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:07:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 18:07:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:07:12 INFO - Running setup.py (path:/tmp/pip-FHctWi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:07:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 18:07:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:07:12 INFO - Running setup.py (path:/tmp/pip-9kwVMn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:07:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 18:07:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:07:12 INFO - Running setup.py (path:/tmp/pip-jS8sa4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:07:12 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 18:07:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:07:12 INFO - Running setup.py (path:/tmp/pip-cj0Xe0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:07:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 18:07:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:07:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:07:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 18:07:12 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:07:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:07:12 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:07:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:07:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:07:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:07:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:07:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:07:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:07:14 INFO - Downloading blessings-1.6.tar.gz 18:07:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:07:14 INFO - Installing collected packages: blessings 18:07:14 INFO - Running setup.py install for blessings 18:07:14 INFO - Successfully installed blessings 18:07:14 INFO - Cleaning up... 18:07:14 INFO - Return code: 0 18:07:14 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:07:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:07:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:07:14 INFO - Reading from file tmpfile_stdout 18:07:14 INFO - Current package versions: 18:07:14 INFO - argparse == 1.2.1 18:07:14 INFO - blessings == 1.6 18:07:14 INFO - blobuploader == 1.2.4 18:07:14 INFO - docopt == 0.6.1 18:07:14 INFO - manifestparser == 1.1 18:07:14 INFO - mozInstall == 1.12 18:07:14 INFO - mozcrash == 0.17 18:07:14 INFO - mozdebug == 0.1 18:07:14 INFO - mozdevice == 0.48 18:07:14 INFO - mozfile == 1.2 18:07:14 INFO - mozhttpd == 0.7 18:07:14 INFO - mozinfo == 0.9 18:07:14 INFO - mozleak == 0.1 18:07:14 INFO - mozlog == 3.1 18:07:14 INFO - moznetwork == 0.27 18:07:14 INFO - mozprocess == 0.22 18:07:14 INFO - mozprofile == 0.28 18:07:14 INFO - mozrunner == 6.11 18:07:14 INFO - mozscreenshot == 0.1 18:07:14 INFO - mozsystemmonitor == 0.0 18:07:14 INFO - moztest == 0.7 18:07:14 INFO - mozversion == 1.4 18:07:14 INFO - psutil == 3.1.1 18:07:14 INFO - requests == 1.2.3 18:07:14 INFO - wsgiref == 0.1.2 18:07:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:07:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:07:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:07:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:07:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:07:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:07:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:07:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f62fdeeff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11808a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x124b770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12da9c0>, '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 0x12db8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12dbd50>, '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': 'aa52b8aba932b19cc50b5275000001de-1462927875.699878-1663118001', '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 18:07:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 18:07:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:07:14 INFO - Using env: {'DISPLAY': ':0', 18:07:14 INFO - 'HOME': '/home/cltbld', 18:07:14 INFO - 'LANG': 'en_US.UTF-8', 18:07:14 INFO - 'LANGUAGE': 'en_US:en', 18:07:14 INFO - 'LOGNAME': 'cltbld', 18:07:14 INFO - 'MAIL': '/var/mail/cltbld', 18:07:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:07:14 INFO - 'MOZ_NO_REMOTE': '1', 18:07:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:07:14 INFO - 'NO_EM_RESTART': '1', 18:07:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:07:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:07:14 INFO - 'PWD': '/builds/slave/test', 18:07:14 INFO - 'SHELL': '/bin/bash', 18:07:14 INFO - 'SHLVL': '1', 18:07:14 INFO - 'TERM': 'linux', 18:07:14 INFO - 'TMOUT': '86400', 18:07:14 INFO - 'USER': 'cltbld', 18:07:14 INFO - 'XDG_SESSION_COOKIE': 'aa52b8aba932b19cc50b5275000001de-1462927875.699878-1663118001', 18:07:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:07:14 INFO - '_': '/tools/buildbot/bin/python'} 18:07:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:07:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 18:07:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 18:07:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 18:07:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 18:07:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 18:07:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 18:07:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 18:07:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 18:07:14 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 18:07:14 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 18:07:14 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 18:07:14 INFO - Cleaning up... 18:07:14 INFO - Return code: 0 18:07:14 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:07:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:07:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:07:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:07:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:07:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:07:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:07:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f62fdeeff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11808a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x124b770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12da9c0>, '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 0x12db8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12dbd50>, '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': 'aa52b8aba932b19cc50b5275000001de-1462927875.699878-1663118001', '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 18:07:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 18:07:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 18:07:14 INFO - Using env: {'DISPLAY': ':0', 18:07:14 INFO - 'HOME': '/home/cltbld', 18:07:14 INFO - 'LANG': 'en_US.UTF-8', 18:07:14 INFO - 'LANGUAGE': 'en_US:en', 18:07:14 INFO - 'LOGNAME': 'cltbld', 18:07:14 INFO - 'MAIL': '/var/mail/cltbld', 18:07:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:07:14 INFO - 'MOZ_NO_REMOTE': '1', 18:07:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:07:14 INFO - 'NO_EM_RESTART': '1', 18:07:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:07:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:07:14 INFO - 'PWD': '/builds/slave/test', 18:07:14 INFO - 'SHELL': '/bin/bash', 18:07:14 INFO - 'SHLVL': '1', 18:07:14 INFO - 'TERM': 'linux', 18:07:14 INFO - 'TMOUT': '86400', 18:07:14 INFO - 'USER': 'cltbld', 18:07:14 INFO - 'XDG_SESSION_COOKIE': 'aa52b8aba932b19cc50b5275000001de-1462927875.699878-1663118001', 18:07:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:07:14 INFO - '_': '/tools/buildbot/bin/python'} 18:07:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:07:15 INFO - Downloading/unpacking jsonschema 18:07:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:07:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:07:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:07:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:07:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:07:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:07:17 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:07:17 INFO - Downloading/unpacking functools32 (from jsonschema) 18:07:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:07:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:07:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:07:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:07:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:07:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:07:17 INFO - Downloading functools32-3.2.3-2.tar.gz 18:07:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:07:17 INFO - warning: no files found matching '*.txt' 18:07:17 INFO - no previously-included directories found matching 'build' 18:07:17 INFO - no previously-included directories found matching 'dist' 18:07:17 INFO - no previously-included directories found matching '.git*' 18:07:17 INFO - Installing collected packages: jsonschema, functools32 18:07:17 INFO - Running setup.py install for functools32 18:07:17 INFO - warning: no files found matching '*.txt' 18:07:17 INFO - no previously-included directories found matching 'build' 18:07:17 INFO - no previously-included directories found matching 'dist' 18:07:17 INFO - no previously-included directories found matching '.git*' 18:07:17 INFO - Successfully installed jsonschema functools32 18:07:17 INFO - Cleaning up... 18:07:17 INFO - Return code: 0 18:07:17 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 18:07:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:07:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:07:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:07:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:07:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:07:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:07:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f62fdeeff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11808a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x124b770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12da9c0>, '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 0x12db8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12dbd50>, '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': 'aa52b8aba932b19cc50b5275000001de-1462927875.699878-1663118001', '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 18:07:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 18:07:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 18:07:17 INFO - Using env: {'DISPLAY': ':0', 18:07:17 INFO - 'HOME': '/home/cltbld', 18:07:17 INFO - 'LANG': 'en_US.UTF-8', 18:07:17 INFO - 'LANGUAGE': 'en_US:en', 18:07:17 INFO - 'LOGNAME': 'cltbld', 18:07:17 INFO - 'MAIL': '/var/mail/cltbld', 18:07:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:07:17 INFO - 'MOZ_NO_REMOTE': '1', 18:07:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:07:17 INFO - 'NO_EM_RESTART': '1', 18:07:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:07:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:07:17 INFO - 'PWD': '/builds/slave/test', 18:07:17 INFO - 'SHELL': '/bin/bash', 18:07:17 INFO - 'SHLVL': '1', 18:07:17 INFO - 'TERM': 'linux', 18:07:17 INFO - 'TMOUT': '86400', 18:07:17 INFO - 'USER': 'cltbld', 18:07:17 INFO - 'XDG_SESSION_COOKIE': 'aa52b8aba932b19cc50b5275000001de-1462927875.699878-1663118001', 18:07:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:07:17 INFO - '_': '/tools/buildbot/bin/python'} 18:07:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:07:17 INFO - Downloading/unpacking flake8 18:07:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:07:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:07:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:07:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:07:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:07:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:07:19 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 18:07:19 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 18:07:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:07:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:07:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:07:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:07:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:07:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:07:19 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 18:07:19 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 18:07:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:07:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:07:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:07:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:07:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:07:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:07:20 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 18:07:20 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 18:07:20 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 18:07:20 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 18:07:20 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 18:07:20 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 18:07:20 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 18:07:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 18:07:20 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 18:07:20 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 18:07:20 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 18:07:20 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 18:07:20 INFO - no previously-included directories found matching 'docs/_build' 18:07:20 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 18:07:20 INFO - Running setup.py install for pep8 18:07:20 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 18:07:20 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 18:07:20 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 18:07:20 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 18:07:20 INFO - no previously-included directories found matching 'docs/_build' 18:07:20 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 18:07:20 INFO - Successfully installed flake8 mccabe pyflakes pep8 18:07:20 INFO - Cleaning up... 18:07:20 INFO - Return code: 0 18:07:20 INFO - Running post-action listener: _resource_record_post_action 18:07:20 INFO - Running post-action listener: _start_resource_monitoring 18:07:20 INFO - Starting resource monitoring. 18:07:20 INFO - ##### 18:07:20 INFO - ##### Finished create-virtualenv step (success) 18:07:20 INFO - ##### 18:07:20 INFO - ##### 18:07:20 INFO - ##### Running install step. 18:07:20 INFO - ##### 18:07:20 INFO - Running pre-action listener: _resource_record_pre_action 18:07:20 INFO - Running main action method: install 18:07:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:07:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:07:20 INFO - Reading from file tmpfile_stdout 18:07:20 INFO - Detecting whether we're running mozinstall >=1.0... 18:07:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:07:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:07:20 INFO - Reading from file tmpfile_stdout 18:07:20 INFO - Output received: 18:07:20 INFO - Usage: mozinstall [options] installer 18:07:20 INFO - Options: 18:07:20 INFO - -h, --help show this help message and exit 18:07:20 INFO - -d DEST, --destination=DEST 18:07:20 INFO - Directory to install application into. [default: 18:07:20 INFO - "/builds/slave/test"] 18:07:20 INFO - --app=APP Application being installed. [default: firefox] 18:07:20 INFO - mkdir: /builds/slave/test/build/application 18:07:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 18:07:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 18:07:36 INFO - Reading from file tmpfile_stdout 18:07:36 INFO - Output received: 18:07:36 INFO - /builds/slave/test/build/application/firefox/firefox 18:07:36 INFO - Running post-action listener: _resource_record_post_action 18:07:36 INFO - ##### 18:07:36 INFO - ##### Finished install step (success) 18:07:36 INFO - ##### 18:07:36 INFO - ##### 18:07:36 INFO - ##### Running run-tests step. 18:07:36 INFO - ##### 18:07:36 INFO - Running pre-action listener: _resource_record_pre_action 18:07:36 INFO - Running main action method: run_tests 18:07:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:07:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:07:36 INFO - Python 2.7.3 18:07:36 INFO - Return code: 0 18:07:36 INFO - grabbing minidump binary from tooltool 18:07:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:07:36 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 0x12da9c0>, '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 0x12db8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12dbd50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:07:36 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 18:07:36 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 18:07:36 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 18:07:36 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:07:36 INFO - Return code: 0 18:07:36 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 18:07:36 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:07:36 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:07:36 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 18:07:36 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:07:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 18:07:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 18:07:37 INFO - Return code: 0 18:07:37 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/e7E-Wzd0TLKoFsJ1WLPbvw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-009', '--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 18:07:37 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/e7E-Wzd0TLKoFsJ1WLPbvw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-009 --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 18:07:37 INFO - Using env: {'DISPLAY': ':0', 18:07:37 INFO - 'HOME': '/home/cltbld', 18:07:37 INFO - 'LANG': 'en_US.UTF-8', 18:07:37 INFO - 'LANGUAGE': 'en_US:en', 18:07:37 INFO - 'LOGNAME': 'cltbld', 18:07:37 INFO - 'MAIL': '/var/mail/cltbld', 18:07:37 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:07:37 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:07:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:07:37 INFO - 'MOZ_NO_REMOTE': '1', 18:07:37 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:07:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:07:37 INFO - 'NO_EM_RESTART': '1', 18:07:37 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:07:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:07:37 INFO - 'PWD': '/builds/slave/test', 18:07:37 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:07:37 INFO - 'SHELL': '/bin/bash', 18:07:37 INFO - 'SHLVL': '1', 18:07:37 INFO - 'TERM': 'linux', 18:07:37 INFO - 'TMOUT': '86400', 18:07:37 INFO - 'USER': 'cltbld', 18:07:37 INFO - 'XDG_SESSION_COOKIE': 'aa52b8aba932b19cc50b5275000001de-1462927875.699878-1663118001', 18:07:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:07:37 INFO - '_': '/tools/buildbot/bin/python'} 18:07:37 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/e7E-Wzd0TLKoFsJ1WLPbvw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-009', '--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 18:07:37 INFO - mozversion application_buildid: 20160510174715 18:07:37 INFO - mozversion application_changeset: 7fc6b24beea495728bc5070a5b621b31e403b82d 18:07:37 INFO - mozversion application_display_name: Nightly 18:07:37 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:07:37 INFO - mozversion application_name: Firefox 18:07:37 INFO - mozversion application_remotingname: firefox 18:07:37 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 18:07:37 INFO - mozversion application_vendor: Mozilla 18:07:37 INFO - mozversion application_version: 49.0a1 18:07:37 INFO - mozversion platform_buildid: 20160510174715 18:07:37 INFO - mozversion platform_changeset: 7fc6b24beea495728bc5070a5b621b31e403b82d 18:07:37 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 18:07:37 INFO - mozversion platform_version: 49.0a1 18:07:37 INFO - using testdate: 1462928857 18:07:37 INFO - actual date: 1462928857 18:07:37 INFO - starting webserver on 'localhost:43158' 18:07:37 INFO - SUITE-START | Running 2 tests 18:07:37 INFO - TEST-START | dromaeo_css 18:07:37 INFO - operating with platform_type : linux_ 18:07:37 INFO - Initialising browser for dromaeo_css test... 18:07:37 INFO - TEST-INFO | started process 4246 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpjjaC8u/profile http://localhost:43158/getInfo.html) 18:07:42 INFO - PROCESS | 4246 | __metrics Screen width/height:1600/1200 18:07:42 INFO - PROCESS | 4246 | colorDepth:24 18:07:42 INFO - PROCESS | 4246 | Browser inner width/height: 1024/697 18:07:42 INFO - PROCESS | 4246 | __metrics 18:07:43 ERROR - PROCESS | 4246 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 18:07:44 INFO - TEST-INFO | 4246: exit 0 18:07:44 INFO - Browser initialized. 18:07:44 INFO - Running cycle 1/1 for dromaeo_css test... 18:07:44 INFO - TEST-INFO | started process 4326 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpjjaC8u/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:07:56 INFO - PROCESS | 4326 | RSS: Main: 153706496 18:07:56 INFO - PROCESS | 4326 | 18:08:48 INFO - PROCESS | 4326 | 1462928928167 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] 18:10:59 INFO - PROCESS | 4326 | Cycle 1(1): loaded http://localhost:43158/tests/dromaeo/cssquery-dojo.html (next: http://localhost:43158/tests/dromaeo/cssquery-ext.html) 18:11:01 INFO - PROCESS | 4326 | RSS: Main: 270032896 18:11:01 INFO - PROCESS | 4326 | 18:14:03 INFO - PROCESS | 4326 | Cycle 1(1): loaded http://localhost:43158/tests/dromaeo/cssquery-ext.html (next: http://localhost:43158/tests/dromaeo/cssquery-jquery.html) 18:14:04 INFO - PROCESS | 4326 | RSS: Main: 239144960 18:14:04 INFO - PROCESS | 4326 | 18:17:06 INFO - PROCESS | 4326 | Cycle 1(1): loaded http://localhost:43158/tests/dromaeo/cssquery-jquery.html (next: http://localhost:43158/tests/dromaeo/cssquery-mootools.html) 18:17:07 INFO - PROCESS | 4326 | RSS: Main: 232181760 18:17:07 INFO - PROCESS | 4326 | 18:20:09 INFO - PROCESS | 4326 | Cycle 1(1): loaded http://localhost:43158/tests/dromaeo/cssquery-mootools.html (next: http://localhost:43158/tests/dromaeo/cssquery-prototype.html) 18:20:10 INFO - PROCESS | 4326 | RSS: Main: 230412288 18:20:10 INFO - PROCESS | 4326 | 18:23:13 INFO - PROCESS | 4326 | Cycle 1(1): loaded http://localhost:43158/tests/dromaeo/cssquery-prototype.html (next: http://localhost:43158/tests/dromaeo/cssquery-yui.html) 18:23:13 INFO - PROCESS | 4326 | RSS: Main: 215777280 18:23:13 INFO - PROCESS | 4326 | 18:26:16 INFO - PROCESS | 4326 | Cycle 1(1): loaded http://localhost:43158/tests/dromaeo/cssquery-yui.html (next: http://localhost:43158/tests/dromaeo/cssquery-dojo.html) 18:26:16 INFO - PROCESS | 4326 | RSS: Main: 288186368 18:26:16 INFO - PROCESS | 4326 | 18:26:16 INFO - PROCESS | 4326 | __start_tp_report 18:26:16 INFO - PROCESS | 4326 | _x_x_mozilla_page_load 18:26:16 INFO - PROCESS | 4326 | _x_x_mozilla_page_load_details 18:26:16 INFO - PROCESS | 4326 | |i|pagename|runs| 18:26:16 INFO - PROCESS | 4326 | |0;dojo.html;2643.7091919888817;2677.705452400237;2723.305557082944;2733.617293578899;2734.7948903832216;4737.407824021997;4739.123262196492;4804.111239420709;4840.6369522285795;4870.26945958106;4783.521647835215;4789.425268967729;4791.952080479195;4821.421429428474;4831.734254615994;4378.6934914555995;4419.602235798778;4425.269830477967;4477.888052798689;4489.551044895501;4388.363687265344;4415.249407600716;4435.977820110895;4448.110377924428;4477.10457908418;4263.25393056217;4303.397524346594;4304.31131019037;4363.65090792739;4392.868213953587;4361.084172323815;4381.868543943687;4425.513193548707;4444.066745983348;4445.422095127613;4394.274944634131;4417.18282117806;4430.623397011258;4467.620252278561;4470.776461176928;4519.096180763828;4590.288505281686;4624.075184962987;4639.512851150615;4704.435467743859;5050.570701490377;5062.924056139161;5111.591072714211;5125.256837758541;5128.384593848725;5006.774695138727;5081.59347252219;5098.668586541863;5111.974440127813;5151.253068305112;4823.035392921394;4850.490698476679;4879.024195160982;4926.729029903389;4948.777305021283;5332.5467335276935;5383.43473935239;5400.594955378362;5404.054290499147;5405.21624364465;5035.874103147451;5055.241713742895;5081.5426611605135;5083.161278389099;5086.898262034739;5056.367954005749;5137.049645815536;5159.019786240602;5196.870078247998;5230.267762513251;5171.379434467888;5175.249588809602;5184.274201611777;5190.896182076337;5235.895282094337;4976.825811096594;5149.845504634867;5169.121249387613;5171.3277273954145;5179.715115668599;4212.49450065994;4225.3873188387515;4241.957580424156;4247.532771395144;4252.744835309892;3712.0719820044988;3716.7769933804057;3721.7953012584026;3746.83139258734;3748;4220.345846393814;4234.851780187679;4239.025024244441;4266.1254442839145;4266.2960611499375;5118.488151184926;5171.5862730981435;5185.688858668492;5217.869553261199;5273.288106105627;4363.738175709467;4365.126974605028;4372.147431250939;4378.934315985262;4417.160739459492;4551.362809206714;4673.462551806518;4689.20283551797;4692.788824502837;4744.525547445228;4690.343351930801;4714.976425117852;4736.668433209642;4763.73799441034;4786.8085276588545;5054.064997975386;5060.089183946925;5063.1898896176435;5066.06277838601;5116.71858047796;4693.55411235932;4731.101090792738;4735.573798358164;4738.265568836836;4787.114383839001;4930.926036109389;5072.9746351268;5124.897502050013;5125.513076257749;5156.355455568054;5183.766730497061;5194.246834209094;5201.531862132426;5208.791648334024;5216.061109000417;4747.548982846763;5045.344105266292;5060;5067.41724701664;5067.847964561069;4783.06730187624;4819.277108433763;4829.710217386968;4855.295982082649;4866.124097662455;4780.521947805191;4792.7124372537755;4837.274408838703;4841.4674385816825;4842.878928026827;4901.705897646153;4932.728699921395;4939.135651261086;4955.058538877602;4971.353724015855;4855.975720121377;4913.28757330178;4920.237363208708;4921.581665558468;4934.259861020875 18:26:16 INFO - PROCESS | 4326 | |1;ext.html;552.8120439050722;609.6494515653499;617.4134572156449;621.9720112594932;637.3721883944316;27596.896124155035;30654.08037758868;46136.769316153455;47275;47338;42970;43484;43491.565084349226;43553.78223108888;43580;7465.178830328659;7897.723579674713;8042.7185048523315;8044.637991290406;8050.677972881078;7489.550626962372;7736.419768517356;7739.845203095934;10018.048285412875;10261.948690256539;10321.58713651453;10376.16990640747;10378.32540884844;10379.169666426667;10383.84424233637;10092.848607270897;10122;10148.89851101487;10159.644412445567;10159.644412445603;7494.400447964204;9630.614775409045;10085.193184545218;10099.646512372032;10501.684949451528;39359;39775;39819.80090099531;39823;39889.80055099735;3406.4379377402697;3580.874669386585;3649.5255616769914;3913.099987002977;3929;29778.40443191146;30349.544756828665;30476.238094047603;30664.693353066406;31502.52746208809;2985.5820185174093;2997.5503674448787;3016.351484430858;3038.8024778389336;3060.3726236121543;231949;232863;232948;233907;234269.82865085735;34100.1474963123;54126.729366353706;54416;54625;54812.45187548153;32689.67310326914;34689;34960;34964.300713985576;34987.30025399529;12843.357832108357;13076.280804555841;13090.214587124803;13107.082504224612;13138.343082846011;9194.816103678022;10231.28381013322;10245.795084098425;10321.432321222404;10401.427921464241;8295.129011453831;8535.871961920577;8545.2309292164;8638.654453821902;8730.083341249077;3303.5705358303267;3305.173706573357;3318.6847249511256;3324.667533246656;3333.4333163362285;924.0436148586181;952.8094381123803;980.4362491567377;1037.2583602723937;1045.4459136657583;28846.7115328844;29317.26706832346;29439.705602944126;29985;30329;893.4996402014892;1009.4548943570536;1020.9591616335412;1026.0304012708068;1041.1514615588271;15755.212239387985;15894.443694470869;15911.522654320388;16058.75911861323;16090.67818643644;365041;368347;370263;372096;373404;52149.217761733606;52159.47840521622;52303.476965229864;52463;52514.47485525172;1161.512164890748;1252.085977236604;1272.0713878868294;1285.3765923527083;1300.596814987357;29184.562231566968;30472.542911856784;30533.23666908345;30536.236594085327;30677.410325109005;38736;38740.6125938737;39129.2174156521;39173.8041309803;39201.411978820914;268035;268249;269230;270256;271490;23813.404664882823;24273;26085.869570652027;26879.596806048317;27110.1866943992;42135.36796948109;42482;43056.78471607622;43441.34837977496;43900.56099439092;35263.471047934814;36044.47688661768;36505;36570.26859462743;36906.8154659225;35267.64732352746;35625;35960.46059309165;36004;36338.4549231767;35485.82257088698;35631.28737425185;40940.59059409487;41638.791806040776;41719.37420938749;32685.509717353783;34382;34422.31155376828;34847;35022.64977350194;25810.049745793996;26003.399660033847;26236.737632624194;26338.86830565835;26697.86651066732 18:26:16 INFO - PROCESS | 4326 | |2;jquery.html;392.14512363048544;393.8621482481134;394.76708741842305;395.9960400395997;416;12723.1729937554;13019.414126364312;16027.198640068009;17445.738313925285;17450.56373590661;16593.66812663746;17245.223964921577;17695.46913592594;17702;18007.27970881163;5245.265662807201;5343.358796944372;5379.327584051994;5392.245085688007;5438.483344082326;3411.9317613647836;5183.17069268917;5280.894382112356;5299.60252981026;5486.533644640211;5514.503694667479;5551.555875529968;5598.916016259759;5625.5499560035305;5626.971865140668;5554.777808887639;5590.580706447013;5602.131669591199;5630.07103827872;5655.321361436613;5273.209018647233;5414.702191379472;5427.294451721251;5435.755391007375;5463.535599474049;12281.201721888048;12634;12641.557545485864;12641.87358126416;18114.637707245914;1884.1332986826076;1948.298612499499;2006.107282259395;2045.120598142798;2311.214187176368;9007.369484136048;9059.59231834569;9215.21670657995;9580.56887440067;9580.90419095807;1705.8038325592468;1810.4387639831693;1874.5594785225462;1883.0678813987026;1886.6226754649126;59597.226283884316;71598;71823.28176718166;72347.27652723511;73135;9677.612895484102;9908.405495670282;10345.534450949724;10386.948065259625;10519.631812886511;14572;14640.194789286692;14679.853201468062;14687.339069741887;14690.26548672562;7172.569645821268;7201.639918004079;7289.271072892669;7371.130156667685;7388.224236455095;6518.413342799171;6706.597604143767;6714.563553369016;6781.152355955505;6870.175578930461;4394.736315821061;4397.934030989538;4444.333349997526;4450.866474005785;4467.977660111744;1548.5431797619729;1685.578605348663;1875.9682174803804;1891.716242563599;1943.9319623813099;798.604690678118;883.6244596046654;943.9899307740651;954.2985905359694;969.6460791811079;15332.616684582974;16329.836701632832;16625.55354117746;17252.13739313029;17279.827201728072;902.7607683963755;964.4502633498838;983.8622592837008;992.0872797026752;1001.3491230699958;9460.716178514656;9558.904410955802;9697.224222062217;9801.11789938909;9823.459709716035;381884;401298;402422;402947;403568;76592.23407765962;78406;78433;78521.2147878514;80699;858.3948316436897;932.3100905330007;949.9050094990583;965.5510187762584;969.4619486185235;12871;15440.150791706115;15540.145292009049;15685;15822.129782861593;33853.15367115842;33984.490232646036;34149;34196;34624.82687586546;43808.123837522435;44939.32591011072;45598;46528.767356163;47141.764291179694;7704.499207551491;7808.5314881107315;7983.5209887406945;8022.799430014296;8173.836523269621;8498.872516912375;11026.44867756625;11598.88401115978;11736.589219377282;12371.814422783846;9291.349605527548;10494.422806745702;10505.31715438494;10874.510647021061;11020.118390528898;10286.434246116236;10401.479926003518;10529.947350263199;10614.1508679307;10695.89304106949;10740.83888741685;10878.29291096108;10974.615888443866;10977.231593788678;11041.944790275997;7985.241402066795;8020.197980201933;8154;8217.917820821955;8308.70919517814;9492.95253523728;9924.354916930377;10000.099991000845;10141.49292535385;10724.73188170302 18:26:16 INFO - PROCESS | 4326 | |3;mootools.html;1304.0675916719545;1485.9925700371496;1526.5878212882521;1687.072110339314;1838.9540261493469;6699.464042876571;7633.084029916404;7863.488873223237;7888.960555197216;8084.9191508084905;7810.882836757439;7981.201879812031;7981.680732770682;7987.4408791384485;7989.041315042203;1923.1634239106033;2178.716766820311;2210.226420752732;2242.0807468937737;2291.51878105598;2269.1604106480627;2292.862428254302;2298.37943755186;2298.643710224918;2299.5630830142304;2198.758136604972;2239.6640503924377;2253.2001139595454;2256.5261295128043;2256.616375216209;1822.5808064145256;1891.3474851176459;1896.6491199128068;2048.467398476392;2129.4569884679386;2193.846430749848;2221.1781640792847;2224.2215224671236;2226.821854251672;2226.8886555672316;7835.373170146431;7870.881936770953;7872.252136047124;7890.566018869017;7899.763007109796;4711.387519622462;4840.080384726926;4869.951300486985;4873.195922672755;4876.975615121937;2689.7579217870484;2783.262435454606;2927.575501552284;2938.147937098239;2942.8528573571234;906.0848542971646;914.8719179314901;936.3679516326453;1649.7607846862259;1731.705610046295;10892.673219803419;10950.671479855617;10997.230193886431;11004.724881877937;11010.724731881686;4032.495938007749;4198;4216.831326746896;4251.256030194765;4278.72188307759;8602.139786021473;8622.353323500762;8693.043765185825;8700.651973921098;8702.47785132894;4710.52894710533;4734.668573199911;4739.170645137087;4744.881377965578;4747.074320507537;3787.299349620329;4139.110091330378;4235.724677895927;4241.427407300037;4286.185624731291;3387.356402283558;3411.727061835047;3457.22212502185;3505.3515099706124;3512.5960514541157;1000.9699709008596;1003.9057427404165;1004.8191325561469;1005.9245556583286;1015.1979935850595;860.2816648098908;873.1312344217545;890.8129292848574;896.878921345623;901.1394118616705;5436.836894893159;5651.474101992521;5720.599558030981;5730.713464326767;5788.652680839163;867.8741582470507;910.6220918318955;954.8663187153804;955.1976339874539;971.4171497101682;3681.226942342138;3792.0898984244136;3871.728978971445;3874.4575759393706;3878.864239751595;9595.088466595662;9618.951905240569;9960.800783984425;9995.200383969264;10050.447225402673;9764.853527197098;9782.461964592016;9813.803723925625;9912.504374781232;9914.107730304164;7107.82230444243;7386.96306518464;7425.108986921604;7440.404767618577;7562.092548894278;5660.235868157746;5684.886302274014;5794.13088036781;5843.451485356469;5845.766169353348;8735.868961965703;9103.135202155785;9153.221976132103;9165.129312715282;9296.651985462206;9018.278537716837;9166.541672916193;9222.216111630318;9265.72202833916;9356.111169438893;5968.701564921824;6166.383361663977;6415.615063096229;6947.68735406918;7074.787756367111;6901.654917254217;7131.8573628528175;7139.000539924311;7825.217478252357;7827.608619568885;6811.284815094344;6936.2023367311385;6959.373656370748;7033.859322813618;7051.365377116084;7001.789946301619;7028.472864535078;7039.260877607982;7043.013978042975;7089.220185779457;6336.366363363627;6372.330905254881;6388.552801303864;6403.64779937108;6408.29508754046;4639.953600463952;4796.616270698406;4965.975170124127;4973.204287314013;4997.050560393659;4777.116233496814;4935.555799978019;4960.677555958851;4999.45006049327;5033.219850923113 18:26:16 INFO - PROCESS | 4326 | |4;prototype.html;290.56270313178663;302.08769516061494;302.9333546619743;313.80700868965585;316.6042446941323;4835.226363781796;4917.82787602434;4928.654994150406;4936.6791158574715;4952.876178095549;4599.908001839961;4624.572099647687;4728.3380326754195;4763.666543341968;4860.975695121529;3824.2351529694033;3843.673287770543;3850.826712797917;3855.2096820151874;3870.5161854768153;3867;3870.3033453978273;3872.3223435898603;3876.6898648108086;3881.6894648428056;3615.8011309377976;3721.274351501458;3828.25349056934;3841.0973421245985;3851.7111216658723;3789.6020917803507;3792.3742582474133;3816.93885301938;3831.214601006787;3833.11838277195;3385.1198688340965;3396;3410.198603328216;3432.4164891968426;3453.0504111369323;4395.428594282755;4667.382778346774;4689.788959496796;4693.859184224479;4721.126591580569;4279.293916503773;4300.096979634281;4308.547602501723;4319.76241306728;4332.7400355978425;5063.594912407036;5084.77118529667;5090.287359769635;5109.744512774384;5117.309163262949;3565.2502435823517;3638.472421498894;3647.4164133738473;3659.670629643345;3672.540932383452;5117.360329958755;5298.443663415324;5313.840584782462;5314.123169676998;5320.791871373375;3639.890803275906;3654.3422184006604;3657.926841463156;3705.8517659293866;3717.237966216919;3755.3615885299564;3767.56672982605;3775.847766772913;3790.9241815164096;3807.143392736612;4140.730852494578;4253.1706317267835;4293.3130699088;4332.436783218224;4333.58830911063;4978.701277923336;5017;5026.346574945233;5073.239014147834;5086.694798312114;1227.404708716272;1280.1998750780763;1301.0502333296747;1306.3729409883308;1313.5468263449095;840.5167028958373;845.7293666026935;856.032683068129;860.4063196394468;870.3602851903846;3964.6431821136234;3967.1867267210155;3976.741511801724;3984.1633257015774;3997.780122093313;4580.946382332082;4624.445066591963;4625.38111660916;4664.300354946718;4670.322803193518;3932.522510818166;3952.0317522207242;3966.7786039817793;3971.3645816669195;3972.4239985201984;2357.26924653358;2457.557639624885;2478.045952308338;2482.7144878338863;2484.9006039758206;4317.438732964757;4328.761918094472;4330.501992270866;4338.587834155814;4364.886953826754;4633.884152896205;4666.113438446684;4686.648501362343;4695.906081878412;4705.376645057449;4292.785360731951;4309.48286205651;4314;4509.391232183613;4971.9005619886675;5204.843854684365;5261.263423120689;5266.868328291824;5287.180487024518;5293.576513878804;5141.2288156776785;5150.665206761549;5163.734884953211;5191.74041297926;5226.876221612371;4741.928871066868;4928.778204980713;4961.330220420197;4996.925046124384;5016.322796422436;3735.234276973268;3748.437734339871;3751.2872554214614;3753.6246375362243;3760.984534175812;5023.824166154167;5271.389098467562;5306.442823503477;5319.973400132974;5342.385625652944;4940.456549779593;5074.568661663652;5088.898222035465;5095.235714642833;5096.694198348111;5077.619178561548;5083.491650834887;5107.927335259637;5114.667546609458;5122.129238029618;4884.291777692286;4992.952889315363;5025.597952163891;5031.547160755549;5035.269885866455;5060.49395060491;5102.260172274925;5106.540411362995;5122.667026643257;5154.329937108302;4973.726445045412;5009.772605711624;5024.296598476143;5035.320231768665;5043.344365232496 18:26:16 INFO - PROCESS | 4326 | |5;yui.html;726.8982342472053;807.4912804932895;821.695972490178;834.0408530190281;837.2757564706527;10587.629432969848;10945.179111566644;10971.067459265952;10996.175286853488;11008.39453830039;10416.63541776036;11044.668659940195;11236.4943577539;11265.267757595751;11435.085193184546;861.8405594964936;876.0057334924843;876.1413814461831;876.5003947749785;876.8071024374659;853.7353420439653;861.5519929636586;862.560094351882;862.5859587398053;863.6847550644032;910.7176775199677;913.6299798581551;913.9908600913973;915.555955361649;917.2157805076663;906.4153620914539;907.4918045894287;907.7367563406605;908.4049947284507;909;672.193367958447;674.8211723893172;677.478341676911;678.1794029224644;678.4233401608642;10438.63464778729;10488.947555262252;10543.84184237237;10620.309679870861;10635.468226588717;9956.701298961043;9992;10062.144717698931;10508.474576271155;10629.84055239172;8217.917820821775;8222.547759873205;8238.876416853753;8437.071922088566;8451.366147538898;3265.8856940006985;3289.7861638993627;3322.2691007978447;3356.47974563943;3391.6099648540485;34581.65418345809;35043;39575.60424395748;40064.79967600173;42529;9552.474613896164;9553.140217380469;9789.510524473748;9827.508624568743;10055.69832905014;17146.485605431855;17198.82801171972;17361.65276694459;17456.912715436596;17489.21298541569;6821.795346139624;6847.21257055445;6872.278410766898;7000.124984376953;7023.789286321418;3620.637936206358;3678.4666223397458;3680.392735198662;3754.2679177560112;3774.226283611853;2759.599858020576;2885.6248687670886;2890.580865774493;2891.407261511385;2892.580575816537;518.8662771843534;525.8685328667833;529.9364076310791;530.4218401941824;537;631.6778442994106;649.4998850884792;650.5413683353224;660.3924389561615;667.7429189761976;8116.553589552514;8482.321414286966;8543.102974187621;8632.69785557515;8676.60955257015;568.5167607533646;585.6925114314934;625;637.1303171171318;654.2182092399537;3859.7684138951754;3950.5259368875345;3976.383660532564;4027.1140349123148;4060.959390406117;47183.764081179375;49016.50983490191;49134.75432622814;49308;50291;37356;43687.56312436835;45397;45539.772301138284;46463.53536464592;41523;41610;41695.166096677895;41765.37351939723;41919.79040104841;8241;8484.745457636282;8724.91275087241;8927.151920567536;9129.543522823966;27064.864675677283;27551.448971020065;28231.43537129205;28252.85873570619;28466.857665711537;33655.83172084124;34756.65243347635;36537.634623654485;36759.63240367562;37014.25971480609;9949.900500994898;10293.588256469657;10486.213533984828;10633.361998280128;10931.398773067558;10783.51474183648;10897.950685583364;11253.099752019982;11375.43122843871;11798.056155507711;10342.069213770797;10515.684529464128;10705.250632455653;11239.04468120219;11286.379249141048;10711;10929.617463388744;11036.227464077438;11095.445227738419;11108.38903860263;11013.174011948975;11578.594749183738;11600.419979001184;11624.418779061183;11664.533418663159;8773.605187766438;8810.427322224035;8838.690645827364;8996.235319997873;9193.448393096436;9341.906580934103;9474.289428292768;9478.668246611336;9529.190018848476;9571.712848614552 18:26:16 INFO - PROCESS | 4326 | __end_tp_report 18:26:16 INFO - PROCESS | 4326 | __start_cc_report 18:26:16 INFO - PROCESS | 4326 | _x_x_mozilla_cycle_collect,1094 18:26:16 INFO - PROCESS | 4326 | __end_cc_report 18:26:16 INFO - PROCESS | 4326 | __startTimestamp1462929976946__endTimestamp 18:26:16 INFO - PROCESS | 4326 | 18:26:16 INFO - PROCESS | 4326 | ------- Summary: start ------- 18:26:16 INFO - PROCESS | 4326 | Number of tests: 6 18:26:16 INFO - PROCESS | 4326 | 18:26:16 INFO - PROCESS | 4326 | [#0] dojo.html Cycles:165 Average:4719.60 Median:4823.04 stddev:510.79 (10.6%) stddev-sans-first:485.71 18:26:16 INFO - PROCESS | 4326 | Values: 2643.7 2677.7 2723.3 2733.6 2734.8 4737.4 4739.1 4804.1 4840.6 4870.3 4783.5 4789.4 4792.0 4821.4 4831.7 4378.7 4419.6 4425.3 4477.9 4489.6 4388.4 4415.2 4436.0 4448.1 4477.1 4263.3 4303.4 4304.3 4363.7 4392.9 4361.1 4381.9 4425.5 4444.1 4445.4 4394.3 4417.2 4430.6 4467.6 4470.8 4519.1 4590.3 4624.1 4639.5 4704.4 5050.6 5062.9 5111.6 5125.3 5128.4 5006.8 5081.6 5098.7 5112.0 5151.3 4823.0 4850.5 4879.0 4926.7 4948.8 5332.5 5383.4 5400.6 5404.1 5405.2 5035.9 5055.2 5081.5 5083.2 5086.9 5056.4 5137.0 5159.0 5196.9 5230.3 5171.4 5175.2 5184.3 5190.9 5235.9 4976.8 5149.8 5169.1 5171.3 5179.7 4212.5 4225.4 4242.0 4247.5 4252.7 3712.1 3716.8 3721.8 3746.8 3748.0 4220.3 4234.9 4239.0 4266.1 4266.3 5118.5 5171.6 5185.7 5217.9 5273.3 4363.7 4365.1 4372.1 4378.9 4417.2 4551.4 4673.5 4689.2 4692.8 4744.5 4690.3 4715.0 4736.7 4763.7 4786.8 5054.1 5060.1 5063.2 5066.1 5116.7 4693.6 4731.1 4735.6 4738.3 4787.1 4930.9 5073.0 5124.9 5125.5 5156.4 5183.8 5194.2 5201.5 5208.8 5216.1 4747.5 5045.3 5060.0 5067.4 5067.8 4783.1 4819.3 4829.7 4855.3 4866.1 4780.5 4792.7 4837.3 4841.5 4842.9 4901.7 4932.7 4939.1 4955.1 4971.4 4856.0 4913.3 4920.2 4921.6 4934.3 18:26:16 INFO - PROCESS | 4326 | 18:26:16 INFO - PROCESS | 4326 | [#1] ext.html Cycles:180 Average:44734.70 Median:34241.07 stddev:77893.78 (227.5%) stddev-sans-first:78041.66 18:26:16 INFO - PROCESS | 4326 | Values: 552.8 609.6 617.4 622.0 637.4 27596.9 30654.1 46136.8 47275.0 47338.0 42970.0 43484.0 43491.6 43553.8 43580.0 7465.2 7897.7 8042.7 8044.6 8050.7 7489.6 7736.4 7739.8 10018.0 10261.9 10321.6 10376.2 10378.3 10379.2 10383.8 10092.8 10122.0 10148.9 10159.6 10159.6 7494.4 9630.6 10085.2 10099.6 10501.7 39359.0 39775.0 39819.8 39823.0 39889.8 3406.4 3580.9 3649.5 3913.1 3929.0 29778.4 30349.5 30476.2 30664.7 31502.5 2985.6 2997.6 3016.4 3038.8 3060.4 231949.0 232863.0 232948.0 233907.0 234269.8 34100.1 54126.7 54416.0 54625.0 54812.5 32689.7 34689.0 34960.0 34964.3 34987.3 12843.4 13076.3 13090.2 13107.1 13138.3 9194.8 10231.3 10245.8 10321.4 10401.4 8295.1 8535.9 8545.2 8638.7 8730.1 3303.6 3305.2 3318.7 3324.7 3333.4 924.0 952.8 980.4 1037.3 1045.4 28846.7 29317.3 29439.7 29985.0 30329.0 893.5 1009.5 1021.0 1026.0 1041.2 15755.2 15894.4 15911.5 16058.8 16090.7 365041.0 368347.0 370263.0 372096.0 373404.0 52149.2 52159.5 52303.5 52463.0 52514.5 1161.5 1252.1 1272.1 1285.4 1300.6 29184.6 30472.5 30533.2 30536.2 30677.4 38736.0 38740.6 39129.2 39173.8 39201.4 268035.0 268249.0 269230.0 270256.0 271490.0 23813.4 24273.0 26085.9 26879.6 27110.2 42135.4 42482.0 43056.8 43441.3 43900.6 35263.5 36044.5 36505.0 36570.3 36906.8 35267.6 35625.0 35960.5 36004.0 36338.5 35485.8 35631.3 40940.6 41638.8 41719.4 32685.5 34382.0 34422.3 34847.0 35022.6 25810.0 26003.4 26236.7 26338.9 26697.9 18:26:16 INFO - PROCESS | 4326 | 18:26:16 INFO - PROCESS | 4326 | [#2] jquery.html Cycles:180 Average:24208.72 Median:4459.42 stddev:65740.37 (1474.2%) stddev-sans-first:65900.47 18:26:16 INFO - PROCESS | 4326 | Values: 392.1 393.9 394.8 396.0 416.0 12723.2 13019.4 16027.2 17445.7 17450.6 16593.7 17245.2 17695.5 17702.0 18007.3 5245.3 5343.4 5379.3 5392.2 5438.5 3411.9 5183.2 5280.9 5299.6 5486.5 5514.5 5551.6 5598.9 5625.5 5627.0 5554.8 5590.6 5602.1 5630.1 5655.3 5273.2 5414.7 5427.3 5435.8 5463.5 12281.2 12634.0 12641.6 12641.9 18114.6 1884.1 1948.3 2006.1 2045.1 2311.2 9007.4 9059.6 9215.2 9580.6 9580.9 1705.8 1810.4 1874.6 1883.1 1886.6 59597.2 71598.0 71823.3 72347.3 73135.0 9677.6 9908.4 10345.5 10386.9 10519.6 14572.0 14640.2 14679.9 14687.3 14690.3 7172.6 7201.6 7289.3 7371.1 7388.2 6518.4 6706.6 6714.6 6781.2 6870.2 4394.7 4397.9 4444.3 4450.9 4468.0 1548.5 1685.6 1876.0 1891.7 1943.9 798.6 883.6 944.0 954.3 969.6 15332.6 16329.8 16625.6 17252.1 17279.8 902.8 964.5 983.9 992.1 1001.3 9460.7 9558.9 9697.2 9801.1 9823.5 381884.0 401298.0 402422.0 402947.0 403568.0 76592.2 78406.0 78433.0 78521.2 80699.0 858.4 932.3 949.9 965.6 969.5 12871.0 15440.2 15540.1 15685.0 15822.1 33853.2 33984.5 34149.0 34196.0 34624.8 43808.1 44939.3 45598.0 46528.8 47141.8 7704.5 7808.5 7983.5 8022.8 8173.8 8498.9 11026.4 11598.9 11736.6 12371.8 9291.3 10494.4 10505.3 10874.5 11020.1 10286.4 10401.5 10529.9 10614.2 10695.9 10740.8 10878.3 10974.6 10977.2 11041.9 7985.2 8020.2 8154.0 8217.9 8308.7 9493.0 9924.4 10000.1 10141.5 10724.7 18:26:16 INFO - PROCESS | 4326 | 18:26:16 INFO - PROCESS | 4326 | [#3] mootools.html Cycles:180 Average:5235.95 Median:5672.56 stddev:2909.63 (51.3%) stddev-sans-first:2902.78 18:26:16 INFO - PROCESS | 4326 | Values: 1304.1 1486.0 1526.6 1687.1 1839.0 6699.5 7633.1 7863.5 7889.0 8084.9 7810.9 7981.2 7981.7 7987.4 7989.0 1923.2 2178.7 2210.2 2242.1 2291.5 2269.2 2292.9 2298.4 2298.6 2299.6 2198.8 2239.7 2253.2 2256.5 2256.6 1822.6 1891.3 1896.6 2048.5 2129.5 2193.8 2221.2 2224.2 2226.8 2226.9 7835.4 7870.9 7872.3 7890.6 7899.8 4711.4 4840.1 4870.0 4873.2 4877.0 2689.8 2783.3 2927.6 2938.1 2942.9 906.1 914.9 936.4 1649.8 1731.7 10892.7 10950.7 10997.2 11004.7 11010.7 4032.5 4198.0 4216.8 4251.3 4278.7 8602.1 8622.4 8693.0 8700.7 8702.5 4710.5 4734.7 4739.2 4744.9 4747.1 3787.3 4139.1 4235.7 4241.4 4286.2 3387.4 3411.7 3457.2 3505.4 3512.6 1001.0 1003.9 1004.8 1005.9 1015.2 860.3 873.1 890.8 896.9 901.1 5436.8 5651.5 5720.6 5730.7 5788.7 867.9 910.6 954.9 955.2 971.4 3681.2 3792.1 3871.7 3874.5 3878.9 9595.1 9619.0 9960.8 9995.2 10050.4 9764.9 9782.5 9813.8 9912.5 9914.1 7107.8 7387.0 7425.1 7440.4 7562.1 5660.2 5684.9 5794.1 5843.5 5845.8 8735.9 9103.1 9153.2 9165.1 9296.7 9018.3 9166.5 9222.2 9265.7 9356.1 5968.7 6166.4 6415.6 6947.7 7074.8 6901.7 7131.9 7139.0 7825.2 7827.6 6811.3 6936.2 6959.4 7033.9 7051.4 7001.8 7028.5 7039.3 7043.0 7089.2 6336.4 6372.3 6388.6 6403.6 6408.3 4640.0 4796.6 4966.0 4973.2 4997.1 4777.1 4935.6 4960.7 4999.5 5033.2 18:26:16 INFO - PROCESS | 4326 | 18:26:16 INFO - PROCESS | 4326 | [#4] prototype.html Cycles:180 Average:4129.69 Median:4590.43 stddev:1201.53 (26.2%) stddev-sans-first:1169.84 18:26:16 INFO - PROCESS | 4326 | Values: 290.6 302.1 302.9 313.8 316.6 4835.2 4917.8 4928.7 4936.7 4952.9 4599.9 4624.6 4728.3 4763.7 4861.0 3824.2 3843.7 3850.8 3855.2 3870.5 3867.0 3870.3 3872.3 3876.7 3881.7 3615.8 3721.3 3828.3 3841.1 3851.7 3789.6 3792.4 3816.9 3831.2 3833.1 3385.1 3396.0 3410.2 3432.4 3453.1 4395.4 4667.4 4689.8 4693.9 4721.1 4279.3 4300.1 4308.5 4319.8 4332.7 5063.6 5084.8 5090.3 5109.7 5117.3 3565.3 3638.5 3647.4 3659.7 3672.5 5117.4 5298.4 5313.8 5314.1 5320.8 3639.9 3654.3 3657.9 3705.9 3717.2 3755.4 3767.6 3775.8 3790.9 3807.1 4140.7 4253.2 4293.3 4332.4 4333.6 4978.7 5017.0 5026.3 5073.2 5086.7 1227.4 1280.2 1301.1 1306.4 1313.5 840.5 845.7 856.0 860.4 870.4 3964.6 3967.2 3976.7 3984.2 3997.8 4580.9 4624.4 4625.4 4664.3 4670.3 3932.5 3952.0 3966.8 3971.4 3972.4 2357.3 2457.6 2478.0 2482.7 2484.9 4317.4 4328.8 4330.5 4338.6 4364.9 4633.9 4666.1 4686.6 4695.9 4705.4 4292.8 4309.5 4314.0 4509.4 4971.9 5204.8 5261.3 5266.9 5287.2 5293.6 5141.2 5150.7 5163.7 5191.7 5226.9 4741.9 4928.8 4961.3 4996.9 5016.3 3735.2 3748.4 3751.3 3753.6 3761.0 5023.8 5271.4 5306.4 5320.0 5342.4 4940.5 5074.6 5088.9 5095.2 5096.7 5077.6 5083.5 5107.9 5114.7 5122.1 4884.3 4993.0 5025.6 5031.5 5035.3 5060.5 5102.3 5106.5 5122.7 5154.3 4973.7 5009.8 5024.3 5035.3 5043.3 18:26:16 INFO - PROCESS | 4326 | 18:26:16 INFO - PROCESS | 4326 | [#5] yui.html Cycles:180 Average:12020.19 Median:45468.39 stddev:13312.73 (29.3%) stddev-sans-first:13323.06 18:26:16 INFO - PROCESS | 4326 | Values: 726.9 807.5 821.7 834.0 837.3 10587.6 10945.2 10971.1 10996.2 11008.4 10416.6 11044.7 11236.5 11265.3 11435.1 861.8 876.0 876.1 876.5 876.8 853.7 861.6 862.6 862.6 863.7 910.7 913.6 914.0 915.6 917.2 906.4 907.5 907.7 908.4 909.0 672.2 674.8 677.5 678.2 678.4 10438.6 10488.9 10543.8 10620.3 10635.5 9956.7 9992.0 10062.1 10508.5 10629.8 8217.9 8222.5 8238.9 8437.1 8451.4 3265.9 3289.8 3322.3 3356.5 3391.6 34581.7 35043.0 39575.6 40064.8 42529.0 9552.5 9553.1 9789.5 9827.5 10055.7 17146.5 17198.8 17361.7 17456.9 17489.2 6821.8 6847.2 6872.3 7000.1 7023.8 3620.6 3678.5 3680.4 3754.3 3774.2 2759.6 2885.6 2890.6 2891.4 2892.6 518.9 525.9 529.9 530.4 537.0 631.7 649.5 650.5 660.4 667.7 8116.6 8482.3 8543.1 8632.7 8676.6 568.5 585.7 625.0 637.1 654.2 3859.8 3950.5 3976.4 4027.1 4061.0 47183.8 49016.5 49134.8 49308.0 50291.0 37356.0 43687.6 45397.0 45539.8 46463.5 41523.0 41610.0 41695.2 41765.4 41919.8 8241.0 8484.7 8724.9 8927.2 9129.5 27064.9 27551.4 28231.4 28252.9 28466.9 33655.8 34756.7 36537.6 36759.6 37014.3 9949.9 10293.6 10486.2 10633.4 10931.4 10783.5 10898.0 11253.1 11375.4 11798.1 10342.1 10515.7 10705.3 11239.0 11286.4 10711.0 10929.6 11036.2 11095.4 11108.4 11013.2 11578.6 11600.4 11624.4 11664.5 8773.6 8810.4 8838.7 8996.2 9193.4 9341.9 9474.3 9478.7 9529.2 9571.7 18:26:16 INFO - PROCESS | 4326 | -------- Summary: end -------- 18:26:16 INFO - PROCESS | 4326 | 18:26:17 INFO - TEST-INFO | 4326: exit 0 18:26:17 INFO - TEST-OK | dromaeo_css | took 1119931ms 18:26:17 INFO - TEST-START | kraken 18:26:17 INFO - operating with platform_type : linux_ 18:26:17 INFO - Initialising browser for kraken test... 18:26:17 INFO - TEST-INFO | started process 4506 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpzeH96S/profile http://localhost:43158/getInfo.html) 18:26:22 INFO - PROCESS | 4506 | __metrics Screen width/height:1600/1200 18:26:22 INFO - PROCESS | 4506 | colorDepth:24 18:26:22 INFO - PROCESS | 4506 | Browser inner width/height: 1024/697 18:26:22 INFO - PROCESS | 4506 | __metrics 18:26:23 ERROR - PROCESS | 4506 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 18:26:24 INFO - TEST-INFO | 4506: exit 0 18:26:24 INFO - Browser initialized. 18:26:24 INFO - Running cycle 1/1 for kraken test... 18:26:24 INFO - TEST-INFO | started process 4592 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpzeH96S/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:26:36 INFO - PROCESS | 4592 | RSS: Main: 153694208 18:26:36 INFO - PROCESS | 4592 | 18:26:39 INFO - PROCESS | 4592 | Cycle 1(1): loaded http://localhost:43158/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:43158/tests/kraken/driver.html?testName=audio-beat-detection) 18:26:40 INFO - PROCESS | 4592 | RSS: Main: 210366464 18:26:40 INFO - PROCESS | 4592 | 18:26:43 INFO - PROCESS | 4592 | Cycle 1(1): loaded http://localhost:43158/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:43158/tests/kraken/driver.html?testName=audio-dft) 18:26:43 INFO - PROCESS | 4592 | RSS: Main: 197693440 18:26:43 INFO - PROCESS | 4592 | 18:26:46 INFO - PROCESS | 4592 | Cycle 1(1): loaded http://localhost:43158/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:43158/tests/kraken/driver.html?testName=audio-fft) 18:26:47 INFO - PROCESS | 4592 | RSS: Main: 266973184 18:26:47 INFO - PROCESS | 4592 | 18:26:49 INFO - PROCESS | 4592 | Cycle 1(1): loaded http://localhost:43158/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:43158/tests/kraken/driver.html?testName=audio-oscillator) 18:26:50 INFO - PROCESS | 4592 | RSS: Main: 175771648 18:26:50 INFO - PROCESS | 4592 | 18:26:52 INFO - PROCESS | 4592 | Cycle 1(1): loaded http://localhost:43158/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:43158/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:26:53 INFO - PROCESS | 4592 | RSS: Main: 179675136 18:26:53 INFO - PROCESS | 4592 | 18:26:56 INFO - PROCESS | 4592 | Cycle 1(1): loaded http://localhost:43158/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:43158/tests/kraken/driver.html?testName=imaging-darkroom) 18:26:57 INFO - PROCESS | 4592 | RSS: Main: 215683072 18:26:57 INFO - PROCESS | 4592 | 18:27:00 INFO - PROCESS | 4592 | Cycle 1(1): loaded http://localhost:43158/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:43158/tests/kraken/driver.html?testName=imaging-desaturate) 18:27:01 INFO - PROCESS | 4592 | RSS: Main: 213954560 18:27:01 INFO - PROCESS | 4592 | 18:27:04 INFO - PROCESS | 4592 | Cycle 1(1): loaded http://localhost:43158/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:43158/tests/kraken/driver.html?testName=json-parse-financial) 18:27:05 INFO - PROCESS | 4592 | RSS: Main: 214552576 18:27:05 INFO - PROCESS | 4592 | 18:27:06 INFO - PROCESS | 4592 | Cycle 1(1): loaded http://localhost:43158/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:43158/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:27:07 INFO - PROCESS | 4592 | RSS: Main: 204578816 18:27:07 INFO - PROCESS | 4592 | 18:27:09 INFO - PROCESS | 4592 | Cycle 1(1): loaded http://localhost:43158/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:43158/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:27:10 INFO - PROCESS | 4592 | RSS: Main: 207171584 18:27:10 INFO - PROCESS | 4592 | 18:27:12 INFO - PROCESS | 4592 | Cycle 1(1): loaded http://localhost:43158/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:43158/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:27:13 INFO - PROCESS | 4592 | RSS: Main: 216514560 18:27:13 INFO - PROCESS | 4592 | 18:27:15 INFO - PROCESS | 4592 | Cycle 1(1): loaded http://localhost:43158/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:43158/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:27:16 INFO - PROCESS | 4592 | RSS: Main: 223047680 18:27:16 INFO - PROCESS | 4592 | 18:27:19 INFO - PROCESS | 4592 | Cycle 1(1): loaded http://localhost:43158/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:43158/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:27:20 INFO - PROCESS | 4592 | RSS: Main: 220246016 18:27:20 INFO - PROCESS | 4592 | 18:27:22 INFO - PROCESS | 4592 | Cycle 1(1): loaded http://localhost:43158/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:43158/tests/kraken/driver.html?testName=ai-astar) 18:27:22 INFO - PROCESS | 4592 | RSS: Main: 265580544 18:27:22 INFO - PROCESS | 4592 | 18:27:22 INFO - PROCESS | 4592 | __start_tp_report 18:27:22 INFO - PROCESS | 4592 | _x_x_mozilla_page_load 18:27:22 INFO - PROCESS | 4592 | _x_x_mozilla_page_load_details 18:27:22 INFO - PROCESS | 4592 | |i|pagename|runs| 18:27:22 INFO - PROCESS | 4592 | |0;ai-astar;114;109;112;113;115;113;115;114;114;113 18:27:22 INFO - PROCESS | 4592 | |1;audio-beat-detection;160;135;155;226;131;165;160;130;177;135 18:27:22 INFO - PROCESS | 4592 | |2;audio-dft;162;162;161;159;161;161;162;162;160;163 18:27:22 INFO - PROCESS | 4592 | |3;audio-fft;77;76;77;155;75;74;76;74;125;76 18:27:22 INFO - PROCESS | 4592 | |4;audio-oscillator;94;92;147;93;92;92;118;93;93;122 18:27:22 INFO - PROCESS | 4592 | |5;imaging-gaussian-blur;116;117;117;117;117;117;117;116;116;117 18:27:22 INFO - PROCESS | 4592 | |6;imaging-darkroom;146;145;146;145;145;146;145;145;145;146 18:27:22 INFO - PROCESS | 4592 | |7;imaging-desaturate;94;95;97;94;94;94;94;95;95;94 18:27:22 INFO - PROCESS | 4592 | |8;json-parse-financial;68;69;68;68;69;69;69;129;67;67 18:27:22 INFO - PROCESS | 4592 | |9;json-stringify-tinderbox;64;63;63;64;63;64;64;64;63;64 18:27:22 INFO - PROCESS | 4592 | |10;stanford-crypto-aes;87;87;84;81;82;84;84;83;88;83 18:27:22 INFO - PROCESS | 4592 | |11;stanford-crypto-ccm;147;128;136;132;129;135;142;144;195;140 18:27:22 INFO - PROCESS | 4592 | |12;stanford-crypto-pbkdf2;176;177;184;179;173;178;176;173;176;176 18:27:22 INFO - PROCESS | 4592 | |13;stanford-crypto-sha256-iterative;70;67;68;67;77;71;71;67;68;66 18:27:22 INFO - PROCESS | 4592 | __end_tp_report 18:27:22 INFO - PROCESS | 4592 | __start_cc_report 18:27:22 INFO - PROCESS | 4592 | _x_x_mozilla_cycle_collect,2113 18:27:22 INFO - PROCESS | 4592 | __end_cc_report 18:27:22 INFO - PROCESS | 4592 | __startTimestamp1462930042362__endTimestamp 18:27:22 INFO - PROCESS | 4592 | 18:27:22 INFO - PROCESS | 4592 | ------- Summary: start ------- 18:27:22 INFO - PROCESS | 4592 | Number of tests: 14 18:27:22 INFO - PROCESS | 4592 | 18:27:22 INFO - PROCESS | 4592 | [#0] ai-astar Cycles:10 Average:113.20 Median:114.00 stddev:1.75 (1.5%) stddev-sans-first:1.83 18:27:22 INFO - PROCESS | 4592 | Values: 114.0 109.0 112.0 113.0 115.0 113.0 115.0 114.0 114.0 113.0 18:27:22 INFO - PROCESS | 4592 | 18:27:22 INFO - PROCESS | 4592 | [#1] audio-beat-detection Cycles:10 Average:157.40 Median:160.00 stddev:29.09 (18.2%) stddev-sans-first:30.84 18:27:22 INFO - PROCESS | 4592 | Values: 160.0 135.0 155.0 226.0 131.0 165.0 160.0 130.0 177.0 135.0 18:27:22 INFO - PROCESS | 4592 | 18:27:22 INFO - PROCESS | 4592 | [#2] audio-dft Cycles:10 Average:161.30 Median:162.00 stddev:1.16 (0.7%) stddev-sans-first:1.20 18:27:22 INFO - PROCESS | 4592 | Values: 162.0 162.0 161.0 159.0 161.0 161.0 162.0 162.0 160.0 163.0 18:27:22 INFO - PROCESS | 4592 | 18:27:22 INFO - PROCESS | 4592 | [#3] audio-fft Cycles:10 Average:88.50 Median:76.00 stddev:28.07 (36.9%) stddev-sans-first:29.46 18:27:22 INFO - PROCESS | 4592 | Values: 77.0 76.0 77.0 155.0 75.0 74.0 76.0 74.0 125.0 76.0 18:27:22 INFO - PROCESS | 4592 | 18:27:22 INFO - PROCESS | 4592 | [#4] audio-oscillator Cycles:10 Average:103.60 Median:92.50 stddev:19.04 (20.6%) stddev-sans-first:19.87 18:27:22 INFO - PROCESS | 4592 | Values: 94.0 92.0 147.0 93.0 92.0 92.0 118.0 93.0 93.0 122.0 18:27:22 INFO - PROCESS | 4592 | 18:27:22 INFO - PROCESS | 4592 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.44 18:27:22 INFO - PROCESS | 4592 | Values: 116.0 117.0 117.0 117.0 117.0 117.0 117.0 116.0 116.0 117.0 18:27:22 INFO - PROCESS | 4592 | 18:27:22 INFO - PROCESS | 4592 | [#6] imaging-darkroom Cycles:10 Average:145.40 Median:145.50 stddev:0.52 (0.4%) stddev-sans-first:0.50 18:27:22 INFO - PROCESS | 4592 | Values: 146.0 145.0 146.0 145.0 145.0 146.0 145.0 145.0 145.0 146.0 18:27:22 INFO - PROCESS | 4592 | 18:27:22 INFO - PROCESS | 4592 | [#7] imaging-desaturate Cycles:10 Average:94.60 Median:94.50 stddev:0.97 (1.0%) stddev-sans-first:1.00 18:27:22 INFO - PROCESS | 4592 | Values: 94.0 95.0 97.0 94.0 94.0 94.0 94.0 95.0 95.0 94.0 18:27:22 INFO - PROCESS | 4592 | 18:27:22 INFO - PROCESS | 4592 | [#8] json-parse-financial Cycles:10 Average:74.30 Median:68.50 stddev:19.24 (28.1%) stddev-sans-first:20.27 18:27:22 INFO - PROCESS | 4592 | Values: 68.0 69.0 68.0 68.0 69.0 69.0 69.0 129.0 67.0 67.0 18:27:22 INFO - PROCESS | 4592 | 18:27:22 INFO - PROCESS | 4592 | [#9] json-stringify-tinderbox Cycles:10 Average:63.60 Median:64.00 stddev:0.52 (0.8%) stddev-sans-first:0.53 18:27:22 INFO - PROCESS | 4592 | Values: 64.0 63.0 63.0 64.0 63.0 64.0 64.0 64.0 63.0 64.0 18:27:22 INFO - PROCESS | 4592 | 18:27:22 INFO - PROCESS | 4592 | [#10] stanford-crypto-aes Cycles:10 Average:84.30 Median:84.00 stddev:2.31 (2.8%) stddev-sans-first:2.24 18:27:22 INFO - PROCESS | 4592 | Values: 87.0 87.0 84.0 81.0 82.0 84.0 84.0 83.0 88.0 83.0 18:27:22 INFO - PROCESS | 4592 | 18:27:22 INFO - PROCESS | 4592 | [#11] stanford-crypto-ccm Cycles:10 Average:142.80 Median:141.00 stddev:19.40 (13.8%) stddev-sans-first:20.51 18:27:22 INFO - PROCESS | 4592 | Values: 147.0 128.0 136.0 132.0 129.0 135.0 142.0 144.0 195.0 140.0 18:27:22 INFO - PROCESS | 4592 | 18:27:22 INFO - PROCESS | 4592 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:176.80 Median:176.50 stddev:3.16 (1.8%) stddev-sans-first:3.33 18:27:22 INFO - PROCESS | 4592 | Values: 176.0 177.0 184.0 179.0 173.0 178.0 176.0 173.0 176.0 176.0 18:27:22 INFO - PROCESS | 4592 | 18:27:22 INFO - PROCESS | 4592 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.20 Median:69.00 stddev:3.26 (4.7%) stddev-sans-first:3.44 18:27:22 INFO - PROCESS | 4592 | Values: 70.0 67.0 68.0 67.0 77.0 71.0 71.0 67.0 68.0 66.0 18:27:22 INFO - PROCESS | 4592 | -------- Summary: end -------- 18:27:22 INFO - PROCESS | 4592 | 18:27:23 INFO - TEST-INFO | 4592: exit 0 18:27:23 INFO - TEST-OK | kraken | took 65696ms 18:27:23 INFO - SUITE-END | took 1185s 18:27:23 INFO - Completed test suite (00:19:45) 18:27:23 INFO - javascript benchmark 18:27:23 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2643.7091919888817, 2677.705452400237, 2723.305557082944, 2733.617293578899, 2734.7948903832216, 4737.407824021997, 4739.123262196492, 4804.111239420709, 4840.6369522285795, 4870.26945958106, 4783.521647835215, 4789.425268967729, 4791.952080479195, 4821.421429428474, 4831.734254615994, 4378.6934914555995, 4419.602235798778, 4425.269830477967, 4477.888052798689, 4489.551044895501, 4388.363687265344, 4415.249407600716, 4435.977820110895, 4448.110377924428, 4477.10457908418, 4263.25393056217, 4303.397524346594, 4304.31131019037, 4363.65090792739, 4392.868213953587, 4361.084172323815, 4381.868543943687, 4425.513193548707, 4444.066745983348, 4445.422095127613, 4394.274944634131, 4417.18282117806, 4430.623397011258, 4467.620252278561, 4470.776461176928, 4519.096180763828, 4590.288505281686, 4624.075184962987, 4639.512851150615, 4704.435467743859, 5050.570701490377, 5062.924056139161, 5111.591072714211, 5125.256837758541, 5128.384593848725, 5006.774695138727, 5081.59347252219, 5098.668586541863, 5111.974440127813, 5151.253068305112, 4823.035392921394, 4850.490698476679, 4879.024195160982, 4926.729029903389, 4948.777305021283, 5332.5467335276935, 5383.43473935239, 5400.594955378362, 5404.054290499147, 5405.21624364465, 5035.874103147451, 5055.241713742895, 5081.5426611605135, 5083.161278389099, 5086.898262034739, 5056.367954005749, 5137.049645815536, 5159.019786240602, 5196.870078247998, 5230.267762513251, 5171.379434467888, 5175.249588809602, 5184.274201611777, 5190.896182076337, 5235.895282094337, 4976.825811096594, 5149.845504634867, 5169.121249387613, 5171.3277273954145, 5179.715115668599, 4212.49450065994, 4225.3873188387515, 4241.957580424156, 4247.532771395144, 4252.744835309892, 3712.0719820044988, 3716.7769933804057, 3721.7953012584026, 3746.83139258734, 3748.0, 4220.345846393814, 4234.851780187679, 4239.025024244441, 4266.1254442839145, 4266.2960611499375, 5118.488151184926, 5171.5862730981435, 5185.688858668492, 5217.869553261199, 5273.288106105627, 4363.738175709467, 4365.126974605028, 4372.147431250939, 4378.934315985262, 4417.160739459492, 4551.362809206714, 4673.462551806518, 4689.20283551797, 4692.788824502837, 4744.525547445228, 4690.343351930801, 4714.976425117852, 4736.668433209642, 4763.73799441034, 4786.8085276588545, 5054.064997975386, 5060.089183946925, 5063.1898896176435, 5066.06277838601, 5116.71858047796, 4693.55411235932, 4731.101090792738, 4735.573798358164, 4738.265568836836, 4787.114383839001, 4930.926036109389, 5072.9746351268, 5124.897502050013, 5125.513076257749, 5156.355455568054, 5183.766730497061, 5194.246834209094, 5201.531862132426, 5208.791648334024, 5216.061109000417, 4747.548982846763, 5045.344105266292, 5060.0, 5067.41724701664, 5067.847964561069, 4783.06730187624, 4819.277108433763, 4829.710217386968, 4855.295982082649, 4866.124097662455, 4780.521947805191, 4792.7124372537755, 4837.274408838703, 4841.4674385816825, 4842.878928026827, 4901.705897646153, 4932.728699921395, 4939.135651261086, 4955.058538877602, 4971.353724015855, 4855.975720121377, 4913.28757330178, 4920.237363208708, 4921.581665558468, 4934.259861020875], "value": 4686.633441728227, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [552.8120439050722, 609.6494515653499, 617.4134572156449, 621.9720112594932, 637.3721883944316, 27596.896124155035, 30654.08037758868, 46136.769316153455, 47275.0, 47338.0, 42970.0, 43484.0, 43491.565084349226, 43553.78223108888, 43580.0, 7465.178830328659, 7897.723579674713, 8042.7185048523315, 8044.637991290406, 8050.677972881078, 7489.550626962372, 7736.419768517356, 7739.845203095934, 10018.048285412875, 10261.948690256539, 10321.58713651453, 10376.16990640747, 10378.32540884844, 10379.169666426667, 10383.84424233637, 10092.848607270897, 10122.0, 10148.89851101487, 10159.644412445567, 10159.644412445603, 7494.400447964204, 9630.614775409045, 10085.193184545218, 10099.646512372032, 10501.684949451528, 39359.0, 39775.0, 39819.80090099531, 39823.0, 39889.80055099735, 3406.4379377402697, 3580.874669386585, 3649.5255616769914, 3913.099987002977, 3929.0, 29778.40443191146, 30349.544756828665, 30476.238094047603, 30664.693353066406, 31502.52746208809, 2985.5820185174093, 2997.5503674448787, 3016.351484430858, 3038.8024778389336, 3060.3726236121543, 231949.0, 232863.0, 232948.0, 233907.0, 234269.82865085735, 34100.1474963123, 54126.729366353706, 54416.0, 54625.0, 54812.45187548153, 32689.67310326914, 34689.0, 34960.0, 34964.300713985576, 34987.30025399529, 12843.357832108357, 13076.280804555841, 13090.214587124803, 13107.082504224612, 13138.343082846011, 9194.816103678022, 10231.28381013322, 10245.795084098425, 10321.432321222404, 10401.427921464241, 8295.129011453831, 8535.871961920577, 8545.2309292164, 8638.654453821902, 8730.083341249077, 3303.5705358303267, 3305.173706573357, 3318.6847249511256, 3324.667533246656, 3333.4333163362285, 924.0436148586181, 952.8094381123803, 980.4362491567377, 1037.2583602723937, 1045.4459136657583, 28846.7115328844, 29317.26706832346, 29439.705602944126, 29985.0, 30329.0, 893.4996402014892, 1009.4548943570536, 1020.9591616335412, 1026.0304012708068, 1041.1514615588271, 15755.212239387985, 15894.443694470869, 15911.522654320388, 16058.75911861323, 16090.67818643644, 365041.0, 368347.0, 370263.0, 372096.0, 373404.0, 52149.217761733606, 52159.47840521622, 52303.476965229864, 52463.0, 52514.47485525172, 1161.512164890748, 1252.085977236604, 1272.0713878868294, 1285.3765923527083, 1300.596814987357, 29184.562231566968, 30472.542911856784, 30533.23666908345, 30536.236594085327, 30677.410325109005, 38736.0, 38740.6125938737, 39129.2174156521, 39173.8041309803, 39201.411978820914, 268035.0, 268249.0, 269230.0, 270256.0, 271490.0, 23813.404664882823, 24273.0, 26085.869570652027, 26879.596806048317, 27110.1866943992, 42135.36796948109, 42482.0, 43056.78471607622, 43441.34837977496, 43900.56099439092, 35263.471047934814, 36044.47688661768, 36505.0, 36570.26859462743, 36906.8154659225, 35267.64732352746, 35625.0, 35960.46059309165, 36004.0, 36338.4549231767, 35485.82257088698, 35631.28737425185, 40940.59059409487, 41638.791806040776, 41719.37420938749, 32685.509717353783, 34382.0, 34422.31155376828, 34847.0, 35022.64977350194, 25810.049745793996, 26003.399660033847, 26236.737632624194, 26338.86830565835, 26697.86651066732], "value": 17144.15666566042, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [392.14512363048544, 393.8621482481134, 394.76708741842305, 395.9960400395997, 416.0, 12723.1729937554, 13019.414126364312, 16027.198640068009, 17445.738313925285, 17450.56373590661, 16593.66812663746, 17245.223964921577, 17695.46913592594, 17702.0, 18007.27970881163, 5245.265662807201, 5343.358796944372, 5379.327584051994, 5392.245085688007, 5438.483344082326, 3411.9317613647836, 5183.17069268917, 5280.894382112356, 5299.60252981026, 5486.533644640211, 5514.503694667479, 5551.555875529968, 5598.916016259759, 5625.5499560035305, 5626.971865140668, 5554.777808887639, 5590.580706447013, 5602.131669591199, 5630.07103827872, 5655.321361436613, 5273.209018647233, 5414.702191379472, 5427.294451721251, 5435.755391007375, 5463.535599474049, 12281.201721888048, 12634.0, 12641.557545485864, 12641.87358126416, 18114.637707245914, 1884.1332986826076, 1948.298612499499, 2006.107282259395, 2045.120598142798, 2311.214187176368, 9007.369484136048, 9059.59231834569, 9215.21670657995, 9580.56887440067, 9580.90419095807, 1705.8038325592468, 1810.4387639831693, 1874.5594785225462, 1883.0678813987026, 1886.6226754649126, 59597.226283884316, 71598.0, 71823.28176718166, 72347.27652723511, 73135.0, 9677.612895484102, 9908.405495670282, 10345.534450949724, 10386.948065259625, 10519.631812886511, 14572.0, 14640.194789286692, 14679.853201468062, 14687.339069741887, 14690.26548672562, 7172.569645821268, 7201.639918004079, 7289.271072892669, 7371.130156667685, 7388.224236455095, 6518.413342799171, 6706.597604143767, 6714.563553369016, 6781.152355955505, 6870.175578930461, 4394.736315821061, 4397.934030989538, 4444.333349997526, 4450.866474005785, 4467.977660111744, 1548.5431797619729, 1685.578605348663, 1875.9682174803804, 1891.716242563599, 1943.9319623813099, 798.604690678118, 883.6244596046654, 943.9899307740651, 954.2985905359694, 969.6460791811079, 15332.616684582974, 16329.836701632832, 16625.55354117746, 17252.13739313029, 17279.827201728072, 902.7607683963755, 964.4502633498838, 983.8622592837008, 992.0872797026752, 1001.3491230699958, 9460.716178514656, 9558.904410955802, 9697.224222062217, 9801.11789938909, 9823.459709716035, 381884.0, 401298.0, 402422.0, 402947.0, 403568.0, 76592.23407765962, 78406.0, 78433.0, 78521.2147878514, 80699.0, 858.3948316436897, 932.3100905330007, 949.9050094990583, 965.5510187762584, 969.4619486185235, 12871.0, 15440.150791706115, 15540.145292009049, 15685.0, 15822.129782861593, 33853.15367115842, 33984.490232646036, 34149.0, 34196.0, 34624.82687586546, 43808.123837522435, 44939.32591011072, 45598.0, 46528.767356163, 47141.764291179694, 7704.499207551491, 7808.5314881107315, 7983.5209887406945, 8022.799430014296, 8173.836523269621, 8498.872516912375, 11026.44867756625, 11598.88401115978, 11736.589219377282, 12371.814422783846, 9291.349605527548, 10494.422806745702, 10505.31715438494, 10874.510647021061, 11020.118390528898, 10286.434246116236, 10401.479926003518, 10529.947350263199, 10614.1508679307, 10695.89304106949, 10740.83888741685, 10878.29291096108, 10974.615888443866, 10977.231593788678, 11041.944790275997, 7985.241402066795, 8020.197980201933, 8154.0, 8217.917820821955, 8308.70919517814, 9492.95253523728, 9924.354916930377, 10000.099991000845, 10141.49292535385, 10724.73188170302], "value": 8192.487516808542, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1304.0675916719545, 1485.9925700371496, 1526.5878212882521, 1687.072110339314, 1838.9540261493469, 6699.464042876571, 7633.084029916404, 7863.488873223237, 7888.960555197216, 8084.9191508084905, 7810.882836757439, 7981.201879812031, 7981.680732770682, 7987.4408791384485, 7989.041315042203, 1923.1634239106033, 2178.716766820311, 2210.226420752732, 2242.0807468937737, 2291.51878105598, 2269.1604106480627, 2292.862428254302, 2298.37943755186, 2298.643710224918, 2299.5630830142304, 2198.758136604972, 2239.6640503924377, 2253.2001139595454, 2256.5261295128043, 2256.616375216209, 1822.5808064145256, 1891.3474851176459, 1896.6491199128068, 2048.467398476392, 2129.4569884679386, 2193.846430749848, 2221.1781640792847, 2224.2215224671236, 2226.821854251672, 2226.8886555672316, 7835.373170146431, 7870.881936770953, 7872.252136047124, 7890.566018869017, 7899.763007109796, 4711.387519622462, 4840.080384726926, 4869.951300486985, 4873.195922672755, 4876.975615121937, 2689.7579217870484, 2783.262435454606, 2927.575501552284, 2938.147937098239, 2942.8528573571234, 906.0848542971646, 914.8719179314901, 936.3679516326453, 1649.7607846862259, 1731.705610046295, 10892.673219803419, 10950.671479855617, 10997.230193886431, 11004.724881877937, 11010.724731881686, 4032.495938007749, 4198.0, 4216.831326746896, 4251.256030194765, 4278.72188307759, 8602.139786021473, 8622.353323500762, 8693.043765185825, 8700.651973921098, 8702.47785132894, 4710.52894710533, 4734.668573199911, 4739.170645137087, 4744.881377965578, 4747.074320507537, 3787.299349620329, 4139.110091330378, 4235.724677895927, 4241.427407300037, 4286.185624731291, 3387.356402283558, 3411.727061835047, 3457.22212502185, 3505.3515099706124, 3512.5960514541157, 1000.9699709008596, 1003.9057427404165, 1004.8191325561469, 1005.9245556583286, 1015.1979935850595, 860.2816648098908, 873.1312344217545, 890.8129292848574, 896.878921345623, 901.1394118616705, 5436.836894893159, 5651.474101992521, 5720.599558030981, 5730.713464326767, 5788.652680839163, 867.8741582470507, 910.6220918318955, 954.8663187153804, 955.1976339874539, 971.4171497101682, 3681.226942342138, 3792.0898984244136, 3871.728978971445, 3874.4575759393706, 3878.864239751595, 9595.088466595662, 9618.951905240569, 9960.800783984425, 9995.200383969264, 10050.447225402673, 9764.853527197098, 9782.461964592016, 9813.803723925625, 9912.504374781232, 9914.107730304164, 7107.82230444243, 7386.96306518464, 7425.108986921604, 7440.404767618577, 7562.092548894278, 5660.235868157746, 5684.886302274014, 5794.13088036781, 5843.451485356469, 5845.766169353348, 8735.868961965703, 9103.135202155785, 9153.221976132103, 9165.129312715282, 9296.651985462206, 9018.278537716837, 9166.541672916193, 9222.216111630318, 9265.72202833916, 9356.111169438893, 5968.701564921824, 6166.383361663977, 6415.615063096229, 6947.68735406918, 7074.787756367111, 6901.654917254217, 7131.8573628528175, 7139.000539924311, 7825.217478252357, 7827.608619568885, 6811.284815094344, 6936.2023367311385, 6959.373656370748, 7033.859322813618, 7051.365377116084, 7001.789946301619, 7028.472864535078, 7039.260877607982, 7043.013978042975, 7089.220185779457, 6336.366363363627, 6372.330905254881, 6388.552801303864, 6403.64779937108, 6408.29508754046, 4639.953600463952, 4796.616270698406, 4965.975170124127, 4973.204287314013, 4997.050560393659, 4777.116233496814, 4935.555799978019, 4960.677555958851, 4999.45006049327, 5033.219850923113], "value": 4236.198135561317, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [290.56270313178663, 302.08769516061494, 302.9333546619743, 313.80700868965585, 316.6042446941323, 4835.226363781796, 4917.82787602434, 4928.654994150406, 4936.6791158574715, 4952.876178095549, 4599.908001839961, 4624.572099647687, 4728.3380326754195, 4763.666543341968, 4860.975695121529, 3824.2351529694033, 3843.673287770543, 3850.826712797917, 3855.2096820151874, 3870.5161854768153, 3867.0, 3870.3033453978273, 3872.3223435898603, 3876.6898648108086, 3881.6894648428056, 3615.8011309377976, 3721.274351501458, 3828.25349056934, 3841.0973421245985, 3851.7111216658723, 3789.6020917803507, 3792.3742582474133, 3816.93885301938, 3831.214601006787, 3833.11838277195, 3385.1198688340965, 3396.0, 3410.198603328216, 3432.4164891968426, 3453.0504111369323, 4395.428594282755, 4667.382778346774, 4689.788959496796, 4693.859184224479, 4721.126591580569, 4279.293916503773, 4300.096979634281, 4308.547602501723, 4319.76241306728, 4332.7400355978425, 5063.594912407036, 5084.77118529667, 5090.287359769635, 5109.744512774384, 5117.309163262949, 3565.2502435823517, 3638.472421498894, 3647.4164133738473, 3659.670629643345, 3672.540932383452, 5117.360329958755, 5298.443663415324, 5313.840584782462, 5314.123169676998, 5320.791871373375, 3639.890803275906, 3654.3422184006604, 3657.926841463156, 3705.8517659293866, 3717.237966216919, 3755.3615885299564, 3767.56672982605, 3775.847766772913, 3790.9241815164096, 3807.143392736612, 4140.730852494578, 4253.1706317267835, 4293.3130699088, 4332.436783218224, 4333.58830911063, 4978.701277923336, 5017.0, 5026.346574945233, 5073.239014147834, 5086.694798312114, 1227.404708716272, 1280.1998750780763, 1301.0502333296747, 1306.3729409883308, 1313.5468263449095, 840.5167028958373, 845.7293666026935, 856.032683068129, 860.4063196394468, 870.3602851903846, 3964.6431821136234, 3967.1867267210155, 3976.741511801724, 3984.1633257015774, 3997.780122093313, 4580.946382332082, 4624.445066591963, 4625.38111660916, 4664.300354946718, 4670.322803193518, 3932.522510818166, 3952.0317522207242, 3966.7786039817793, 3971.3645816669195, 3972.4239985201984, 2357.26924653358, 2457.557639624885, 2478.045952308338, 2482.7144878338863, 2484.9006039758206, 4317.438732964757, 4328.761918094472, 4330.501992270866, 4338.587834155814, 4364.886953826754, 4633.884152896205, 4666.113438446684, 4686.648501362343, 4695.906081878412, 4705.376645057449, 4292.785360731951, 4309.48286205651, 4314.0, 4509.391232183613, 4971.9005619886675, 5204.843854684365, 5261.263423120689, 5266.868328291824, 5287.180487024518, 5293.576513878804, 5141.2288156776785, 5150.665206761549, 5163.734884953211, 5191.74041297926, 5226.876221612371, 4741.928871066868, 4928.778204980713, 4961.330220420197, 4996.925046124384, 5016.322796422436, 3735.234276973268, 3748.437734339871, 3751.2872554214614, 3753.6246375362243, 3760.984534175812, 5023.824166154167, 5271.389098467562, 5306.442823503477, 5319.973400132974, 5342.385625652944, 4940.456549779593, 5074.568661663652, 5088.898222035465, 5095.235714642833, 5096.694198348111, 5077.619178561548, 5083.491650834887, 5107.927335259637, 5114.667546609458, 5122.129238029618, 4884.291777692286, 4992.952889315363, 5025.597952163891, 5031.547160755549, 5035.269885866455, 5060.49395060491, 5102.260172274925, 5106.540411362995, 5122.667026643257, 5154.329937108302, 4973.726445045412, 5009.772605711624, 5024.296598476143, 5035.320231768665, 5043.344365232496], "value": 3752.1536215593264, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [726.8982342472053, 807.4912804932895, 821.695972490178, 834.0408530190281, 837.2757564706527, 10587.629432969848, 10945.179111566644, 10971.067459265952, 10996.175286853488, 11008.39453830039, 10416.63541776036, 11044.668659940195, 11236.4943577539, 11265.267757595751, 11435.085193184546, 861.8405594964936, 876.0057334924843, 876.1413814461831, 876.5003947749785, 876.8071024374659, 853.7353420439653, 861.5519929636586, 862.560094351882, 862.5859587398053, 863.6847550644032, 910.7176775199677, 913.6299798581551, 913.9908600913973, 915.555955361649, 917.2157805076663, 906.4153620914539, 907.4918045894287, 907.7367563406605, 908.4049947284507, 909.0, 672.193367958447, 674.8211723893172, 677.478341676911, 678.1794029224644, 678.4233401608642, 10438.63464778729, 10488.947555262252, 10543.84184237237, 10620.309679870861, 10635.468226588717, 9956.701298961043, 9992.0, 10062.144717698931, 10508.474576271155, 10629.84055239172, 8217.917820821775, 8222.547759873205, 8238.876416853753, 8437.071922088566, 8451.366147538898, 3265.8856940006985, 3289.7861638993627, 3322.2691007978447, 3356.47974563943, 3391.6099648540485, 34581.65418345809, 35043.0, 39575.60424395748, 40064.79967600173, 42529.0, 9552.474613896164, 9553.140217380469, 9789.510524473748, 9827.508624568743, 10055.69832905014, 17146.485605431855, 17198.82801171972, 17361.65276694459, 17456.912715436596, 17489.21298541569, 6821.795346139624, 6847.21257055445, 6872.278410766898, 7000.124984376953, 7023.789286321418, 3620.637936206358, 3678.4666223397458, 3680.392735198662, 3754.2679177560112, 3774.226283611853, 2759.599858020576, 2885.6248687670886, 2890.580865774493, 2891.407261511385, 2892.580575816537, 518.8662771843534, 525.8685328667833, 529.9364076310791, 530.4218401941824, 537.0, 631.6778442994106, 649.4998850884792, 650.5413683353224, 660.3924389561615, 667.7429189761976, 8116.553589552514, 8482.321414286966, 8543.102974187621, 8632.69785557515, 8676.60955257015, 568.5167607533646, 585.6925114314934, 625.0, 637.1303171171318, 654.2182092399537, 3859.7684138951754, 3950.5259368875345, 3976.383660532564, 4027.1140349123148, 4060.959390406117, 47183.764081179375, 49016.50983490191, 49134.75432622814, 49308.0, 50291.0, 37356.0, 43687.56312436835, 45397.0, 45539.772301138284, 46463.53536464592, 41523.0, 41610.0, 41695.166096677895, 41765.37351939723, 41919.79040104841, 8241.0, 8484.745457636282, 8724.91275087241, 8927.151920567536, 9129.543522823966, 27064.864675677283, 27551.448971020065, 28231.43537129205, 28252.85873570619, 28466.857665711537, 33655.83172084124, 34756.65243347635, 36537.634623654485, 36759.63240367562, 37014.25971480609, 9949.900500994898, 10293.588256469657, 10486.213533984828, 10633.361998280128, 10931.398773067558, 10783.51474183648, 10897.950685583364, 11253.099752019982, 11375.43122843871, 11798.056155507711, 10342.069213770797, 10515.684529464128, 10705.250632455653, 11239.04468120219, 11286.379249141048, 10711.0, 10929.617463388744, 11036.227464077438, 11095.445227738419, 11108.38903860263, 11013.174011948975, 11578.594749183738, 11600.419979001184, 11624.418779061183, 11664.533418663159, 8773.605187766438, 8810.427322224035, 8838.690645827364, 8996.235319997873, 9193.448393096436, 9341.906580934103, 9474.289428292768, 9478.668246611336, 9529.190018848476, 9571.712848614552], "value": 5859.775912210205, "unit": "score"}], "name": "dromaeo_css", "value": 6279.569435181196, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 109.0, 112.0, 113.0, 115.0, 113.0, 115.0, 114.0, 114.0, 113.0], "value": 113.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 135.0, 155.0, 226.0, 131.0, 165.0, 160.0, 130.0, 177.0, 135.0], "value": 157.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 162.0, 161.0, 159.0, 161.0, 161.0, 162.0, 162.0, 160.0, 163.0], "value": 161.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 76.0, 77.0, 155.0, 75.0, 74.0, 76.0, 74.0, 125.0, 76.0], "value": 88.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 92.0, 147.0, 93.0, 92.0, 92.0, 118.0, 93.0, 93.0, 122.0], "value": 103.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 116.0, 116.0, 117.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 145.0, 146.0, 145.0, 145.0, 146.0, 145.0, 145.0, 145.0, 146.0], "value": 145.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 97.0, 94.0, 94.0, 94.0, 94.0, 95.0, 95.0, 94.0], "value": 94.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 69.0, 68.0, 68.0, 69.0, 69.0, 69.0, 129.0, 67.0, 67.0], "value": 74.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 63.0, 63.0, 64.0, 63.0, 64.0, 64.0, 64.0, 63.0, 64.0], "value": 63.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 87.0, 84.0, 81.0, 82.0, 84.0, 84.0, 83.0, 88.0, 83.0], "value": 84.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 128.0, 136.0, 132.0, 129.0, 135.0, 142.0, 144.0, 195.0, 140.0], "value": 142.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 177.0, 184.0, 179.0, 173.0, 178.0, 176.0, 173.0, 176.0, 176.0], "value": 176.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 67.0, 68.0, 67.0, 77.0, 71.0, 71.0, 67.0, 68.0, 66.0], "value": 69.2, "unit": "score"}], "name": "kraken", "value": 1591.7, "alertThreshold": 2.0}]} 18:27:23 INFO - Return code: 0 18:27:23 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 18:27:23 ERROR - # TBPL SUCCESS # 18:27:23 INFO - Running post-action listener: _resource_record_post_action 18:27:23 INFO - ##### 18:27:23 INFO - ##### Finished run-tests step (success) 18:27:23 INFO - ##### 18:27:23 INFO - Running post-run listener: _resource_record_post_run 18:27:24 INFO - Total resource usage - Wall time: 1202s; CPU: 13.0%; Read bytes: 40960; Write bytes: 304476160; Read time: 68; Write time: 453660 18:27:24 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 17403904; Read time: 0; Write time: 13980 18:27:24 INFO - run-tests - Wall time: 1187s; CPU: 13.0%; Read bytes: 40960; Write bytes: 287072256; Read time: 68; Write time: 439680 18:27:24 INFO - Running post-run listener: _upload_blobber_files 18:27:24 INFO - Blob upload gear active. 18:27:24 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:27:24 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 18:27:24 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'] 18:27:24 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 18:27:25 INFO - (blobuploader) - INFO - Open directory for files ... 18:27:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 18:27:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:27:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:27:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 18:27:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:27:25 INFO - (blobuploader) - INFO - Done attempting. 18:27:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 18:27:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:27:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:27:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 18:27:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:27:26 INFO - (blobuploader) - INFO - Done attempting. 18:27:26 INFO - (blobuploader) - INFO - Iteration through files over. 18:27:26 INFO - Return code: 0 18:27:26 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:27:26 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:27:26 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/17b17c6f7e3b6c58617e5e4dd45d81d0191f8dde97a93f87cc87f090bb466b40f474ffd5cd8d0746a4a15494f37b323405a805bad513d3e4f9c0ba36f4649fc1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/e5722c57ac3118ce1c7094cc988d48872b9e1d0e3f69deacfb905781d8668a26ddc9362c05a0c3779bb700ce17ec15f4905a17e09be46cb672aaa38096a3a977"} 18:27:26 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:27:26 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:27:26 INFO - Contents: 18:27:26 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/17b17c6f7e3b6c58617e5e4dd45d81d0191f8dde97a93f87cc87f090bb466b40f474ffd5cd8d0746a4a15494f37b323405a805bad513d3e4f9c0ba36f4649fc1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/e5722c57ac3118ce1c7094cc988d48872b9e1d0e3f69deacfb905781d8668a26ddc9362c05a0c3779bb700ce17ec15f4905a17e09be46cb672aaa38096a3a977"} 18:27:26 INFO - Running post-run listener: copy_logs_to_upload_dir 18:27:26 INFO - Copying logs to upload dir... 18:27:26 INFO - mkdir: /builds/slave/test/build/upload/logs 18:27:26 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1250.372215 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2016-05-10 18:27:26.845976) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-10 18:27:26.849150) ========= 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=aa52b8aba932b19cc50b5275000001de-1462927875.699878-1663118001 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/17b17c6f7e3b6c58617e5e4dd45d81d0191f8dde97a93f87cc87f090bb466b40f474ffd5cd8d0746a4a15494f37b323405a805bad513d3e4f9c0ba36f4649fc1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/e5722c57ac3118ce1c7094cc988d48872b9e1d0e3f69deacfb905781d8668a26ddc9362c05a0c3779bb700ce17ec15f4905a17e09be46cb672aaa38096a3a977"} build_url:https://queue.taskcluster.net/v1/task/e7E-Wzd0TLKoFsJ1WLPbvw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011210 build_url: 'https://queue.taskcluster.net/v1/task/e7E-Wzd0TLKoFsJ1WLPbvw/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/17b17c6f7e3b6c58617e5e4dd45d81d0191f8dde97a93f87cc87f090bb466b40f474ffd5cd8d0746a4a15494f37b323405a805bad513d3e4f9c0ba36f4649fc1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/e5722c57ac3118ce1c7094cc988d48872b9e1d0e3f69deacfb905781d8668a26ddc9362c05a0c3779bb700ce17ec15f4905a17e09be46cb672aaa38096a3a977"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-10 18:27:26.873605) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 18:27:26.873955) ========= 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=aa52b8aba932b19cc50b5275000001de-1462927875.699878-1663118001 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004380 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 18:27:26.959598) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-10 18:27:26.959930) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-10 18:27:26.960239) ========= ========= Total master_lag: 0.18 =========