builder: fx-team_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-008 starttime: 1461767432.98 results: success (0) buildid: 20160427071020 builduid: c68ab51b35d04f3eb59d2ae2ec182e95 revision: ed934827bebaeee8a1aeb1726a3af1021d2a8364 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-27 07:30:32.978094) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-27 07:30:32.978549) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-27 07:30:32.978832) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-27 07:30:32.997540) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-27 07:30:32.997921) ========= 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=32c5991c409366f7fec8a718000001d2-1461767131.869006-389222091 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-04-27 07:30:33-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 44.8M=0s 2016-04-27 07:30:33 (44.8 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.112707 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-27 07:30:33.124163) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-27 07:30:33.124577) ========= 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=32c5991c409366f7fec8a718000001d2-1461767131.869006-389222091 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019571 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-27 07:30:33.174870) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-27 07:30:33.175233) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev ed934827bebaeee8a1aeb1726a3af1021d2a8364 --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 ed934827bebaeee8a1aeb1726a3af1021d2a8364 --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=32c5991c409366f7fec8a718000001d2-1461767131.869006-389222091 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-04-27 07:30:33,680 truncating revision to first 12 chars 2016-04-27 07:30:33,680 Setting DEBUG logging. 2016-04-27 07:30:33,680 attempt 1/10 2016-04-27 07:30:33,680 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/ed934827beba?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-27 07:30:33,987 unpacking tar archive at: fx-team-ed934827beba/testing/mozharness/ program finished with exit code 0 elapsedTime=0.592343 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-27 07:30:33.781636) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-27 07:30:33.782096) ========= script_repo_revision: ed934827bebaeee8a1aeb1726a3af1021d2a8364 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-27 07:30:33.782630) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-27 07:30:33.783033) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-27 07:30:33.794196) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 54 secs) (at 2016-04-27 07:30:33.794643) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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=32c5991c409366f7fec8a718000001d2-1461767131.869006-389222091 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 07:30:34 INFO - MultiFileLogger online at 20160427 07:30:34 in /builds/slave/test 07:30:34 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --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 07:30:34 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:30:34 INFO - {'append_to_log': False, 07:30:34 INFO - 'base_work_dir': '/builds/slave/test', 07:30:34 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 07:30:34 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:30:34 INFO - 'branch': 'Fx-Team-Non-PGO', 07:30:34 INFO - 'buildbot_json_path': 'buildprops.json', 07:30:34 INFO - 'config_files': ('talos/linux_config.py',), 07:30:34 INFO - 'default_actions': ('clobber', 07:30:34 INFO - 'read-buildbot-config', 07:30:34 INFO - 'download-and-extract', 07:30:34 INFO - 'populate-webroot', 07:30:34 INFO - 'create-virtualenv', 07:30:34 INFO - 'install', 07:30:34 INFO - 'run-tests'), 07:30:34 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:30:34 INFO - 'download_minidump_stackwalk': True, 07:30:34 INFO - 'download_symbols': 'ondemand', 07:30:34 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:30:34 INFO - 'tooltool.py': '/tools/tooltool.py', 07:30:34 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:30:34 INFO - '/tools/misc-python/virtualenv.py')}, 07:30:34 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:30:34 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:30:34 INFO - 'installer_path': 'installer.exe', 07:30:34 INFO - 'log_level': 'info', 07:30:34 INFO - 'log_name': 'talos', 07:30:34 INFO - 'log_to_console': True, 07:30:34 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 07:30:34 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 07:30:34 INFO - 'opt_config_files': (), 07:30:34 INFO - 'pip_index': False, 07:30:34 INFO - 'sps_profile': False, 07:30:34 INFO - 'sps_profile_interval': 0, 07:30:34 INFO - 'suite': 'dromaeojs-e10s', 07:30:34 INFO - 'system_bits': '32', 07:30:34 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:30:34 INFO - 'title': 'talos-linux64-ix-008', 07:30:34 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:30:34 INFO - 'use_talos_json': True, 07:30:34 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:30:34 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:30:34 INFO - 'work_dir': 'build'} 07:30:34 INFO - ##### 07:30:34 INFO - ##### Running clobber step. 07:30:34 INFO - ##### 07:30:34 INFO - Running pre-action listener: _resource_record_pre_action 07:30:34 INFO - Running main action method: clobber 07:30:34 INFO - rmtree: /builds/slave/test/build 07:30:34 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:30:35 INFO - Running post-action listener: _resource_record_post_action 07:30:35 INFO - ##### 07:30:35 INFO - ##### Running read-buildbot-config step. 07:30:35 INFO - ##### 07:30:35 INFO - Running pre-action listener: _resource_record_pre_action 07:30:35 INFO - Running main action method: read_buildbot_config 07:30:35 INFO - Using buildbot properties: 07:30:35 INFO - { 07:30:35 INFO - "project": "", 07:30:35 INFO - "product": "firefox", 07:30:35 INFO - "script_repo_revision": "production", 07:30:35 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs-e10s", 07:30:35 INFO - "repository": "", 07:30:35 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs-e10s", 07:30:35 INFO - "buildid": "20160427071020", 07:30:35 INFO - "pgo_build": "False", 07:30:35 INFO - "basedir": "/builds/slave/test", 07:30:35 INFO - "buildnumber": 1221, 07:30:35 INFO - "slavename": "talos-linux64-ix-008", 07:30:35 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 07:30:35 INFO - "platform": "ubuntu64_hw", 07:30:35 INFO - "scheduler": "tests-fx-team-linux64-talos", 07:30:35 INFO - "branch": "fx-team", 07:30:35 INFO - "repo_path": "integration/fx-team", 07:30:35 INFO - "revision": "ed934827bebaeee8a1aeb1726a3af1021d2a8364", 07:30:35 INFO - "stage_platform": "linux64", 07:30:35 INFO - "builduid": "c68ab51b35d04f3eb59d2ae2ec182e95", 07:30:35 INFO - "slavebuilddir": "test" 07:30:35 INFO - } 07:30:35 INFO - Found installer url https://queue.taskcluster.net/v1/task/JNpWz5iKRZqd5ze4Ucr82g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 07:30:35 INFO - Running post-action listener: _resource_record_post_action 07:30:35 INFO - ##### 07:30:35 INFO - ##### Running download-and-extract step. 07:30:35 INFO - ##### 07:30:35 INFO - Running pre-action listener: _resource_record_pre_action 07:30:35 INFO - Running main action method: download_and_extract 07:30:35 INFO - mkdir: /builds/slave/test/build/tests 07:30:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:30:35 INFO - https://queue.taskcluster.net/v1/task/JNpWz5iKRZqd5ze4Ucr82g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 07:30:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JNpWz5iKRZqd5ze4Ucr82g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 07:30:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JNpWz5iKRZqd5ze4Ucr82g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 07:30:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JNpWz5iKRZqd5ze4Ucr82g/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 07:30:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JNpWz5iKRZqd5ze4Ucr82g/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 07:30:36 INFO - Downloaded 1361 bytes. 07:30:36 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 07:30:36 INFO - Using the following test package requirements: 07:30:36 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 07:30:36 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 07:30:36 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 07:30:36 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 07:30:36 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 07:30:36 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 07:30:36 INFO - u'jsshell-linux-x86_64.zip'], 07:30:36 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 07:30:36 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 07:30:36 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 07:30:36 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 07:30:36 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 07:30:36 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 07:30:36 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 07:30:36 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 07:30:36 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 07:30:36 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 07:30:36 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 07:30:36 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 07:30: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')]} 07:30:36 INFO - https://queue.taskcluster.net/v1/task/JNpWz5iKRZqd5ze4Ucr82g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 07:30:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JNpWz5iKRZqd5ze4Ucr82g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 07:30:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JNpWz5iKRZqd5ze4Ucr82g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 07:30:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JNpWz5iKRZqd5ze4Ucr82g/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 07:30:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JNpWz5iKRZqd5ze4Ucr82g/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 07:30:37 INFO - Downloaded 22294896 bytes. 07:30:37 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 07:30:37 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 07:30:38 INFO - Return code: 0 07:30:38 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 07:30: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')]} 07:30:38 INFO - https://queue.taskcluster.net/v1/task/JNpWz5iKRZqd5ze4Ucr82g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 07:30:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JNpWz5iKRZqd5ze4Ucr82g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 07:30:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JNpWz5iKRZqd5ze4Ucr82g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 07:30:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JNpWz5iKRZqd5ze4Ucr82g/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 07:30:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JNpWz5iKRZqd5ze4Ucr82g/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 07:30:40 INFO - Downloaded 22294896 bytes. 07:30:40 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'] 07:30:40 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 07:30:42 INFO - Return code: 0 07:30:42 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')]} 07:30:42 INFO - https://queue.taskcluster.net/v1/task/JNpWz5iKRZqd5ze4Ucr82g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 07:30:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JNpWz5iKRZqd5ze4Ucr82g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 07:30:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JNpWz5iKRZqd5ze4Ucr82g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 07:30:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JNpWz5iKRZqd5ze4Ucr82g/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 07:30:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JNpWz5iKRZqd5ze4Ucr82g/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 07:30:44 INFO - Downloaded 10840145 bytes. 07:30:44 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'] 07:30:44 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 07:30:44 INFO - Return code: 0 07:30:44 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')]} 07:30:44 INFO - https://queue.taskcluster.net/v1/task/JNpWz5iKRZqd5ze4Ucr82g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 07:30:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JNpWz5iKRZqd5ze4Ucr82g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 07:30:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JNpWz5iKRZqd5ze4Ucr82g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 07:30:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JNpWz5iKRZqd5ze4Ucr82g/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 07:30:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JNpWz5iKRZqd5ze4Ucr82g/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 07:30:46 INFO - Downloaded 54815054 bytes. 07:30:46 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/JNpWz5iKRZqd5ze4Ucr82g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 07:30:46 INFO - mkdir: /builds/slave/test/properties 07:30:46 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:30:46 INFO - Writing to file /builds/slave/test/properties/build_url 07:30:46 INFO - Contents: 07:30:46 INFO - build_url:https://queue.taskcluster.net/v1/task/JNpWz5iKRZqd5ze4Ucr82g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 07:30:47 INFO - Running post-action listener: _resource_record_post_action 07:30:47 INFO - Running post-action listener: set_extra_try_arguments 07:30:47 INFO - ##### 07:30:47 INFO - ##### Running populate-webroot step. 07:30:47 INFO - ##### 07:30:47 INFO - Running pre-action listener: _resource_record_pre_action 07:30:47 INFO - Running main action method: populate_webroot 07:30:47 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:30:47 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:30:47 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:30:47 INFO - '--tppagecycles', 07:30:47 INFO - '7'], 07:30:47 INFO - 'tests': ['tsvgm']}}, 07:30:47 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:30:47 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:30:47 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:30:47 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:30:47 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:30:47 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:30:47 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:30:47 INFO - 'talos_options': ['--disable-e10s'], 07:30:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:30:47 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:30:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:30:47 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:30:47 INFO - 'talos_options': ['--disable-e10s'], 07:30:47 INFO - 'tests': ['damp', 'tps']}, 07:30:47 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:30:47 INFO - 'tests': ['damp', 'tps']}, 07:30:47 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:30:47 INFO - 'tests': ['dromaeo_dom']}, 07:30:47 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:30:47 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:30:47 INFO - 'tests': ['a11yr', 07:30:47 INFO - 'ts_paint', 07:30:47 INFO - 'tpaint', 07:30:47 INFO - 'sessionrestore', 07:30:47 INFO - 'sessionrestore_no_auto_restore', 07:30:47 INFO - 'tabpaint']}, 07:30:47 INFO - 'other-e10s': {'tests': ['a11yr', 07:30:47 INFO - 'ts_paint', 07:30:47 INFO - 'tpaint', 07:30:47 INFO - 'sessionrestore', 07:30:47 INFO - 'sessionrestore_no_auto_restore', 07:30:47 INFO - 'tabpaint']}, 07:30:47 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:30:47 INFO - 'ts_paint', 07:30:47 INFO - 'tpaint', 07:30:47 INFO - 'sessionrestore', 07:30:47 INFO - 'sessionrestore_no_auto_restore', 07:30:47 INFO - 'tabpaint']}, 07:30:47 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:30:47 INFO - 'ts_paint', 07:30:47 INFO - 'tpaint', 07:30:47 INFO - 'sessionrestore', 07:30:47 INFO - 'sessionrestore_no_auto_restore', 07:30:47 INFO - 'tabpaint']}, 07:30:47 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:30:47 INFO - 'tests': ['a11yr', 07:30:47 INFO - 'ts_paint', 07:30:47 INFO - 'tpaint', 07:30:47 INFO - 'sessionrestore', 07:30:47 INFO - 'sessionrestore_no_auto_restore', 07:30:47 INFO - 'tabpaint']}, 07:30:47 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:30:47 INFO - 'tests': ['a11yr', 07:30:47 INFO - 'ts_paint', 07:30:47 INFO - 'tpaint', 07:30:47 INFO - 'sessionrestore', 07:30:47 INFO - 'sessionrestore_no_auto_restore', 07:30:47 INFO - 'tabpaint']}, 07:30:47 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:30:47 INFO - 'tests': ['tsvgx', 07:30:47 INFO - 'tsvgr_opacity', 07:30:47 INFO - 'tart', 07:30:47 INFO - 'tscrollx', 07:30:47 INFO - 'cart']}, 07:30:47 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:30:47 INFO - 'tsvgr_opacity', 07:30:47 INFO - 'tart', 07:30:47 INFO - 'tscrollx', 07:30:47 INFO - 'cart']}, 07:30:47 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:30:47 INFO - 'talos_options': ['--disable-e10s'], 07:30:47 INFO - 'tests': ['tp5o']}, 07:30:47 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:30:47 INFO - 'tests': ['tp5o']}, 07:30:47 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:30:47 INFO - 'talos_options': ['--disable-e10s', 07:30:47 INFO - '--xperf_path', 07:30:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:30:47 INFO - 'tests': ['tp5n']}, 07:30:47 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:30:47 INFO - 'talos_options': ['--xperf_path', 07:30:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:30:47 INFO - 'tests': ['tp5n']}}, 07:30:47 INFO - 'talos.zip': {'path': '', 07:30:47 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:30:47 INFO - Running post-action listener: _resource_record_post_action 07:30:47 INFO - ##### 07:30:47 INFO - ##### Running create-virtualenv step. 07:30:47 INFO - ##### 07:30:47 INFO - Running pre-action listener: _resource_record_pre_action 07:30:47 INFO - Running main action method: create_virtualenv 07:30:47 INFO - Creating virtualenv /builds/slave/test/build/venv 07:30:47 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 07:30:47 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:30:47 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:30:47 INFO - Using real prefix '/usr' 07:30:47 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:30:48 INFO - Installing distribute.............................................................................................................................................................................................done. 07:30:50 INFO - Installing pip.................done. 07:30:50 INFO - Return code: 0 07:30:50 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:30:50 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')]} 07:30:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:30:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:30:50 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')]} 07:30:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:30:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:30:50 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 0x7f363fadbf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18c48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x198f5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a1e410>, '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 0x1a1f310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a1f7a0>, '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': '32c5991c409366f7fec8a718000001d2-1461767131.869006-389222091', '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 07:30:50 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 07:30:50 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 07:30:50 INFO - Using env: {'DISPLAY': ':0', 07:30:50 INFO - 'HOME': '/home/cltbld', 07:30:50 INFO - 'LANG': 'en_US.UTF-8', 07:30:50 INFO - 'LANGUAGE': 'en_US:en', 07:30:50 INFO - 'LOGNAME': 'cltbld', 07:30:50 INFO - 'MAIL': '/var/mail/cltbld', 07:30:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:30:50 INFO - 'MOZ_NO_REMOTE': '1', 07:30:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:30:50 INFO - 'NO_EM_RESTART': '1', 07:30:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:30:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:30:50 INFO - 'PWD': '/builds/slave/test', 07:30:50 INFO - 'SHELL': '/bin/bash', 07:30:50 INFO - 'SHLVL': '1', 07:30:50 INFO - 'TERM': 'linux', 07:30:50 INFO - 'TMOUT': '86400', 07:30:50 INFO - 'USER': 'cltbld', 07:30:50 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1461767131.869006-389222091', 07:30:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:30:50 INFO - '_': '/tools/buildbot/bin/python'} 07:30:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:30:50 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 07:30:50 INFO - Cleaning up... 07:30:50 INFO - Return code: 0 07:30:50 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 07:30:50 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')]} 07:30:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:30:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:30:50 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')]} 07:30:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:30:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:30:50 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 0x7f363fadbf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18c48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x198f5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a1e410>, '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 0x1a1f310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a1f7a0>, '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': '32c5991c409366f7fec8a718000001d2-1461767131.869006-389222091', '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 07:30:50 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 07:30:50 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 07:30:50 INFO - Using env: {'DISPLAY': ':0', 07:30:50 INFO - 'HOME': '/home/cltbld', 07:30:50 INFO - 'LANG': 'en_US.UTF-8', 07:30:50 INFO - 'LANGUAGE': 'en_US:en', 07:30:50 INFO - 'LOGNAME': 'cltbld', 07:30:50 INFO - 'MAIL': '/var/mail/cltbld', 07:30:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:30:50 INFO - 'MOZ_NO_REMOTE': '1', 07:30:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:30:50 INFO - 'NO_EM_RESTART': '1', 07:30:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:30:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:30:50 INFO - 'PWD': '/builds/slave/test', 07:30:50 INFO - 'SHELL': '/bin/bash', 07:30:50 INFO - 'SHLVL': '1', 07:30:50 INFO - 'TERM': 'linux', 07:30:50 INFO - 'TMOUT': '86400', 07:30:50 INFO - 'USER': 'cltbld', 07:30:50 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1461767131.869006-389222091', 07:30:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:30:50 INFO - '_': '/tools/buildbot/bin/python'} 07:30:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:30:50 INFO - Downloading/unpacking psutil>=0.7.1 07:30:50 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 07:30:50 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 07:30:50 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 07:30:50 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 07:30:50 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 07:30:50 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 07:30:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:30:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:30:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:30:52 INFO - Installing collected packages: psutil 07:30:52 INFO - Running setup.py install for psutil 07:30:52 INFO - building 'psutil._psutil_linux' extension 07:30:52 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 07:30:52 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 07:30:52 INFO - building 'psutil._psutil_posix' extension 07:30:52 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 07:30:53 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 07:30:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:30:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:30:53 INFO - Successfully installed psutil 07:30:53 INFO - Cleaning up... 07:30:53 INFO - Return code: 0 07:30:53 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 07:30:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:30:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:30:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:30:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:30:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:30:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:30:53 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 0x7f363fadbf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18c48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x198f5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a1e410>, '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 0x1a1f310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a1f7a0>, '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': '32c5991c409366f7fec8a718000001d2-1461767131.869006-389222091', '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 07:30:53 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 07:30:53 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 07:30:53 INFO - Using env: {'DISPLAY': ':0', 07:30:53 INFO - 'HOME': '/home/cltbld', 07:30:53 INFO - 'LANG': 'en_US.UTF-8', 07:30:53 INFO - 'LANGUAGE': 'en_US:en', 07:30:53 INFO - 'LOGNAME': 'cltbld', 07:30:53 INFO - 'MAIL': '/var/mail/cltbld', 07:30:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:30:53 INFO - 'MOZ_NO_REMOTE': '1', 07:30:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:30:53 INFO - 'NO_EM_RESTART': '1', 07:30:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:30:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:30:53 INFO - 'PWD': '/builds/slave/test', 07:30:53 INFO - 'SHELL': '/bin/bash', 07:30:53 INFO - 'SHLVL': '1', 07:30:53 INFO - 'TERM': 'linux', 07:30:53 INFO - 'TMOUT': '86400', 07:30:53 INFO - 'USER': 'cltbld', 07:30:53 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1461767131.869006-389222091', 07:30:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:30:53 INFO - '_': '/tools/buildbot/bin/python'} 07:30:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:30:53 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 07:30:53 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 07:30:53 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 07:30:53 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 07:30:53 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 07:30:53 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 07:30:53 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 07:30:55 INFO - Downloading mozsystemmonitor-0.0.tar.gz 07:30:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:30:55 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 07:30:55 INFO - Installing collected packages: mozsystemmonitor 07:30:55 INFO - Running setup.py install for mozsystemmonitor 07:30:55 INFO - Successfully installed mozsystemmonitor 07:30:55 INFO - Cleaning up... 07:30:55 INFO - Return code: 0 07:30:55 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:30:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:30:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:30:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:30:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:30:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:30:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:30:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f363fadbf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18c48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x198f5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a1e410>, '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 0x1a1f310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a1f7a0>, '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': '32c5991c409366f7fec8a718000001d2-1461767131.869006-389222091', '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 07:30:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 07:30:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 07:30:55 INFO - Using env: {'DISPLAY': ':0', 07:30:55 INFO - 'HOME': '/home/cltbld', 07:30:55 INFO - 'LANG': 'en_US.UTF-8', 07:30:55 INFO - 'LANGUAGE': 'en_US:en', 07:30:55 INFO - 'LOGNAME': 'cltbld', 07:30:55 INFO - 'MAIL': '/var/mail/cltbld', 07:30:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:30:55 INFO - 'MOZ_NO_REMOTE': '1', 07:30:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:30:55 INFO - 'NO_EM_RESTART': '1', 07:30:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:30:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:30:55 INFO - 'PWD': '/builds/slave/test', 07:30:55 INFO - 'SHELL': '/bin/bash', 07:30:55 INFO - 'SHLVL': '1', 07:30:55 INFO - 'TERM': 'linux', 07:30:55 INFO - 'TMOUT': '86400', 07:30:55 INFO - 'USER': 'cltbld', 07:30:55 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1461767131.869006-389222091', 07:30:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:30:55 INFO - '_': '/tools/buildbot/bin/python'} 07:30:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:30:55 INFO - Downloading/unpacking blobuploader==1.2.4 07:30:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:30:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:30:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:30:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:30:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:30:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:30:57 INFO - Downloading blobuploader-1.2.4.tar.gz 07:30:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:30:57 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:30:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:30:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:30:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:30:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:30:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:30:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:30:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:30:58 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:30:58 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 07:30:58 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 07:30:58 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 07:30:58 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 07:30:58 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 07:30:58 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 07:30:58 INFO - Downloading docopt-0.6.1.tar.gz 07:30:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:30:58 INFO - Installing collected packages: blobuploader, requests, docopt 07:30:58 INFO - Running setup.py install for blobuploader 07:30:58 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:30:58 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:30:58 INFO - Running setup.py install for requests 07:30:58 INFO - Running setup.py install for docopt 07:30:58 INFO - Successfully installed blobuploader requests docopt 07:30:58 INFO - Cleaning up... 07:30:58 INFO - Return code: 0 07:30:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:30:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:30:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:30:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:30:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:30:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:30:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:30:58 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 0x7f363fadbf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18c48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x198f5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a1e410>, '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 0x1a1f310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a1f7a0>, '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': '32c5991c409366f7fec8a718000001d2-1461767131.869006-389222091', '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 07:30:58 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 07:30:58 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 07:30:58 INFO - Using env: {'DISPLAY': ':0', 07:30:58 INFO - 'HOME': '/home/cltbld', 07:30:58 INFO - 'LANG': 'en_US.UTF-8', 07:30:58 INFO - 'LANGUAGE': 'en_US:en', 07:30:58 INFO - 'LOGNAME': 'cltbld', 07:30:58 INFO - 'MAIL': '/var/mail/cltbld', 07:30:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:30:58 INFO - 'MOZ_NO_REMOTE': '1', 07:30:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:30:58 INFO - 'NO_EM_RESTART': '1', 07:30:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:30:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:30:58 INFO - 'PWD': '/builds/slave/test', 07:30:58 INFO - 'SHELL': '/bin/bash', 07:30:58 INFO - 'SHLVL': '1', 07:30:58 INFO - 'TERM': 'linux', 07:30:58 INFO - 'TMOUT': '86400', 07:30:58 INFO - 'USER': 'cltbld', 07:30:58 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1461767131.869006-389222091', 07:30:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:30:58 INFO - '_': '/tools/buildbot/bin/python'} 07:30:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:30:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:30:59 INFO - Running setup.py (path:/tmp/pip-XIxe2r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:30:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:30:59 INFO - Running setup.py (path:/tmp/pip-oVYw4z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:30:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:30:59 INFO - Running setup.py (path:/tmp/pip-wta2Os-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:30:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:30:59 INFO - Running setup.py (path:/tmp/pip-SU46nR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:30:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:30:59 INFO - Running setup.py (path:/tmp/pip-MUo0VL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:30:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:30:59 INFO - Running setup.py (path:/tmp/pip-puQh9h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:30:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:30:59 INFO - Running setup.py (path:/tmp/pip-mC34lB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:30:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:30:59 INFO - Running setup.py (path:/tmp/pip-DdXzQk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:30:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:30:59 INFO - Running setup.py (path:/tmp/pip-sO_bUm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:30:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:30:59 INFO - Running setup.py (path:/tmp/pip-OhlpHD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:30:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:30:59 INFO - Running setup.py (path:/tmp/pip-ij1iN5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:30:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:30:59 INFO - Running setup.py (path:/tmp/pip-U0bLlO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:31:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:31:00 INFO - Running setup.py (path:/tmp/pip-SRgcM3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:31:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:31:00 INFO - Running setup.py (path:/tmp/pip-BN7z9o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:31:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:31:00 INFO - Running setup.py (path:/tmp/pip-Q9_wP9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:31:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:31:00 INFO - Running setup.py (path:/tmp/pip-ouMgf2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:31:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:31:00 INFO - Running setup.py (path:/tmp/pip-1vym3_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:31:00 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:31:00 INFO - Running setup.py install for manifestparser 07:31:00 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:31:00 INFO - Running setup.py install for mozcrash 07:31:00 INFO - Running setup.py install for mozdebug 07:31:00 INFO - Running setup.py install for mozdevice 07:31:00 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:31:00 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:31:00 INFO - Running setup.py install for mozfile 07:31:00 INFO - Running setup.py install for mozhttpd 07:31:00 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:31:00 INFO - Running setup.py install for mozinfo 07:31:01 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:31:01 INFO - Running setup.py install for mozInstall 07:31:01 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:31:01 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:31:01 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:31:01 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:31:01 INFO - Running setup.py install for mozleak 07:31:01 INFO - Running setup.py install for mozlog 07:31:01 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:31:01 INFO - Running setup.py install for moznetwork 07:31:01 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:31:01 INFO - Running setup.py install for mozprocess 07:31:01 INFO - Running setup.py install for mozprofile 07:31:01 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:31:01 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:31:01 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:31:01 INFO - Running setup.py install for mozrunner 07:31:01 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:31:01 INFO - Running setup.py install for mozscreenshot 07:31:01 INFO - Running setup.py install for moztest 07:31:02 INFO - Running setup.py install for mozversion 07:31:02 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:31:02 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:31:02 INFO - Cleaning up... 07:31:02 INFO - Return code: 0 07:31:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:31: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')]} 07:31:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:31:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:31: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')]} 07:31:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:31:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:31:02 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 0x7f363fadbf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18c48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x198f5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a1e410>, '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 0x1a1f310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a1f7a0>, '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': '32c5991c409366f7fec8a718000001d2-1461767131.869006-389222091', '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 07:31:02 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 07:31:02 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 07:31:02 INFO - Using env: {'DISPLAY': ':0', 07:31:02 INFO - 'HOME': '/home/cltbld', 07:31:02 INFO - 'LANG': 'en_US.UTF-8', 07:31:02 INFO - 'LANGUAGE': 'en_US:en', 07:31:02 INFO - 'LOGNAME': 'cltbld', 07:31:02 INFO - 'MAIL': '/var/mail/cltbld', 07:31:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:31:02 INFO - 'MOZ_NO_REMOTE': '1', 07:31:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:31:02 INFO - 'NO_EM_RESTART': '1', 07:31:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:31:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:31:02 INFO - 'PWD': '/builds/slave/test', 07:31:02 INFO - 'SHELL': '/bin/bash', 07:31:02 INFO - 'SHLVL': '1', 07:31:02 INFO - 'TERM': 'linux', 07:31:02 INFO - 'TMOUT': '86400', 07:31:02 INFO - 'USER': 'cltbld', 07:31:02 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1461767131.869006-389222091', 07:31:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:31:02 INFO - '_': '/tools/buildbot/bin/python'} 07:31:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:31:02 INFO - Running setup.py (path:/tmp/pip-X5q310-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:31:02 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)) 07:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:31:02 INFO - Running setup.py (path:/tmp/pip-Hrsnl6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:31:02 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)) 07:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:31:02 INFO - Running setup.py (path:/tmp/pip-EpcTgZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:31:02 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)) 07:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:31:02 INFO - Running setup.py (path:/tmp/pip-GQGUqc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:31:02 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)) 07:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:31:02 INFO - Running setup.py (path:/tmp/pip-75fUUs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:31:02 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)) 07:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:31:02 INFO - Running setup.py (path:/tmp/pip-L_cF5t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:31:02 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)) 07:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:31:02 INFO - Running setup.py (path:/tmp/pip-rlLhfz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:31:02 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)) 07:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:31:02 INFO - Running setup.py (path:/tmp/pip-_kpFGZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:31:02 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)) 07:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:31:02 INFO - Running setup.py (path:/tmp/pip-l_MRF6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:31:03 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)) 07:31:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:31:03 INFO - Running setup.py (path:/tmp/pip-pMYmQA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:31:03 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)) 07:31:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:31:03 INFO - Running setup.py (path:/tmp/pip-2FiNcV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:31:03 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)) 07:31:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:31:03 INFO - Running setup.py (path:/tmp/pip-I6SpNM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:31:03 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)) 07:31:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:31:03 INFO - Running setup.py (path:/tmp/pip-DRYbJH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:31:03 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)) 07:31:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:31:03 INFO - Running setup.py (path:/tmp/pip-6St1sF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:31:03 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)) 07:31:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:31:03 INFO - Running setup.py (path:/tmp/pip-KimcoE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:31:03 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)) 07:31:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:31:03 INFO - Running setup.py (path:/tmp/pip-avACim-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:31:03 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)) 07:31:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:31:03 INFO - Running setup.py (path:/tmp/pip-yFf2UU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:31:03 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)) 07:31:03 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)) 07:31:03 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)) 07:31:03 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)) 07:31:03 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)) 07:31:03 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)) 07:31:03 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:31:03 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 07:31:03 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 07:31:03 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 07:31:03 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 07:31:03 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 07:31:03 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 07:31:05 INFO - Downloading blessings-1.6.tar.gz 07:31:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:31:05 INFO - Installing collected packages: blessings 07:31:05 INFO - Running setup.py install for blessings 07:31:05 INFO - Successfully installed blessings 07:31:05 INFO - Cleaning up... 07:31:05 INFO - Return code: 0 07:31:05 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:31:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:31:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:31:05 INFO - Reading from file tmpfile_stdout 07:31:05 INFO - Current package versions: 07:31:05 INFO - argparse == 1.2.1 07:31:05 INFO - blessings == 1.6 07:31:05 INFO - blobuploader == 1.2.4 07:31:05 INFO - docopt == 0.6.1 07:31:05 INFO - manifestparser == 1.1 07:31:05 INFO - mozInstall == 1.12 07:31:05 INFO - mozcrash == 0.17 07:31:05 INFO - mozdebug == 0.1 07:31:05 INFO - mozdevice == 0.48 07:31:05 INFO - mozfile == 1.2 07:31:05 INFO - mozhttpd == 0.7 07:31:05 INFO - mozinfo == 0.9 07:31:05 INFO - mozleak == 0.1 07:31:05 INFO - mozlog == 3.1 07:31:05 INFO - moznetwork == 0.27 07:31:05 INFO - mozprocess == 0.22 07:31:05 INFO - mozprofile == 0.28 07:31:05 INFO - mozrunner == 6.11 07:31:05 INFO - mozscreenshot == 0.1 07:31:05 INFO - mozsystemmonitor == 0.0 07:31:05 INFO - moztest == 0.7 07:31:05 INFO - mozversion == 1.4 07:31:05 INFO - psutil == 3.1.1 07:31:05 INFO - requests == 1.2.3 07:31:05 INFO - wsgiref == 0.1.2 07:31:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:31:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:31:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:31:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:31:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:31:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:31:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:31:05 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 0x7f363fadbf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18c48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x198f5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a1e410>, '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 0x1a1f310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a1f7a0>, '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': '32c5991c409366f7fec8a718000001d2-1461767131.869006-389222091', '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 07:31:05 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 07:31:05 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 07:31:05 INFO - Using env: {'DISPLAY': ':0', 07:31:05 INFO - 'HOME': '/home/cltbld', 07:31:05 INFO - 'LANG': 'en_US.UTF-8', 07:31:05 INFO - 'LANGUAGE': 'en_US:en', 07:31:05 INFO - 'LOGNAME': 'cltbld', 07:31:05 INFO - 'MAIL': '/var/mail/cltbld', 07:31:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:31:05 INFO - 'MOZ_NO_REMOTE': '1', 07:31:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:31:05 INFO - 'NO_EM_RESTART': '1', 07:31:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:31:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:31:05 INFO - 'PWD': '/builds/slave/test', 07:31:05 INFO - 'SHELL': '/bin/bash', 07:31:05 INFO - 'SHLVL': '1', 07:31:05 INFO - 'TERM': 'linux', 07:31:05 INFO - 'TMOUT': '86400', 07:31:05 INFO - 'USER': 'cltbld', 07:31:05 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1461767131.869006-389222091', 07:31:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:31:05 INFO - '_': '/tools/buildbot/bin/python'} 07:31:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:31:06 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)) 07:31:06 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)) 07:31:06 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)) 07:31:06 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)) 07:31:06 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)) 07:31:06 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)) 07:31:06 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)) 07:31:06 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)) 07:31:06 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)) 07:31:06 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)) 07:31:06 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)) 07:31:06 INFO - Cleaning up... 07:31:06 INFO - Return code: 0 07:31:06 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:31:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:31:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:31:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:31:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:31:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:31:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:31:06 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 0x7f363fadbf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18c48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x198f5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a1e410>, '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 0x1a1f310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a1f7a0>, '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': '32c5991c409366f7fec8a718000001d2-1461767131.869006-389222091', '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 07:31:06 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 07:31:06 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 07:31:06 INFO - Using env: {'DISPLAY': ':0', 07:31:06 INFO - 'HOME': '/home/cltbld', 07:31:06 INFO - 'LANG': 'en_US.UTF-8', 07:31:06 INFO - 'LANGUAGE': 'en_US:en', 07:31:06 INFO - 'LOGNAME': 'cltbld', 07:31:06 INFO - 'MAIL': '/var/mail/cltbld', 07:31:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:31:06 INFO - 'MOZ_NO_REMOTE': '1', 07:31:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:31:06 INFO - 'NO_EM_RESTART': '1', 07:31:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:31:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:31:06 INFO - 'PWD': '/builds/slave/test', 07:31:06 INFO - 'SHELL': '/bin/bash', 07:31:06 INFO - 'SHLVL': '1', 07:31:06 INFO - 'TERM': 'linux', 07:31:06 INFO - 'TMOUT': '86400', 07:31:06 INFO - 'USER': 'cltbld', 07:31:06 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1461767131.869006-389222091', 07:31:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:31:06 INFO - '_': '/tools/buildbot/bin/python'} 07:31:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:31:06 INFO - Downloading/unpacking jsonschema 07:31: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 07:31: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 07:31: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 07:31: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 07:31: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 07:31: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 07:31:08 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:31:08 INFO - Downloading/unpacking functools32 (from jsonschema) 07:31:08 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 07:31:08 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 07:31:08 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 07:31:08 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 07:31:08 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 07:31:08 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 07:31:08 INFO - Downloading functools32-3.2.3-2.tar.gz 07:31:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:31:08 INFO - warning: no files found matching '*.txt' 07:31:08 INFO - no previously-included directories found matching 'build' 07:31:08 INFO - no previously-included directories found matching 'dist' 07:31:08 INFO - no previously-included directories found matching '.git*' 07:31:08 INFO - Installing collected packages: jsonschema, functools32 07:31:08 INFO - Running setup.py install for functools32 07:31:08 INFO - warning: no files found matching '*.txt' 07:31:08 INFO - no previously-included directories found matching 'build' 07:31:08 INFO - no previously-included directories found matching 'dist' 07:31:08 INFO - no previously-included directories found matching '.git*' 07:31:08 INFO - Successfully installed jsonschema functools32 07:31:08 INFO - Cleaning up... 07:31:08 INFO - Return code: 0 07:31:08 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 07:31:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:31:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:31:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:31:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:31:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:31:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:31:08 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 0x7f363fadbf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18c48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x198f5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a1e410>, '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 0x1a1f310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a1f7a0>, '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': '32c5991c409366f7fec8a718000001d2-1461767131.869006-389222091', '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 07:31:08 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 07:31:08 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 07:31:08 INFO - Using env: {'DISPLAY': ':0', 07:31:08 INFO - 'HOME': '/home/cltbld', 07:31:08 INFO - 'LANG': 'en_US.UTF-8', 07:31:08 INFO - 'LANGUAGE': 'en_US:en', 07:31:08 INFO - 'LOGNAME': 'cltbld', 07:31:08 INFO - 'MAIL': '/var/mail/cltbld', 07:31:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:31:08 INFO - 'MOZ_NO_REMOTE': '1', 07:31:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:31:08 INFO - 'NO_EM_RESTART': '1', 07:31:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:31:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:31:08 INFO - 'PWD': '/builds/slave/test', 07:31:08 INFO - 'SHELL': '/bin/bash', 07:31:08 INFO - 'SHLVL': '1', 07:31:08 INFO - 'TERM': 'linux', 07:31:08 INFO - 'TMOUT': '86400', 07:31:08 INFO - 'USER': 'cltbld', 07:31:08 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1461767131.869006-389222091', 07:31:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:31:08 INFO - '_': '/tools/buildbot/bin/python'} 07:31:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:31:08 INFO - Downloading/unpacking flake8 07:31:08 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 07:31:08 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 07:31:08 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 07:31:08 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 07:31:08 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 07:31:08 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 07:31:10 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 07:31:10 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 07:31:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:31:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:31:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:31:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:31:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:31:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:31:11 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 07:31:11 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 07:31:11 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 07:31:11 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 07:31:11 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 07:31:11 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 07:31:11 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 07:31:11 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 07:31:11 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 07:31:11 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 07:31:11 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 07:31:11 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 07:31:11 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 07:31:11 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 07:31:11 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 07:31:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 07:31:11 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 07:31:11 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 07:31:11 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 07:31:11 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 07:31:11 INFO - no previously-included directories found matching 'docs/_build' 07:31:11 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 07:31:11 INFO - Running setup.py install for pep8 07:31:11 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 07:31:11 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 07:31:11 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 07:31:11 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 07:31:11 INFO - no previously-included directories found matching 'docs/_build' 07:31:11 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 07:31:11 INFO - Successfully installed flake8 mccabe pyflakes pep8 07:31:11 INFO - Cleaning up... 07:31:11 INFO - Return code: 0 07:31:11 INFO - Running post-action listener: _resource_record_post_action 07:31:11 INFO - Running post-action listener: _start_resource_monitoring 07:31:11 INFO - Starting resource monitoring. 07:31:11 INFO - ##### 07:31:11 INFO - ##### Running install step. 07:31:11 INFO - ##### 07:31:11 INFO - Running pre-action listener: _resource_record_pre_action 07:31:11 INFO - Running main action method: install 07:31:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:31:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:31:12 INFO - Reading from file tmpfile_stdout 07:31:12 INFO - Detecting whether we're running mozinstall >=1.0... 07:31:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:31:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:31:12 INFO - Reading from file tmpfile_stdout 07:31:12 INFO - Output received: 07:31:12 INFO - Usage: mozinstall [options] installer 07:31:12 INFO - Options: 07:31:12 INFO - -h, --help show this help message and exit 07:31:12 INFO - -d DEST, --destination=DEST 07:31:12 INFO - Directory to install application into. [default: 07:31:12 INFO - "/builds/slave/test"] 07:31:12 INFO - --app=APP Application being installed. [default: firefox] 07:31:12 INFO - mkdir: /builds/slave/test/build/application 07:31:12 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'] 07:31:12 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 07:31:27 INFO - Reading from file tmpfile_stdout 07:31:27 INFO - Output received: 07:31:27 INFO - /builds/slave/test/build/application/firefox/firefox 07:31:27 INFO - Running post-action listener: _resource_record_post_action 07:31:27 INFO - ##### 07:31:27 INFO - ##### Running run-tests step. 07:31:27 INFO - ##### 07:31:27 INFO - Running pre-action listener: _resource_record_pre_action 07:31:27 INFO - Running main action method: run_tests 07:31:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:31:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:31:27 INFO - Python 2.7.3 07:31:27 INFO - Return code: 0 07:31:27 INFO - grabbing minidump binary from tooltool 07:31:27 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')]} 07:31:27 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: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a1e410>, '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 0x1a1f310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a1f7a0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:31:27 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 07:31:27 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 07:31:27 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:31:27 INFO - Return code: 0 07:31:27 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 07:31:27 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:31:27 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:31:27 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 07:31:27 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:31:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 07:31:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 07:31:28 INFO - Return code: 0 07:31:28 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-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JNpWz5iKRZqd5ze4Ucr82g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-008', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 07:31:28 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-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/JNpWz5iKRZqd5ze4Ucr82g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-008 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 07:31:28 INFO - Using env: {'DISPLAY': ':0', 07:31:28 INFO - 'HOME': '/home/cltbld', 07:31:28 INFO - 'LANG': 'en_US.UTF-8', 07:31:28 INFO - 'LANGUAGE': 'en_US:en', 07:31:28 INFO - 'LOGNAME': 'cltbld', 07:31:28 INFO - 'MAIL': '/var/mail/cltbld', 07:31:28 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:31:28 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 07:31:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:31:28 INFO - 'MOZ_NO_REMOTE': '1', 07:31:28 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:31:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:31:28 INFO - 'NO_EM_RESTART': '1', 07:31:28 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:31:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:31:28 INFO - 'PWD': '/builds/slave/test', 07:31:28 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:31:28 INFO - 'SHELL': '/bin/bash', 07:31:28 INFO - 'SHLVL': '1', 07:31:28 INFO - 'TERM': 'linux', 07:31:28 INFO - 'TMOUT': '86400', 07:31:28 INFO - 'USER': 'cltbld', 07:31:28 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1461767131.869006-389222091', 07:31:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:31:28 INFO - '_': '/tools/buildbot/bin/python'} 07:31:28 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-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JNpWz5iKRZqd5ze4Ucr82g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-008', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 07:31:29 INFO - mozversion application_buildid: 20160427071020 07:31:29 INFO - mozversion application_changeset: ed934827bebaeee8a1aeb1726a3af1021d2a8364 07:31:29 INFO - mozversion application_display_name: Nightly 07:31:29 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:31:29 INFO - mozversion application_name: Firefox 07:31:29 INFO - mozversion application_remotingname: firefox 07:31:29 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 07:31:29 INFO - mozversion application_vendor: Mozilla 07:31:29 INFO - mozversion application_version: 49.0a1 07:31:29 INFO - mozversion platform_buildid: 20160427071020 07:31:29 INFO - mozversion platform_changeset: ed934827bebaeee8a1aeb1726a3af1021d2a8364 07:31:29 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 07:31:29 INFO - mozversion platform_version: 49.0a1 07:31:29 INFO - using testdate: 1461767489 07:31:29 INFO - actual date: 1461767489 07:31:29 INFO - starting webserver on 'localhost:60902' 07:31:29 INFO - SUITE-START | Running 2 tests 07:31:29 INFO - TEST-START | dromaeo_css 07:31:29 INFO - operating with platform_type : linux_ 07:31:29 INFO - Initialising browser for dromaeo_css test... 07:31:29 INFO - TEST-INFO | started process 28889 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpARURSI/profile http://localhost:60902/getInfo.html) 07:31:32 INFO - PROCESS | 28889 | 07:31:32 INFO - PROCESS | 28889 | (process:28957): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 07:31:33 INFO - PROCESS | 28889 | __metrics Screen width/height:1600/1200 07:31:33 INFO - PROCESS | 28889 | colorDepth:24 07:31:33 INFO - PROCESS | 28889 | Browser inner width/height: 1024/697 07:31:33 INFO - PROCESS | 28889 | __metrics 07:31:34 ERROR - PROCESS | 28889 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 07:31:35 INFO - TEST-INFO | 28889: exit 0 07:31:35 INFO - Browser initialized. 07:31:35 INFO - Running cycle 1/1 for dromaeo_css test... 07:31:35 INFO - TEST-INFO | started process 29008 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpARURSI/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:31:37 INFO - PROCESS | 29008 | 07:31:37 INFO - PROCESS | 29008 | (process:29068): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 07:31:47 INFO - PROCESS | 29008 | RSS: Main: 166596608 07:31:47 INFO - PROCESS | 29008 | 07:32:36 INFO - PROCESS | 29008 | 1461767556959 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] 07:34:48 INFO - PROCESS | 29008 | Cycle 1(1): loaded http://localhost:60902/tests/dromaeo/cssquery-dojo.html (next: http://localhost:60902/tests/dromaeo/cssquery-ext.html) 07:34:49 INFO - PROCESS | 29008 | RSS: Main: 169996288 07:34:49 INFO - PROCESS | 29008 | 07:37:52 INFO - PROCESS | 29008 | Cycle 1(1): loaded http://localhost:60902/tests/dromaeo/cssquery-ext.html (next: http://localhost:60902/tests/dromaeo/cssquery-jquery.html) 07:37:52 INFO - PROCESS | 29008 | RSS: Main: 167395328 07:37:52 INFO - PROCESS | 29008 | 07:40:54 INFO - PROCESS | 29008 | Cycle 1(1): loaded http://localhost:60902/tests/dromaeo/cssquery-jquery.html (next: http://localhost:60902/tests/dromaeo/cssquery-mootools.html) 07:40:55 INFO - PROCESS | 29008 | RSS: Main: 168280064 07:40:55 INFO - PROCESS | 29008 | 07:43:57 INFO - PROCESS | 29008 | Cycle 1(1): loaded http://localhost:60902/tests/dromaeo/cssquery-mootools.html (next: http://localhost:60902/tests/dromaeo/cssquery-prototype.html) 07:43:57 INFO - PROCESS | 29008 | RSS: Main: 165896192 07:43:57 INFO - PROCESS | 29008 | 07:47:00 INFO - PROCESS | 29008 | Cycle 1(1): loaded http://localhost:60902/tests/dromaeo/cssquery-prototype.html (next: http://localhost:60902/tests/dromaeo/cssquery-yui.html) 07:47:00 INFO - PROCESS | 29008 | RSS: Main: 167469056 07:47:00 INFO - PROCESS | 29008 | 07:50:03 INFO - PROCESS | 29008 | Cycle 1(1): loaded http://localhost:60902/tests/dromaeo/cssquery-yui.html (next: http://localhost:60902/tests/dromaeo/cssquery-dojo.html) 07:50:03 INFO - PROCESS | 29008 | RSS: Main: 169304064 07:50:03 INFO - PROCESS | 29008 | 07:50:03 INFO - PROCESS | 29008 | __start_tp_report 07:50:03 INFO - PROCESS | 29008 | _x_x_mozilla_page_load 07:50:03 INFO - PROCESS | 29008 | _x_x_mozilla_page_load_details 07:50:03 INFO - PROCESS | 29008 | |i|pagename|runs| 07:50:03 INFO - PROCESS | 29008 | |0;dojo.html;2621.882015309311;2709.945801083979;2724.822886512375;2733.04343479782;2748.546489829177;4755.096531658986;4784.282357646355;4786.880327991802;4805.135075686375;4862.465128835825;4651.186042442568;4706.105839890427;4723.9843433661845;4726.385569875912;4732.124556956956;4285.742855428684;4293.8711838644895;4341.544137865526;4446.7554284514335;4531.4109165808395;4420.359047938047;4478.932816007747;4482.10357928414;4485.192665320241;4505.504394516601;4384.671149663788;4411.1177764447075;4436.600705936465;4446.955530444703;4459.464864216298;4325.372820940977;4448.532904045093;4451.821927122911;4477.037436951071;4492.460904691426;4409.470863496401;4413.757243351614;4459.777011149462;4462.553744625511;4468.575485328888;4554.658400619934;4725.881852953704;4745.572898439157;4778.784954677048;4817.156997525454;5079.958608485252;5117.02776472469;5121.641485096045;5144.56271216947;5179.637425380225;5137.383513978347;5157.381114266312;5183.092958732207;5188.507091826271;5234.345706786652;4653.930191047137;4761.666733314677;4809.78355973982;4811.855644330711;4818.469968303484;5220.243064755589;5244.947550524522;5248.553872920768;5258.368995720538;5260.9736951315;4969.726665033458;5016.54851063406;5020.2720605511995;5057.165567681365;5065.316182315414;5065.214891691794;5123.820666276662;5149.047426226085;5154.7164905929785;5164.56101231392;5089.770960306795;5100.1948928089405;5111.667741596784;5123.180291153398;5134.075866344094;5113.181890897438;5121.4622464641425;5125.794968201305;5127.769250383667;5134.306868572695;4153.646940010072;4161.667066634723;4249.107687385684;4321.7406955582765;4322.5677432256525;3671.9816400917825;3686.189038411545;3700.6669399753614;3710.276496083292;3734.589195188527;4409.4488188976375;4417.447819022622;4418.116376724668;4419.867403977886;4444.755538445352;5035.144025515671;5136.717480538607;5189.636725429184;5227.738613069331;5247.239150323182;4452.532484089189;4463.866084017484;4466.798994045275;4470.262406702923;4498.865034048984;4485.0805584855025;4556.7721613919175;4570.108828778423;4575.153596584576;4593.563611456906;4845.200541910545;4878.170710979213;4890.9021819562695;4928.088303663833;4931.211006239128;5156.097682905552;5221.791128354976;5228.268042474133;5230.320058392384;5275.182346736262;4777.474477807369;4889.217725163957;4910.189818679878;4913.70517768935;4916.803327866845;5060.746962651926;5077.847664570068;5081.466446023114;5094.719790411415;5131.563817075441;5104.259882316969;5146.794128234828;5153.3815942087185;5165.690058596496;5202.453742356998;4812.590929770869;4910.238912968495;4963.528464795839;5003.399592048978;5008.524190201925;4945.777440015136;4968.801247950041;4970.602351811919;4976.054549635558;4981.252812078217;4887.7556122194455;4915.139850526215;4916.139675556835;4920.852374428773;4964.429090654621;4928.112939670894;4952.331435256195;4993.900121997613;5039.571636410946;5067.569256613229;4826.565609095198;4903;4983.601311895112;5013.849584512471;5017.62367822422 07:50:03 INFO - PROCESS | 29008 | |1;ext.html;565.8387359602514;614.048225250861;615.7536985205918;631.151101768122;639.5938579002332;32039.199020024484;32588.67411325886;33086.33827323455;33110.17224569387;33217.501737473925;32530.18674533138;32553;33368.165795855115;33424;33563.83218083912;7550.282723141293;7785.1436342002335;7805.4536182467255;7814.570198639072;7830.138684744702;7666;7768.067831860157;7783.727569535067;7788.493747906368;7792.10390805059;7740.9225907740765;7773.8833917491;7828.491148075385;7833.21667833215;7846.254605812467;7675.04061992251;7751.418643601724;7751.457397982087;7755.612219389036;7756.301932826016;7689.9615501922135;7768.456208065438;7780.49426787257;7787.805304867367;7802.687892484294;25311.873440632862;34482.82758586191;34975.6502434975;35031.1242218944;35075.298494030234;2942.2350188950813;2952.335724462;3075.1543325585644;3234.0297910626955;3456.9827150864335;24470.877645611836;24694.25917222486;24759.5048099037;24844.50310993788;25096.623550646756;3300.5544251525976;3323.833808309575;3406.9488957665408;3427.845746941394;3436.9828150859335;143601.28199358936;145228;145310.27344863207;145381.27309363385;146175.2691236537;31218.21954451157;31542.053738387884;31633.20916977048;31663.525047124313;31700.524492132638;32527;32598.348033039205;52315;53666.19500707492;53891.46108538943;13755.31223438844;13885.930570347084;13931.65170870716;13932.860671393157;13933.930330348283;10756.78486430267;10793.29843560166;10854.67435976922;10858.94570527158;10860.565577376816;17301.221445035004;17371.82628173702;17453;17457.738133928;17464.563385915455;6480.870382592322;6684.498662600325;6687.799366019027;6874.381305682413;6939.230496223532;942.9669961551313;956.1824639932896;1022.2486472442748;1058.872935247775;1064.2762921213493;25884.092781356416;26253.21240362792;26546.601800973;26789.732102678725;26943.326416839343;988.2983082011708;997.7356000659831;998.3710262534556;1052.2634156090614;1072.7103682005816;13970.301484925916;14435.711285774225;14522.636934076521;14546.854531454761;14695;263292;268393;270237;270658;270783;44820.3276950846;45313.54686453159;45390;45428.091438171716;45434;1195.647284051207;1266.778313795082;1269.041873385588;1270.7267937393506;1271.3389037700344;25168.37079073038;25782.484350313265;25855.741442585335;26102.34744131412;26370.472590548467;39816.60183398245;39980;40271;40313.97645176682;40351.3947290785;198719;200696;200894;201180;202552;24187;24628.753712462647;24631.137910173056;24650.50698986046;24772;38844.223115538094;39653.405198921464;39793.80103099466;39799;40330.596694033855;34460.655393446745;34470.31059378848;34734.65265347314;34856.82571587126;34880.30239395249;33525.66474335225;34022.31955360829;34231;34419.4837077439;34563;27285.181444556696;30375.848120759256;31358.52962205523;33881.15297117592;33887.322253555285;28736.42527149487;29507;29954.700452995192;30254.243643909078;30416.847915760278;23031.193908213132;23724.644130338405;23734.881325593262;24007;24068.39829004289 07:50:03 INFO - PROCESS | 29008 | |2;jquery.html;382.8583424133071;383.14558534468136;383.8196047857506;396.68067205899257;402.2839345964185;14373.065750726195;14874.181919994397;15027.098374097563;15212.467563635275;15285.617859553502;14598.927005364985;15543.533693989199;16233.91883040586;16281.348746050173;16358.263878125483;5015.498450154973;5247.160454327309;5289.2859463972445;5289.788408463657;5298.894022119555;3684.7236457265676;5715.1998720179;6024.397560243962;6029.638221706689;6040.48655864252;5063.898722025558;5888.64668119912;5892.175095486636;5900.616459930112;5940.405959404046;5332.066888294552;5346.144616861322;5357.598180136505;5369.24830523727;5384.3807962084275;5002.324686167393;5398.676079435247;5411.242426060355;5421.9457805421835;5439.809606663787;11254.324740515594;11312.434378281054;11505.827412588818;13167.802482962667;19441.416757497296;1822.6263615958828;1913.9521511962173;1938.9903050484797;2256.9687281446663;2275.2264230161823;8698.086700896441;8909.975352834443;8939.19547240745;11051.17116216287;11883.168178227528;1797.8381945624956;1809.665211935783;1831.7269497699103;1858.0297703642836;1894.8578856585673;59295;59773.402265977216;60354;60962;61646.07530887085;9814.165795907427;9902.455364954996;10065.69802905914;10126.620244242597;10233.43716095607;13812.37844297009;14001.29993500321;14048;14110;14114.223717695626;7072.257412971667;7286;7329.340359367682;7403.777886663409;7565.205653406423;7005.754798582026;7016.684249208798;7045.612491312924;7162.785116446515;7163.42692584592;4269.786510674454;4329.631981281564;4360.193364227628;4374.21264172452;4434.778261086933;1718.682043821872;1768.1777973242504;1870.6632806095033;1884.9528761781064;1899.3162461513843;823.9958800206081;886.3352485635774;915.3409545127631;918.9615734220288;974.2157563161722;14103.435862565588;14585.92707036458;14946.701065978838;15185.088894666134;16261.918690406708;905.5517518828223;925.9259259259243;929.9116583924516;933.7712260496222;953.1564565359706;8901.243394311427;9118.908810911806;9142.680006199751;9266;9348.392354496937;338498;342723;343046;343571;360178;63882.6805865977;68975.31024689789;69165.30834691589;70308.6484567574;71295.64352178205;891.7191084808264;899.6581299106429;913.6710784117712;917.4266083697689;917.6512925088424;13863.514776982756;14086.718265634834;14300;14324.28378581046;14457;30911.76352945947;30983.535246970863;31475;31872.36255274928;32302.83848580742;41557.58442415537;41644;42268.154636907704;42782.144357113306;43357.78321108374;7523.510971786816;7573.545587264782;7643.121041080347;7644.847103057797;7704.6918123274445;9955.452450115314;10240.05438966594;10633.415162165846;11258;11307.717307067389;9564.282678799256;9634.421934683942;9744.561494732896;9805.607775688892;9823.607055717692;9550.90449095528;9642.276829237695;9823.115919567274;9880.45657488845;10117.494125293853;9134.26925845944;9239;9468.715938521855;9687.757806054906;10004.199664026715;7639.579823109782;7750.302472777477;7778.572178530008;7799.5320280783335;7834.216578342121;8587.785305367415;9793.51032448389;9910.801783964136;10007.449590272605;10046.04562566555 07:50:03 INFO - PROCESS | 29008 | |3;mootools.html;1407.94368225271;1432.5344263114487;1539.9846001539988;1688.4934519644103;1707.1037705204774;6285.685715714208;6748.527603067787;6873.690683919223;7114.893276600848;7136.928630713698;6827.249002609709;6896.655167241643;6940.583564986105;6975.2675969023285;6982.650867456619;2156.676498525222;2184.388371256051;2196.0447205465675;2202.922897698581;2205.0848897707433;2123.946901327464;2169.858959167657;2188.102877820094;2195.1768086967386;2203.4050806282294;2157.6763485477145;2177.858439201447;2179.5640871825617;2183.4977955070344;2214.900329485177;2148.881811500367;2150.387139665188;2150.4838838678684;2150.6773983902385;2151.075037733774;2114.587655407196;2138.8823614701187;2148.52732398872;2150.666646669769;2150.763416024236;7093.964530177368;7103.964480177618;7108.324709152622;7130.25132361105;7130.465215108888;4419.690621656486;4556.066006468666;4556.270996640522;4566.725996440225;4585.037142200142;2756.834589924611;2819.887204511838;2859.742623163925;2897.840618765967;2906.6512018557696;820.5856042698459;920.2408013388914;939.5020639061308;949.7483166960761;959.8800149981253;8851;8874.866876996786;8942.508162051085;9021.684241051531;9033.412828166149;4221.387898754663;4224.134052519226;4241.194173107161;4286.678499112579;4294.463192100988;7813.726519571901;7919.247671471201;7930.405219608552;7970.123286438487;8010.479318844256;4457.8439754608435;4496.7077139985795;4508.774561271924;4521.638268938543;4553.362529245908;3636.254567813592;3934.1934903344745;4028.8992775180855;4034.1931613677384;4063;3541.061618671054;3574.857005719794;3609.4733686419613;3653.6711695947492;3660.8718694846084;1206.631977246948;1237.6905773556612;1243.247835059794;1248.3508575540668;1253.5549879792725;748.0163584885858;871.904090550039;883.3507372081515;888.7422647432172;909.9863502047475;4906.288588154697;5064.34163558735;5069.619778516552;5087.8982420351385;5143.742812859342;879.331707901999;916.9174774270215;956.2111258212003;969.8836139663285;973.4402718436927;3300.976697223868;3354.04409743222;3378.3918894598714;3392.4402473592077;3413.4709120086427;8236.546989915492;8510.872336914952;8528.701495447754;8529.402941794015;8540.914590854136;8390.412671113085;8425.157484251526;8474.27968622674;8603.52680602573;8698.391112622183;6608.900866487006;6689.4982876284475;6810.182778066664;6968;7034.613096279753;4790.712557246577;4821.180399332192;4849.830255941025;4851.733154676526;4852;8014.078380986028;8016.078151012708;8181.018277806464;8234.464759790595;8251.504909705436;7645;7958.602069896598;7962.562059086806;8041.43709940322;8111.75664730059;5653.858653533694;6080.331163572092;6103.206583144163;6233.625982441068;6333.20834895638;6699.464042876461;6806.217285012287;6813.8977915330315;6833.043373927753;6848.14398200225;5907.4683278505145;6096.420840020191;6105.908411373922;6396.520260980352;6631;6304.968475157595;6333.145025421509;6395.5203359747275;6445.6454894981225;6496.415322620986;5820.447057529528;5866.6186697864505;5892.882142357214;5894.0274854650215;5895.0862616292825;4405.955940440683;4412.51462339136;4461.107778444389;4526.411566496467;4568.79440425175;4530.932036019396;4568.200564901195;4593.471750748573;4608.147492713859;4671.416072990876 07:50:03 INFO - PROCESS | 29008 | |4;prototype.html;310.87564974010394;321.0096851214005;323.3242523126666;324.69154303411756;326.03818734732545;4870.464248932619;4892;4941.827036053739;4966.652334336598;5001.549860512549;4861.659683822134;4867.561919427251;4869.610431165507;4872.805087796492;4890.144224760662;3665.413533834587;3790.77255364679;3914.7063970202207;3921;3921.450996860442;3883.339832228514;3904.7461914975434;3905.1408690088133;3917.29597625034;3919.3729003359326;3880.9805950970203;3888.300105980922;3909.491766070407;3911.8239679214503;3924.3917192835156;3780.3573392523335;3834.293739286019;3839.1361943562756;3918.294706952747;3921.3529767588316;3391.406503861839;3403.5575375201065;3405.0465869556565;3408.250184959305;3437.693676402951;4682.7658617069355;4688.203005489041;4697.67116301859;4707.364505791708;4715.693479923794;4386.210482113218;4408.559144085566;4438.578335058164;4454.643628509712;4458.108378324316;4942.975285123587;5121.615878809067;5137.922931156036;5158.767855446514;5172.19830926207;3766.4538641897047;3770.472133901256;3786.829592668309;3788.772673639563;3791.2607041626893;5389.191621256804;5417.539509141728;5452.182172674091;5455.509004189642;5457.181422786574;3732.8880133596035;3820.713446491469;3837.597052309523;3882.7087968402484;3896.8636097736444;3961.069148750041;3975.8927768282856;3980.7611543306916;3983.203359328146;3983.223271462095;4420.336949457607;4433.357163211316;4440.4671439427475;4444.822207111744;4471.8658440246845;4956.157453232959;5105.540501354896;5117.775801245191;5153.587712983027;5166.2508936204795;1448.7182687267596;1465.8827293816469;1466.3914475492552;1467.2590341877496;1468.5374107156213;925.5418567808978;979.5102448775613;984.0946329376991;1000.1498726082772;1010.4745532323298;4448.332750087513;4583.175028494903;4603.516630753789;4628.861134165981;4631.490536041033;5338.225957236179;5361.571074314045;5367.523930919028;5394.91907621386;5423.2136340230445;4470.664700147502;4581.839635612738;4592.954070459253;4607.9308810367875;4610.124076425468;3028.8485575721566;3061.709137631966;3066.018873960356;3067.0952069139657;3078.5382192671277;5426.647267927572;5428.348598168245;5432.320959880015;5438.129899216106;5456.61803673739;5677.858053548694;5810.6513609183585;5829.5044921181325;5872.853178670567;5892.263467066617;5338.492843179892;5365.941413394327;5371.113766228685;5396.1366181410785;5419.320010796587;5671.914921276101;5691;5691.971540142438;5698.629589076697;5714.971425142848;5017.12200364942;5278.392984806796;5285.070949103627;5290.285811415563;5309.203619457112;5020.272060551127;5039.7732102056225;5130.870688664162;5144.4855514449755;5144.922826157535;3741;3794.4877441545036;3802.73380863348;3806.8096595169577;3810.714196435223;5181.3005244293045;5281.392639846466;5321.148616221386;5321.973390133025;5373.919391209057;5155.252488389237;5189.481051894932;5191.610629202901;5197.116490196751;5208.328128669204;4920.2041254942405;5173.715445650375;5179.663321884216;5184.196449550326;5203.27154198405;5126.871828204325;5128.564072053768;5145.218264575459;5145.253938179018;5163.8192663258105;4934.235193545006;5050.924236136534;5201.375834899685;5272;5283.89432211346;5120.846374608767;5146.382434107931;5159.251908473176;5178.61160412963;5180.326557547494 07:50:03 INFO - PROCESS | 29008 | |5;yui.html;697.4908316928642;704.1093017333073;718.9856202875943;752.1951511882288;788.235411650699;11483.712907177314;11847.644570662873;11893.22694024889;11994.280343179404;12064.879351206486;11644.767104657902;12817.166884152524;13371.064025518217;13403.262820544865;13436.529721459727;798.7923139983617;806.7862016565615;807.3944541593804;807.4711064252924;807.6446363600027;633.5723386713972;773.1649818196363;798.22572105058;799.9200079991983;801.1027649033091;831.5301854452215;842.1031601344579;844.6199210355364;844.8141408890065;846.5767116441779;834.7996480844614;835.8035861572556;836.69042454292;837.0165056059167;839.4291881520563;606.4602503771647;610.8808782287456;614.4746241963146;615.2125279642021;616.3651439017818;12853.100282980195;12958.611241662766;13001.544945926942;13012.934935325262;13018.674533136653;12005.399730013465;12121.151519393647;12190.08574356918;12209.93895030528;12523.248605083632;10089.899101008969;10248.79502409955;10363.378197308186;10409.531571079244;10444.738881527946;3192.808431494118;3214.180384002088;3222.822744749038;3253.1053960160907;3334.7665663403573;39978.40032399516;41899.162016759496;44504.77747611274;44878.878028049236;45070;9548.618055277711;9577.377470464537;9598.808023839623;9764.560594773253;9868.506574671237;16647.250873710713;16718.080505572067;16742.916285418494;16763.49709508717;16784.41254556085;6666.733330666719;6691.230508491586;6762.425193858478;6767.154105736783;7053.426740794234;3517.2437925846066;3798.2973149967343;3861.362875125573;3882.2235552889533;3908.785016824102;2751.9724802752116;2793.6228609137506;2818.760405365526;2818.9295267618063;2823.816451930618;493.42269544632865;501.43338028028097;507.8857257117193;511.37867490998616;514.0644027869242;545.8253358925185;556.9526590239794;559.8068666310198;573.650073455192;584.5732615190934;7525.736599219111;7624.961875190588;7829.608519573999;7917.327027202752;7964.561949092739;478.1393491714899;491.4544855210713;573.3234782956068;589.9440002395663;647.4529022975578;3433.3476639438927;3543.7342199335153;3592.8023958682;3593.13764696471;3611.2777444511203;56029.719851400485;56363.71818140883;56841.71579142078;57750.7112464435;57879.42120578825;47311.76344118326;47837.76081119572;48696.269555956686;49637.25544116841;50398.49601504012;45336.50649214259;46061.30908036382;46082.30876536854;46398.66415362209;46637.7668111664;6822.556533825285;6892.551984121016;6893.655317234018;6979.930200697928;7499.400047996256;29959;30256.243593909447;30462;30625.54061689121;31475.685243147276;39322.41016384814;41132;41656.3751543721;41656.375154373316;41969.79015104905;10918.12654987615;11019.228653994143;11066.612668556561;11187.664370068585;11254.88745112571;11992.940035299767;12048.457819398323;12485.250884946568;12504;12547.686307842378;10895.237333386587;11111.388873611706;11182.776344473228;11399.715007124889;11692.566981436177;11303.7739245213;11422.543098276308;11487.081033517132;11551.191416601094;11689.181757276909;9837.508124593885;10959.780804384027;11267;11267.042301404144;11326.886731132583;8218.342532597497;8553.61508732096;8771.561421929006;8920.910790892009;9057.818843623223;8223.424360294723;8454.535526720829;8994.280457563511;9093.77265568366;9219.124183202584 07:50:03 INFO - PROCESS | 29008 | __end_tp_report 07:50:03 INFO - PROCESS | 29008 | __start_cc_report 07:50:03 INFO - PROCESS | 29008 | _x_x_mozilla_cycle_collect,374 07:50:03 INFO - PROCESS | 29008 | __end_cc_report 07:50:03 INFO - PROCESS | 29008 | __startTimestamp1461768603748__endTimestamp 07:50:03 INFO - PROCESS | 29008 | 07:50:03 INFO - PROCESS | 29008 | ------- Summary: start ------- 07:50:03 INFO - PROCESS | 29008 | Number of tests: 6 07:50:03 INFO - PROCESS | 29008 | 07:50:03 INFO - PROCESS | 29008 | [#0] dojo.html Cycles:165 Average:4739.32 Median:4890.90 stddev:503.91 (10.3%) stddev-sans-first:477.29 07:50:03 INFO - PROCESS | 29008 | Values: 2621.9 2709.9 2724.8 2733.0 2748.5 4755.1 4784.3 4786.9 4805.1 4862.5 4651.2 4706.1 4724.0 4726.4 4732.1 4285.7 4293.9 4341.5 4446.8 4531.4 4420.4 4478.9 4482.1 4485.2 4505.5 4384.7 4411.1 4436.6 4447.0 4459.5 4325.4 4448.5 4451.8 4477.0 4492.5 4409.5 4413.8 4459.8 4462.6 4468.6 4554.7 4725.9 4745.6 4778.8 4817.2 5080.0 5117.0 5121.6 5144.6 5179.6 5137.4 5157.4 5183.1 5188.5 5234.3 4653.9 4761.7 4809.8 4811.9 4818.5 5220.2 5244.9 5248.6 5258.4 5261.0 4969.7 5016.5 5020.3 5057.2 5065.3 5065.2 5123.8 5149.0 5154.7 5164.6 5089.8 5100.2 5111.7 5123.2 5134.1 5113.2 5121.5 5125.8 5127.8 5134.3 4153.6 4161.7 4249.1 4321.7 4322.6 3672.0 3686.2 3700.7 3710.3 3734.6 4409.4 4417.4 4418.1 4419.9 4444.8 5035.1 5136.7 5189.6 5227.7 5247.2 4452.5 4463.9 4466.8 4470.3 4498.9 4485.1 4556.8 4570.1 4575.2 4593.6 4845.2 4878.2 4890.9 4928.1 4931.2 5156.1 5221.8 5228.3 5230.3 5275.2 4777.5 4889.2 4910.2 4913.7 4916.8 5060.7 5077.8 5081.5 5094.7 5131.6 5104.3 5146.8 5153.4 5165.7 5202.5 4812.6 4910.2 4963.5 5003.4 5008.5 4945.8 4968.8 4970.6 4976.1 4981.3 4887.8 4915.1 4916.1 4920.9 4964.4 4928.1 4952.3 4993.9 5039.6 5067.6 4826.6 4903.0 4983.6 5013.8 5017.6 07:50:03 INFO - PROCESS | 29008 | 07:50:03 INFO - PROCESS | 29008 | [#1] ext.html Cycles:180 Average:35639.99 Median:32541.59 stddev:54945.64 (168.8%) stddev-sans-first:55036.66 07:50:03 INFO - PROCESS | 29008 | Values: 565.8 614.0 615.8 631.2 639.6 32039.2 32588.7 33086.3 33110.2 33217.5 32530.2 32553.0 33368.2 33424.0 33563.8 7550.3 7785.1 7805.5 7814.6 7830.1 7666.0 7768.1 7783.7 7788.5 7792.1 7740.9 7773.9 7828.5 7833.2 7846.3 7675.0 7751.4 7751.5 7755.6 7756.3 7690.0 7768.5 7780.5 7787.8 7802.7 25311.9 34482.8 34975.7 35031.1 35075.3 2942.2 2952.3 3075.2 3234.0 3457.0 24470.9 24694.3 24759.5 24844.5 25096.6 3300.6 3323.8 3406.9 3427.8 3437.0 143601.3 145228.0 145310.3 145381.3 146175.3 31218.2 31542.1 31633.2 31663.5 31700.5 32527.0 32598.3 52315.0 53666.2 53891.5 13755.3 13885.9 13931.7 13932.9 13933.9 10756.8 10793.3 10854.7 10858.9 10860.6 17301.2 17371.8 17453.0 17457.7 17464.6 6480.9 6684.5 6687.8 6874.4 6939.2 943.0 956.2 1022.2 1058.9 1064.3 25884.1 26253.2 26546.6 26789.7 26943.3 988.3 997.7 998.4 1052.3 1072.7 13970.3 14435.7 14522.6 14546.9 14695.0 263292.0 268393.0 270237.0 270658.0 270783.0 44820.3 45313.5 45390.0 45428.1 45434.0 1195.6 1266.8 1269.0 1270.7 1271.3 25168.4 25782.5 25855.7 26102.3 26370.5 39816.6 39980.0 40271.0 40314.0 40351.4 198719.0 200696.0 200894.0 201180.0 202552.0 24187.0 24628.8 24631.1 24650.5 24772.0 38844.2 39653.4 39793.8 39799.0 40330.6 34460.7 34470.3 34734.7 34856.8 34880.3 33525.7 34022.3 34231.0 34419.5 34563.0 27285.2 30375.8 31358.5 33881.2 33887.3 28736.4 29507.0 29954.7 30254.2 30416.8 23031.2 23724.6 23734.9 24007.0 24068.4 07:50:03 INFO - PROCESS | 29008 | 07:50:03 INFO - PROCESS | 29008 | [#2] jquery.html Cycles:180 Average:21791.42 Median:5315.48 stddev:56930.17 (1071.0%) stddev-sans-first:57067.18 07:50:03 INFO - PROCESS | 29008 | Values: 382.9 383.1 383.8 396.7 402.3 14373.1 14874.2 15027.1 15212.5 15285.6 14598.9 15543.5 16233.9 16281.3 16358.3 5015.5 5247.2 5289.3 5289.8 5298.9 3684.7 5715.2 6024.4 6029.6 6040.5 5063.9 5888.6 5892.2 5900.6 5940.4 5332.1 5346.1 5357.6 5369.2 5384.4 5002.3 5398.7 5411.2 5421.9 5439.8 11254.3 11312.4 11505.8 13167.8 19441.4 1822.6 1914.0 1939.0 2257.0 2275.2 8698.1 8910.0 8939.2 11051.2 11883.2 1797.8 1809.7 1831.7 1858.0 1894.9 59295.0 59773.4 60354.0 60962.0 61646.1 9814.2 9902.5 10065.7 10126.6 10233.4 13812.4 14001.3 14048.0 14110.0 14114.2 7072.3 7286.0 7329.3 7403.8 7565.2 7005.8 7016.7 7045.6 7162.8 7163.4 4269.8 4329.6 4360.2 4374.2 4434.8 1718.7 1768.2 1870.7 1885.0 1899.3 824.0 886.3 915.3 919.0 974.2 14103.4 14585.9 14946.7 15185.1 16261.9 905.6 925.9 929.9 933.8 953.2 8901.2 9118.9 9142.7 9266.0 9348.4 338498.0 342723.0 343046.0 343571.0 360178.0 63882.7 68975.3 69165.3 70308.6 71295.6 891.7 899.7 913.7 917.4 917.7 13863.5 14086.7 14300.0 14324.3 14457.0 30911.8 30983.5 31475.0 31872.4 32302.8 41557.6 41644.0 42268.2 42782.1 43357.8 7523.5 7573.5 7643.1 7644.8 7704.7 9955.5 10240.1 10633.4 11258.0 11307.7 9564.3 9634.4 9744.6 9805.6 9823.6 9550.9 9642.3 9823.1 9880.5 10117.5 9134.3 9239.0 9468.7 9687.8 10004.2 7639.6 7750.3 7778.6 7799.5 7834.2 8587.8 9793.5 9910.8 10007.4 10046.0 07:50:03 INFO - PROCESS | 29008 | 07:50:03 INFO - PROCESS | 29008 | [#3] mootools.html Cycles:180 Average:4760.60 Median:5737.15 stddev:2462.83 (42.9%) stddev-sans-first:2456.85 07:50:03 INFO - PROCESS | 29008 | Values: 1407.9 1432.5 1540.0 1688.5 1707.1 6285.7 6748.5 6873.7 7114.9 7136.9 6827.2 6896.7 6940.6 6975.3 6982.7 2156.7 2184.4 2196.0 2202.9 2205.1 2123.9 2169.9 2188.1 2195.2 2203.4 2157.7 2177.9 2179.6 2183.5 2214.9 2148.9 2150.4 2150.5 2150.7 2151.1 2114.6 2138.9 2148.5 2150.7 2150.8 7094.0 7104.0 7108.3 7130.3 7130.5 4419.7 4556.1 4556.3 4566.7 4585.0 2756.8 2819.9 2859.7 2897.8 2906.7 820.6 920.2 939.5 949.7 959.9 8851.0 8874.9 8942.5 9021.7 9033.4 4221.4 4224.1 4241.2 4286.7 4294.5 7813.7 7919.2 7930.4 7970.1 8010.5 4457.8 4496.7 4508.8 4521.6 4553.4 3636.3 3934.2 4028.9 4034.2 4063.0 3541.1 3574.9 3609.5 3653.7 3660.9 1206.6 1237.7 1243.2 1248.4 1253.6 748.0 871.9 883.4 888.7 910.0 4906.3 5064.3 5069.6 5087.9 5143.7 879.3 916.9 956.2 969.9 973.4 3301.0 3354.0 3378.4 3392.4 3413.5 8236.5 8510.9 8528.7 8529.4 8540.9 8390.4 8425.2 8474.3 8603.5 8698.4 6608.9 6689.5 6810.2 6968.0 7034.6 4790.7 4821.2 4849.8 4851.7 4852.0 8014.1 8016.1 8181.0 8234.5 8251.5 7645.0 7958.6 7962.6 8041.4 8111.8 5653.9 6080.3 6103.2 6233.6 6333.2 6699.5 6806.2 6813.9 6833.0 6848.1 5907.5 6096.4 6105.9 6396.5 6631.0 6305.0 6333.1 6395.5 6445.6 6496.4 5820.4 5866.6 5892.9 5894.0 5895.1 4406.0 4412.5 4461.1 4526.4 4568.8 4530.9 4568.2 4593.5 4608.1 4671.4 07:50:03 INFO - PROCESS | 29008 | 07:50:03 INFO - PROCESS | 29008 | [#4] prototype.html Cycles:180 Average:4372.08 Median:4871.63 stddev:1254.82 (25.8%) stddev-sans-first:1220.76 07:50:03 INFO - PROCESS | 29008 | Values: 310.9 321.0 323.3 324.7 326.0 4870.5 4892.0 4941.8 4966.7 5001.5 4861.7 4867.6 4869.6 4872.8 4890.1 3665.4 3790.8 3914.7 3921.0 3921.5 3883.3 3904.7 3905.1 3917.3 3919.4 3881.0 3888.3 3909.5 3911.8 3924.4 3780.4 3834.3 3839.1 3918.3 3921.4 3391.4 3403.6 3405.0 3408.3 3437.7 4682.8 4688.2 4697.7 4707.4 4715.7 4386.2 4408.6 4438.6 4454.6 4458.1 4943.0 5121.6 5137.9 5158.8 5172.2 3766.5 3770.5 3786.8 3788.8 3791.3 5389.2 5417.5 5452.2 5455.5 5457.2 3732.9 3820.7 3837.6 3882.7 3896.9 3961.1 3975.9 3980.8 3983.2 3983.2 4420.3 4433.4 4440.5 4444.8 4471.9 4956.2 5105.5 5117.8 5153.6 5166.3 1448.7 1465.9 1466.4 1467.3 1468.5 925.5 979.5 984.1 1000.1 1010.5 4448.3 4583.2 4603.5 4628.9 4631.5 5338.2 5361.6 5367.5 5394.9 5423.2 4470.7 4581.8 4593.0 4607.9 4610.1 3028.8 3061.7 3066.0 3067.1 3078.5 5426.6 5428.3 5432.3 5438.1 5456.6 5677.9 5810.7 5829.5 5872.9 5892.3 5338.5 5365.9 5371.1 5396.1 5419.3 5671.9 5691.0 5692.0 5698.6 5715.0 5017.1 5278.4 5285.1 5290.3 5309.2 5020.3 5039.8 5130.9 5144.5 5144.9 3741.0 3794.5 3802.7 3806.8 3810.7 5181.3 5281.4 5321.1 5322.0 5373.9 5155.3 5189.5 5191.6 5197.1 5208.3 4920.2 5173.7 5179.7 5184.2 5203.3 5126.9 5128.6 5145.2 5145.3 5163.8 4934.2 5050.9 5201.4 5272.0 5283.9 5120.8 5146.4 5159.3 5178.6 5180.3 07:50:03 INFO - PROCESS | 29008 | 07:50:03 INFO - PROCESS | 29008 | [#5] yui.html Cycles:180 Average:13035.54 Median:46071.81 stddev:15187.09 (33.0%) stddev-sans-first:15201.44 07:50:03 INFO - PROCESS | 29008 | Values: 697.5 704.1 719.0 752.2 788.2 11483.7 11847.6 11893.2 11994.3 12064.9 11644.8 12817.2 13371.1 13403.3 13436.5 798.8 806.8 807.4 807.5 807.6 633.6 773.2 798.2 799.9 801.1 831.5 842.1 844.6 844.8 846.6 834.8 835.8 836.7 837.0 839.4 606.5 610.9 614.5 615.2 616.4 12853.1 12958.6 13001.5 13012.9 13018.7 12005.4 12121.2 12190.1 12209.9 12523.2 10089.9 10248.8 10363.4 10409.5 10444.7 3192.8 3214.2 3222.8 3253.1 3334.8 39978.4 41899.2 44504.8 44878.9 45070.0 9548.6 9577.4 9598.8 9764.6 9868.5 16647.3 16718.1 16742.9 16763.5 16784.4 6666.7 6691.2 6762.4 6767.2 7053.4 3517.2 3798.3 3861.4 3882.2 3908.8 2752.0 2793.6 2818.8 2818.9 2823.8 493.4 501.4 507.9 511.4 514.1 545.8 557.0 559.8 573.7 584.6 7525.7 7625.0 7829.6 7917.3 7964.6 478.1 491.5 573.3 589.9 647.5 3433.3 3543.7 3592.8 3593.1 3611.3 56029.7 56363.7 56841.7 57750.7 57879.4 47311.8 47837.8 48696.3 49637.3 50398.5 45336.5 46061.3 46082.3 46398.7 46637.8 6822.6 6892.6 6893.7 6979.9 7499.4 29959.0 30256.2 30462.0 30625.5 31475.7 39322.4 41132.0 41656.4 41656.4 41969.8 10918.1 11019.2 11066.6 11187.7 11254.9 11992.9 12048.5 12485.3 12504.0 12547.7 10895.2 11111.4 11182.8 11399.7 11692.6 11303.8 11422.5 11487.1 11551.2 11689.2 9837.5 10959.8 11267.0 11267.0 11326.9 8218.3 8553.6 8771.6 8920.9 9057.8 8223.4 8454.5 8994.3 9093.8 9219.1 07:50:03 INFO - PROCESS | 29008 | -------- Summary: end -------- 07:50:03 INFO - PROCESS | 29008 | 07:50:04 INFO - TEST-INFO | 29008: exit 0 07:50:04 INFO - TEST-OK | dromaeo_css | took 1115449ms 07:50:04 INFO - TEST-START | kraken 07:50:04 INFO - operating with platform_type : linux_ 07:50:04 INFO - Initialising browser for kraken test... 07:50:04 INFO - TEST-INFO | started process 29274 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpI9ALo5/profile http://localhost:60902/getInfo.html) 07:50:08 INFO - PROCESS | 29274 | 07:50:08 INFO - PROCESS | 29274 | (process:29339): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 07:50:09 INFO - PROCESS | 29274 | __metrics Screen width/height:1600/1200 07:50:09 INFO - PROCESS | 29274 | colorDepth:24 07:50:09 INFO - PROCESS | 29274 | Browser inner width/height: 1024/697 07:50:09 INFO - PROCESS | 29274 | __metrics 07:50:09 ERROR - PROCESS | 29274 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 07:50:10 INFO - TEST-INFO | 29274: exit 0 07:50:10 INFO - Browser initialized. 07:50:10 INFO - Running cycle 1/1 for kraken test... 07:50:10 INFO - TEST-INFO | started process 29392 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpI9ALo5/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:50:12 INFO - PROCESS | 29392 | 07:50:12 INFO - PROCESS | 29392 | (process:29453): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 07:50:22 INFO - PROCESS | 29392 | RSS: Main: 165883904 07:50:22 INFO - PROCESS | 29392 | 07:50:25 INFO - PROCESS | 29392 | Cycle 1(1): loaded http://localhost:60902/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:60902/tests/kraken/driver.html?testName=audio-beat-detection) 07:50:26 INFO - PROCESS | 29392 | RSS: Main: 170418176 07:50:26 INFO - PROCESS | 29392 | 07:50:29 INFO - PROCESS | 29392 | Cycle 1(1): loaded http://localhost:60902/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:60902/tests/kraken/driver.html?testName=audio-dft) 07:50:29 INFO - PROCESS | 29392 | RSS: Main: 173871104 07:50:29 INFO - PROCESS | 29392 | 07:50:32 INFO - PROCESS | 29392 | Cycle 1(1): loaded http://localhost:60902/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:60902/tests/kraken/driver.html?testName=audio-fft) 07:50:33 INFO - PROCESS | 29392 | RSS: Main: 163913728 07:50:33 INFO - PROCESS | 29392 | 07:50:35 INFO - PROCESS | 29392 | Cycle 1(1): loaded http://localhost:60902/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:60902/tests/kraken/driver.html?testName=audio-oscillator) 07:50:35 INFO - PROCESS | 29392 | RSS: Main: 164990976 07:50:35 INFO - PROCESS | 29392 | 07:50:38 INFO - PROCESS | 29392 | Cycle 1(1): loaded http://localhost:60902/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:60902/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:50:38 INFO - PROCESS | 29392 | RSS: Main: 165683200 07:50:38 INFO - PROCESS | 29392 | 07:50:42 INFO - PROCESS | 29392 | Cycle 1(1): loaded http://localhost:60902/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:60902/tests/kraken/driver.html?testName=imaging-darkroom) 07:50:42 INFO - PROCESS | 29392 | RSS: Main: 166150144 07:50:42 INFO - PROCESS | 29392 | 07:50:46 INFO - PROCESS | 29392 | Cycle 1(1): loaded http://localhost:60902/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:60902/tests/kraken/driver.html?testName=imaging-desaturate) 07:50:46 INFO - PROCESS | 29392 | RSS: Main: 165875712 07:50:46 INFO - PROCESS | 29392 | 07:50:49 INFO - PROCESS | 29392 | Cycle 1(1): loaded http://localhost:60902/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:60902/tests/kraken/driver.html?testName=json-parse-financial) 07:50:50 INFO - PROCESS | 29392 | RSS: Main: 166883328 07:50:50 INFO - PROCESS | 29392 | 07:50:52 INFO - PROCESS | 29392 | Cycle 1(1): loaded http://localhost:60902/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:60902/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:50:52 INFO - PROCESS | 29392 | RSS: Main: 166969344 07:50:52 INFO - PROCESS | 29392 | 07:50:54 INFO - PROCESS | 29392 | Cycle 1(1): loaded http://localhost:60902/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:60902/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:50:55 INFO - PROCESS | 29392 | RSS: Main: 166891520 07:50:55 INFO - PROCESS | 29392 | 07:50:57 INFO - PROCESS | 29392 | Cycle 1(1): loaded http://localhost:60902/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:60902/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:50:58 INFO - PROCESS | 29392 | RSS: Main: 167559168 07:50:58 INFO - PROCESS | 29392 | 07:51:00 INFO - PROCESS | 29392 | Cycle 1(1): loaded http://localhost:60902/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:60902/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:51:01 INFO - PROCESS | 29392 | RSS: Main: 167145472 07:51:01 INFO - PROCESS | 29392 | 07:51:04 INFO - PROCESS | 29392 | Cycle 1(1): loaded http://localhost:60902/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:60902/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:51:05 INFO - PROCESS | 29392 | RSS: Main: 164790272 07:51:05 INFO - PROCESS | 29392 | 07:51:07 INFO - PROCESS | 29392 | Cycle 1(1): loaded http://localhost:60902/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:60902/tests/kraken/driver.html?testName=ai-astar) 07:51:07 INFO - PROCESS | 29392 | RSS: Main: 166866944 07:51:07 INFO - PROCESS | 29392 | 07:51:07 INFO - PROCESS | 29392 | __start_tp_report 07:51:07 INFO - PROCESS | 29392 | _x_x_mozilla_page_load 07:51:07 INFO - PROCESS | 29392 | _x_x_mozilla_page_load_details 07:51:07 INFO - PROCESS | 29392 | |i|pagename|runs| 07:51:07 INFO - PROCESS | 29392 | |0;ai-astar;115;111;112;114;114;125;114;113;114;114 07:51:07 INFO - PROCESS | 29392 | |1;audio-beat-detection;158;134;153;195;134;159;156;134;188;141 07:51:07 INFO - PROCESS | 29392 | |2;audio-dft;164;165;165;168;166;161;165;164;167;167 07:51:07 INFO - PROCESS | 29392 | |3;audio-fft;74;77;74;179;76;78;74;75;153;74 07:51:07 INFO - PROCESS | 29392 | |4;audio-oscillator;95;97;155;95;94;94;147;94;97;144 07:51:07 INFO - PROCESS | 29392 | |5;imaging-gaussian-blur;118;118;117;117;118;117;118;118;118;118 07:51:07 INFO - PROCESS | 29392 | |6;imaging-darkroom;148;146;146;147;146;147;147;146;147;148 07:51:07 INFO - PROCESS | 29392 | |7;imaging-desaturate;95;96;95;95;95;96;95;95;95;95 07:51:07 INFO - PROCESS | 29392 | |8;json-parse-financial;73;66;64;65;65;66;66;89;62;61 07:51:07 INFO - PROCESS | 29392 | |9;json-stringify-tinderbox;65;65;66;65;66;65;65;64;69;64 07:51:07 INFO - PROCESS | 29392 | |10;stanford-crypto-aes;80;88;80;80;81;85;80;90;82;81 07:51:07 INFO - PROCESS | 29392 | |11;stanford-crypto-ccm;144;135;143;133;133;152;143;132;169;147 07:51:07 INFO - PROCESS | 29392 | |12;stanford-crypto-pbkdf2;229;224;221;219;221;216;221;212;215;219 07:51:07 INFO - PROCESS | 29392 | |13;stanford-crypto-sha256-iterative;82;75;77;87;77;77;75;73;75;72 07:51:07 INFO - PROCESS | 29392 | __end_tp_report 07:51:07 INFO - PROCESS | 29392 | __start_cc_report 07:51:07 INFO - PROCESS | 29392 | _x_x_mozilla_cycle_collect,934 07:51:07 INFO - PROCESS | 29392 | __end_cc_report 07:51:07 INFO - PROCESS | 29392 | __startTimestamp1461768667740__endTimestamp 07:51:07 INFO - PROCESS | 29392 | 07:51:07 INFO - PROCESS | 29392 | ------- Summary: start ------- 07:51:07 INFO - PROCESS | 29392 | Number of tests: 14 07:51:07 INFO - PROCESS | 29392 | 07:51:07 INFO - PROCESS | 29392 | [#0] ai-astar Cycles:10 Average:114.60 Median:114.00 stddev:3.84 (3.4%) stddev-sans-first:4.07 07:51:07 INFO - PROCESS | 29392 | Values: 115.0 111.0 112.0 114.0 114.0 125.0 114.0 113.0 114.0 114.0 07:51:07 INFO - PROCESS | 29392 | 07:51:07 INFO - PROCESS | 29392 | [#1] audio-beat-detection Cycles:10 Average:155.20 Median:157.00 stddev:21.70 (13.8%) stddev-sans-first:22.99 07:51:07 INFO - PROCESS | 29392 | Values: 158.0 134.0 153.0 195.0 134.0 159.0 156.0 134.0 188.0 141.0 07:51:07 INFO - PROCESS | 29392 | 07:51:07 INFO - PROCESS | 29392 | [#2] audio-dft Cycles:10 Average:165.20 Median:165.50 stddev:1.99 (1.2%) stddev-sans-first:2.06 07:51:07 INFO - PROCESS | 29392 | Values: 164.0 165.0 165.0 168.0 166.0 161.0 165.0 164.0 167.0 167.0 07:51:07 INFO - PROCESS | 29392 | 07:51:07 INFO - PROCESS | 29392 | [#3] audio-fft Cycles:10 Average:93.40 Median:74.50 stddev:38.78 (52.0%) stddev-sans-first:40.49 07:51:07 INFO - PROCESS | 29392 | Values: 74.0 77.0 74.0 179.0 76.0 78.0 74.0 75.0 153.0 74.0 07:51:07 INFO - PROCESS | 29392 | 07:51:07 INFO - PROCESS | 29392 | [#4] audio-oscillator Cycles:10 Average:111.20 Median:94.50 stddev:26.02 (27.5%) stddev-sans-first:26.93 07:51:07 INFO - PROCESS | 29392 | Values: 95.0 97.0 155.0 95.0 94.0 94.0 147.0 94.0 97.0 144.0 07:51:07 INFO - PROCESS | 29392 | 07:51:07 INFO - PROCESS | 29392 | [#5] imaging-gaussian-blur Cycles:10 Average:117.70 Median:118.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 07:51:07 INFO - PROCESS | 29392 | Values: 118.0 118.0 117.0 117.0 118.0 117.0 118.0 118.0 118.0 118.0 07:51:07 INFO - PROCESS | 29392 | 07:51:07 INFO - PROCESS | 29392 | [#6] imaging-darkroom Cycles:10 Average:146.80 Median:147.00 stddev:0.79 (0.5%) stddev-sans-first:0.71 07:51:07 INFO - PROCESS | 29392 | Values: 148.0 146.0 146.0 147.0 146.0 147.0 147.0 146.0 147.0 148.0 07:51:07 INFO - PROCESS | 29392 | 07:51:07 INFO - PROCESS | 29392 | [#7] imaging-desaturate Cycles:10 Average:95.20 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 07:51:07 INFO - PROCESS | 29392 | Values: 95.0 96.0 95.0 95.0 95.0 96.0 95.0 95.0 95.0 95.0 07:51:07 INFO - PROCESS | 29392 | 07:51:07 INFO - PROCESS | 29392 | [#8] json-parse-financial Cycles:10 Average:67.70 Median:66.00 stddev:8.14 (12.3%) stddev-sans-first:8.40 07:51:07 INFO - PROCESS | 29392 | Values: 73.0 66.0 64.0 65.0 65.0 66.0 66.0 89.0 62.0 61.0 07:51:07 INFO - PROCESS | 29392 | 07:51:07 INFO - PROCESS | 29392 | [#9] json-stringify-tinderbox Cycles:10 Average:65.40 Median:65.00 stddev:1.43 (2.2%) stddev-sans-first:1.51 07:51:07 INFO - PROCESS | 29392 | Values: 65.0 65.0 66.0 65.0 66.0 65.0 65.0 64.0 69.0 64.0 07:51:07 INFO - PROCESS | 29392 | 07:51:07 INFO - PROCESS | 29392 | [#10] stanford-crypto-aes Cycles:10 Average:82.70 Median:81.50 stddev:3.68 (4.5%) stddev-sans-first:3.77 07:51:07 INFO - PROCESS | 29392 | Values: 80.0 88.0 80.0 80.0 81.0 85.0 80.0 90.0 82.0 81.0 07:51:07 INFO - PROCESS | 29392 | 07:51:07 INFO - PROCESS | 29392 | [#11] stanford-crypto-ccm Cycles:10 Average:143.10 Median:143.50 stddev:11.35 (7.9%) stddev-sans-first:12.03 07:51:07 INFO - PROCESS | 29392 | Values: 144.0 135.0 143.0 133.0 133.0 152.0 143.0 132.0 169.0 147.0 07:51:07 INFO - PROCESS | 29392 | 07:51:07 INFO - PROCESS | 29392 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:219.70 Median:221.00 stddev:4.79 (2.2%) stddev-sans-first:3.71 07:51:07 INFO - PROCESS | 29392 | Values: 229.0 224.0 221.0 219.0 221.0 216.0 221.0 212.0 215.0 219.0 07:51:07 INFO - PROCESS | 29392 | 07:51:07 INFO - PROCESS | 29392 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:77.00 Median:77.00 stddev:4.45 (5.8%) stddev-sans-first:4.33 07:51:07 INFO - PROCESS | 29392 | Values: 82.0 75.0 77.0 87.0 77.0 77.0 75.0 73.0 75.0 72.0 07:51:07 INFO - PROCESS | 29392 | -------- Summary: end -------- 07:51:07 INFO - PROCESS | 29392 | 07:51:08 INFO - TEST-INFO | 29392: exit 0 07:51:08 INFO - TEST-OK | kraken | took 64272ms 07:51:08 INFO - SUITE-END | took 1179s 07:51:08 INFO - Completed test suite (00:19:39) 07:51:08 INFO - javascript benchmark 07:51:08 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2621.882015309311, 2709.945801083979, 2724.822886512375, 2733.04343479782, 2748.546489829177, 4755.096531658986, 4784.282357646355, 4786.880327991802, 4805.135075686375, 4862.465128835825, 4651.186042442568, 4706.105839890427, 4723.9843433661845, 4726.385569875912, 4732.124556956956, 4285.742855428684, 4293.8711838644895, 4341.544137865526, 4446.7554284514335, 4531.4109165808395, 4420.359047938047, 4478.932816007747, 4482.10357928414, 4485.192665320241, 4505.504394516601, 4384.671149663788, 4411.1177764447075, 4436.600705936465, 4446.955530444703, 4459.464864216298, 4325.372820940977, 4448.532904045093, 4451.821927122911, 4477.037436951071, 4492.460904691426, 4409.470863496401, 4413.757243351614, 4459.777011149462, 4462.553744625511, 4468.575485328888, 4554.658400619934, 4725.881852953704, 4745.572898439157, 4778.784954677048, 4817.156997525454, 5079.958608485252, 5117.02776472469, 5121.641485096045, 5144.56271216947, 5179.637425380225, 5137.383513978347, 5157.381114266312, 5183.092958732207, 5188.507091826271, 5234.345706786652, 4653.930191047137, 4761.666733314677, 4809.78355973982, 4811.855644330711, 4818.469968303484, 5220.243064755589, 5244.947550524522, 5248.553872920768, 5258.368995720538, 5260.9736951315, 4969.726665033458, 5016.54851063406, 5020.2720605511995, 5057.165567681365, 5065.316182315414, 5065.214891691794, 5123.820666276662, 5149.047426226085, 5154.7164905929785, 5164.56101231392, 5089.770960306795, 5100.1948928089405, 5111.667741596784, 5123.180291153398, 5134.075866344094, 5113.181890897438, 5121.4622464641425, 5125.794968201305, 5127.769250383667, 5134.306868572695, 4153.646940010072, 4161.667066634723, 4249.107687385684, 4321.7406955582765, 4322.5677432256525, 3671.9816400917825, 3686.189038411545, 3700.6669399753614, 3710.276496083292, 3734.589195188527, 4409.4488188976375, 4417.447819022622, 4418.116376724668, 4419.867403977886, 4444.755538445352, 5035.144025515671, 5136.717480538607, 5189.636725429184, 5227.738613069331, 5247.239150323182, 4452.532484089189, 4463.866084017484, 4466.798994045275, 4470.262406702923, 4498.865034048984, 4485.0805584855025, 4556.7721613919175, 4570.108828778423, 4575.153596584576, 4593.563611456906, 4845.200541910545, 4878.170710979213, 4890.9021819562695, 4928.088303663833, 4931.211006239128, 5156.097682905552, 5221.791128354976, 5228.268042474133, 5230.320058392384, 5275.182346736262, 4777.474477807369, 4889.217725163957, 4910.189818679878, 4913.70517768935, 4916.803327866845, 5060.746962651926, 5077.847664570068, 5081.466446023114, 5094.719790411415, 5131.563817075441, 5104.259882316969, 5146.794128234828, 5153.3815942087185, 5165.690058596496, 5202.453742356998, 4812.590929770869, 4910.238912968495, 4963.528464795839, 5003.399592048978, 5008.524190201925, 4945.777440015136, 4968.801247950041, 4970.602351811919, 4976.054549635558, 4981.252812078217, 4887.7556122194455, 4915.139850526215, 4916.139675556835, 4920.852374428773, 4964.429090654621, 4928.112939670894, 4952.331435256195, 4993.900121997613, 5039.571636410946, 5067.569256613229, 4826.565609095198, 4903.0, 4983.601311895112, 5013.849584512471, 5017.62367822422], "value": 4706.992817868579, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [565.8387359602514, 614.048225250861, 615.7536985205918, 631.151101768122, 639.5938579002332, 32039.199020024484, 32588.67411325886, 33086.33827323455, 33110.17224569387, 33217.501737473925, 32530.18674533138, 32553.0, 33368.165795855115, 33424.0, 33563.83218083912, 7550.282723141293, 7785.1436342002335, 7805.4536182467255, 7814.570198639072, 7830.138684744702, 7666.0, 7768.067831860157, 7783.727569535067, 7788.493747906368, 7792.10390805059, 7740.9225907740765, 7773.8833917491, 7828.491148075385, 7833.21667833215, 7846.254605812467, 7675.04061992251, 7751.418643601724, 7751.457397982087, 7755.612219389036, 7756.301932826016, 7689.9615501922135, 7768.456208065438, 7780.49426787257, 7787.805304867367, 7802.687892484294, 25311.873440632862, 34482.82758586191, 34975.6502434975, 35031.1242218944, 35075.298494030234, 2942.2350188950813, 2952.335724462, 3075.1543325585644, 3234.0297910626955, 3456.9827150864335, 24470.877645611836, 24694.25917222486, 24759.5048099037, 24844.50310993788, 25096.623550646756, 3300.5544251525976, 3323.833808309575, 3406.9488957665408, 3427.845746941394, 3436.9828150859335, 143601.28199358936, 145228.0, 145310.27344863207, 145381.27309363385, 146175.2691236537, 31218.21954451157, 31542.053738387884, 31633.20916977048, 31663.525047124313, 31700.524492132638, 32527.0, 32598.348033039205, 52315.0, 53666.19500707492, 53891.46108538943, 13755.31223438844, 13885.930570347084, 13931.65170870716, 13932.860671393157, 13933.930330348283, 10756.78486430267, 10793.29843560166, 10854.67435976922, 10858.94570527158, 10860.565577376816, 17301.221445035004, 17371.82628173702, 17453.0, 17457.738133928, 17464.563385915455, 6480.870382592322, 6684.498662600325, 6687.799366019027, 6874.381305682413, 6939.230496223532, 942.9669961551313, 956.1824639932896, 1022.2486472442748, 1058.872935247775, 1064.2762921213493, 25884.092781356416, 26253.21240362792, 26546.601800973, 26789.732102678725, 26943.326416839343, 988.2983082011708, 997.7356000659831, 998.3710262534556, 1052.2634156090614, 1072.7103682005816, 13970.301484925916, 14435.711285774225, 14522.636934076521, 14546.854531454761, 14695.0, 263292.0, 268393.0, 270237.0, 270658.0, 270783.0, 44820.3276950846, 45313.54686453159, 45390.0, 45428.091438171716, 45434.0, 1195.647284051207, 1266.778313795082, 1269.041873385588, 1270.7267937393506, 1271.3389037700344, 25168.37079073038, 25782.484350313265, 25855.741442585335, 26102.34744131412, 26370.472590548467, 39816.60183398245, 39980.0, 40271.0, 40313.97645176682, 40351.3947290785, 198719.0, 200696.0, 200894.0, 201180.0, 202552.0, 24187.0, 24628.753712462647, 24631.137910173056, 24650.50698986046, 24772.0, 38844.223115538094, 39653.405198921464, 39793.80103099466, 39799.0, 40330.596694033855, 34460.655393446745, 34470.31059378848, 34734.65265347314, 34856.82571587126, 34880.30239395249, 33525.66474335225, 34022.31955360829, 34231.0, 34419.4837077439, 34563.0, 27285.181444556696, 30375.848120759256, 31358.52962205523, 33881.15297117592, 33887.322253555285, 28736.42527149487, 29507.0, 29954.700452995192, 30254.243643909078, 30416.847915760278, 23031.193908213132, 23724.644130338405, 23734.881325593262, 24007.0, 24068.39829004289], "value": 15929.081464880626, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [382.8583424133071, 383.14558534468136, 383.8196047857506, 396.68067205899257, 402.2839345964185, 14373.065750726195, 14874.181919994397, 15027.098374097563, 15212.467563635275, 15285.617859553502, 14598.927005364985, 15543.533693989199, 16233.91883040586, 16281.348746050173, 16358.263878125483, 5015.498450154973, 5247.160454327309, 5289.2859463972445, 5289.788408463657, 5298.894022119555, 3684.7236457265676, 5715.1998720179, 6024.397560243962, 6029.638221706689, 6040.48655864252, 5063.898722025558, 5888.64668119912, 5892.175095486636, 5900.616459930112, 5940.405959404046, 5332.066888294552, 5346.144616861322, 5357.598180136505, 5369.24830523727, 5384.3807962084275, 5002.324686167393, 5398.676079435247, 5411.242426060355, 5421.9457805421835, 5439.809606663787, 11254.324740515594, 11312.434378281054, 11505.827412588818, 13167.802482962667, 19441.416757497296, 1822.6263615958828, 1913.9521511962173, 1938.9903050484797, 2256.9687281446663, 2275.2264230161823, 8698.086700896441, 8909.975352834443, 8939.19547240745, 11051.17116216287, 11883.168178227528, 1797.8381945624956, 1809.665211935783, 1831.7269497699103, 1858.0297703642836, 1894.8578856585673, 59295.0, 59773.402265977216, 60354.0, 60962.0, 61646.07530887085, 9814.165795907427, 9902.455364954996, 10065.69802905914, 10126.620244242597, 10233.43716095607, 13812.37844297009, 14001.29993500321, 14048.0, 14110.0, 14114.223717695626, 7072.257412971667, 7286.0, 7329.340359367682, 7403.777886663409, 7565.205653406423, 7005.754798582026, 7016.684249208798, 7045.612491312924, 7162.785116446515, 7163.42692584592, 4269.786510674454, 4329.631981281564, 4360.193364227628, 4374.21264172452, 4434.778261086933, 1718.682043821872, 1768.1777973242504, 1870.6632806095033, 1884.9528761781064, 1899.3162461513843, 823.9958800206081, 886.3352485635774, 915.3409545127631, 918.9615734220288, 974.2157563161722, 14103.435862565588, 14585.92707036458, 14946.701065978838, 15185.088894666134, 16261.918690406708, 905.5517518828223, 925.9259259259243, 929.9116583924516, 933.7712260496222, 953.1564565359706, 8901.243394311427, 9118.908810911806, 9142.680006199751, 9266.0, 9348.392354496937, 338498.0, 342723.0, 343046.0, 343571.0, 360178.0, 63882.6805865977, 68975.31024689789, 69165.30834691589, 70308.6484567574, 71295.64352178205, 891.7191084808264, 899.6581299106429, 913.6710784117712, 917.4266083697689, 917.6512925088424, 13863.514776982756, 14086.718265634834, 14300.0, 14324.28378581046, 14457.0, 30911.76352945947, 30983.535246970863, 31475.0, 31872.36255274928, 32302.83848580742, 41557.58442415537, 41644.0, 42268.154636907704, 42782.144357113306, 43357.78321108374, 7523.510971786816, 7573.545587264782, 7643.121041080347, 7644.847103057797, 7704.6918123274445, 9955.452450115314, 10240.05438966594, 10633.415162165846, 11258.0, 11307.717307067389, 9564.282678799256, 9634.421934683942, 9744.561494732896, 9805.607775688892, 9823.607055717692, 9550.90449095528, 9642.276829237695, 9823.115919567274, 9880.45657488845, 10117.494125293853, 9134.26925845944, 9239.0, 9468.715938521855, 9687.757806054906, 10004.199664026715, 7639.579823109782, 7750.302472777477, 7778.572178530008, 7799.5320280783335, 7834.216578342121, 8587.785305367415, 9793.51032448389, 9910.801783964136, 10007.449590272605, 10046.04562566555], "value": 7890.9257949563225, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1407.94368225271, 1432.5344263114487, 1539.9846001539988, 1688.4934519644103, 1707.1037705204774, 6285.685715714208, 6748.527603067787, 6873.690683919223, 7114.893276600848, 7136.928630713698, 6827.249002609709, 6896.655167241643, 6940.583564986105, 6975.2675969023285, 6982.650867456619, 2156.676498525222, 2184.388371256051, 2196.0447205465675, 2202.922897698581, 2205.0848897707433, 2123.946901327464, 2169.858959167657, 2188.102877820094, 2195.1768086967386, 2203.4050806282294, 2157.6763485477145, 2177.858439201447, 2179.5640871825617, 2183.4977955070344, 2214.900329485177, 2148.881811500367, 2150.387139665188, 2150.4838838678684, 2150.6773983902385, 2151.075037733774, 2114.587655407196, 2138.8823614701187, 2148.52732398872, 2150.666646669769, 2150.763416024236, 7093.964530177368, 7103.964480177618, 7108.324709152622, 7130.25132361105, 7130.465215108888, 4419.690621656486, 4556.066006468666, 4556.270996640522, 4566.725996440225, 4585.037142200142, 2756.834589924611, 2819.887204511838, 2859.742623163925, 2897.840618765967, 2906.6512018557696, 820.5856042698459, 920.2408013388914, 939.5020639061308, 949.7483166960761, 959.8800149981253, 8851.0, 8874.866876996786, 8942.508162051085, 9021.684241051531, 9033.412828166149, 4221.387898754663, 4224.134052519226, 4241.194173107161, 4286.678499112579, 4294.463192100988, 7813.726519571901, 7919.247671471201, 7930.405219608552, 7970.123286438487, 8010.479318844256, 4457.8439754608435, 4496.7077139985795, 4508.774561271924, 4521.638268938543, 4553.362529245908, 3636.254567813592, 3934.1934903344745, 4028.8992775180855, 4034.1931613677384, 4063.0, 3541.061618671054, 3574.857005719794, 3609.4733686419613, 3653.6711695947492, 3660.8718694846084, 1206.631977246948, 1237.6905773556612, 1243.247835059794, 1248.3508575540668, 1253.5549879792725, 748.0163584885858, 871.904090550039, 883.3507372081515, 888.7422647432172, 909.9863502047475, 4906.288588154697, 5064.34163558735, 5069.619778516552, 5087.8982420351385, 5143.742812859342, 879.331707901999, 916.9174774270215, 956.2111258212003, 969.8836139663285, 973.4402718436927, 3300.976697223868, 3354.04409743222, 3378.3918894598714, 3392.4402473592077, 3413.4709120086427, 8236.546989915492, 8510.872336914952, 8528.701495447754, 8529.402941794015, 8540.914590854136, 8390.412671113085, 8425.157484251526, 8474.27968622674, 8603.52680602573, 8698.391112622183, 6608.900866487006, 6689.4982876284475, 6810.182778066664, 6968.0, 7034.613096279753, 4790.712557246577, 4821.180399332192, 4849.830255941025, 4851.733154676526, 4852.0, 8014.078380986028, 8016.078151012708, 8181.018277806464, 8234.464759790595, 8251.504909705436, 7645.0, 7958.602069896598, 7962.562059086806, 8041.43709940322, 8111.75664730059, 5653.858653533694, 6080.331163572092, 6103.206583144163, 6233.625982441068, 6333.20834895638, 6699.464042876461, 6806.217285012287, 6813.8977915330315, 6833.043373927753, 6848.14398200225, 5907.4683278505145, 6096.420840020191, 6105.908411373922, 6396.520260980352, 6631.0, 6304.968475157595, 6333.145025421509, 6395.5203359747275, 6445.6454894981225, 6496.415322620986, 5820.447057529528, 5866.6186697864505, 5892.882142357214, 5894.0274854650215, 5895.0862616292825, 4405.955940440683, 4412.51462339136, 4461.107778444389, 4526.411566496467, 4568.79440425175, 4530.932036019396, 4568.200564901195, 4593.471750748573, 4608.147492713859, 4671.416072990876], "value": 3949.874251445697, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [310.87564974010394, 321.0096851214005, 323.3242523126666, 324.69154303411756, 326.03818734732545, 4870.464248932619, 4892.0, 4941.827036053739, 4966.652334336598, 5001.549860512549, 4861.659683822134, 4867.561919427251, 4869.610431165507, 4872.805087796492, 4890.144224760662, 3665.413533834587, 3790.77255364679, 3914.7063970202207, 3921.0, 3921.450996860442, 3883.339832228514, 3904.7461914975434, 3905.1408690088133, 3917.29597625034, 3919.3729003359326, 3880.9805950970203, 3888.300105980922, 3909.491766070407, 3911.8239679214503, 3924.3917192835156, 3780.3573392523335, 3834.293739286019, 3839.1361943562756, 3918.294706952747, 3921.3529767588316, 3391.406503861839, 3403.5575375201065, 3405.0465869556565, 3408.250184959305, 3437.693676402951, 4682.7658617069355, 4688.203005489041, 4697.67116301859, 4707.364505791708, 4715.693479923794, 4386.210482113218, 4408.559144085566, 4438.578335058164, 4454.643628509712, 4458.108378324316, 4942.975285123587, 5121.615878809067, 5137.922931156036, 5158.767855446514, 5172.19830926207, 3766.4538641897047, 3770.472133901256, 3786.829592668309, 3788.772673639563, 3791.2607041626893, 5389.191621256804, 5417.539509141728, 5452.182172674091, 5455.509004189642, 5457.181422786574, 3732.8880133596035, 3820.713446491469, 3837.597052309523, 3882.7087968402484, 3896.8636097736444, 3961.069148750041, 3975.8927768282856, 3980.7611543306916, 3983.203359328146, 3983.223271462095, 4420.336949457607, 4433.357163211316, 4440.4671439427475, 4444.822207111744, 4471.8658440246845, 4956.157453232959, 5105.540501354896, 5117.775801245191, 5153.587712983027, 5166.2508936204795, 1448.7182687267596, 1465.8827293816469, 1466.3914475492552, 1467.2590341877496, 1468.5374107156213, 925.5418567808978, 979.5102448775613, 984.0946329376991, 1000.1498726082772, 1010.4745532323298, 4448.332750087513, 4583.175028494903, 4603.516630753789, 4628.861134165981, 4631.490536041033, 5338.225957236179, 5361.571074314045, 5367.523930919028, 5394.91907621386, 5423.2136340230445, 4470.664700147502, 4581.839635612738, 4592.954070459253, 4607.9308810367875, 4610.124076425468, 3028.8485575721566, 3061.709137631966, 3066.018873960356, 3067.0952069139657, 3078.5382192671277, 5426.647267927572, 5428.348598168245, 5432.320959880015, 5438.129899216106, 5456.61803673739, 5677.858053548694, 5810.6513609183585, 5829.5044921181325, 5872.853178670567, 5892.263467066617, 5338.492843179892, 5365.941413394327, 5371.113766228685, 5396.1366181410785, 5419.320010796587, 5671.914921276101, 5691.0, 5691.971540142438, 5698.629589076697, 5714.971425142848, 5017.12200364942, 5278.392984806796, 5285.070949103627, 5290.285811415563, 5309.203619457112, 5020.272060551127, 5039.7732102056225, 5130.870688664162, 5144.4855514449755, 5144.922826157535, 3741.0, 3794.4877441545036, 3802.73380863348, 3806.8096595169577, 3810.714196435223, 5181.3005244293045, 5281.392639846466, 5321.148616221386, 5321.973390133025, 5373.919391209057, 5155.252488389237, 5189.481051894932, 5191.610629202901, 5197.116490196751, 5208.328128669204, 4920.2041254942405, 5173.715445650375, 5179.663321884216, 5184.196449550326, 5203.27154198405, 5126.871828204325, 5128.564072053768, 5145.218264575459, 5145.253938179018, 5163.8192663258105, 4934.235193545006, 5050.924236136534, 5201.375834899685, 5272.0, 5283.89432211346, 5120.846374608767, 5146.382434107931, 5159.251908473176, 5178.61160412963, 5180.326557547494], "value": 3986.8676260910584, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [697.4908316928642, 704.1093017333073, 718.9856202875943, 752.1951511882288, 788.235411650699, 11483.712907177314, 11847.644570662873, 11893.22694024889, 11994.280343179404, 12064.879351206486, 11644.767104657902, 12817.166884152524, 13371.064025518217, 13403.262820544865, 13436.529721459727, 798.7923139983617, 806.7862016565615, 807.3944541593804, 807.4711064252924, 807.6446363600027, 633.5723386713972, 773.1649818196363, 798.22572105058, 799.9200079991983, 801.1027649033091, 831.5301854452215, 842.1031601344579, 844.6199210355364, 844.8141408890065, 846.5767116441779, 834.7996480844614, 835.8035861572556, 836.69042454292, 837.0165056059167, 839.4291881520563, 606.4602503771647, 610.8808782287456, 614.4746241963146, 615.2125279642021, 616.3651439017818, 12853.100282980195, 12958.611241662766, 13001.544945926942, 13012.934935325262, 13018.674533136653, 12005.399730013465, 12121.151519393647, 12190.08574356918, 12209.93895030528, 12523.248605083632, 10089.899101008969, 10248.79502409955, 10363.378197308186, 10409.531571079244, 10444.738881527946, 3192.808431494118, 3214.180384002088, 3222.822744749038, 3253.1053960160907, 3334.7665663403573, 39978.40032399516, 41899.162016759496, 44504.77747611274, 44878.878028049236, 45070.0, 9548.618055277711, 9577.377470464537, 9598.808023839623, 9764.560594773253, 9868.506574671237, 16647.250873710713, 16718.080505572067, 16742.916285418494, 16763.49709508717, 16784.41254556085, 6666.733330666719, 6691.230508491586, 6762.425193858478, 6767.154105736783, 7053.426740794234, 3517.2437925846066, 3798.2973149967343, 3861.362875125573, 3882.2235552889533, 3908.785016824102, 2751.9724802752116, 2793.6228609137506, 2818.760405365526, 2818.9295267618063, 2823.816451930618, 493.42269544632865, 501.43338028028097, 507.8857257117193, 511.37867490998616, 514.0644027869242, 545.8253358925185, 556.9526590239794, 559.8068666310198, 573.650073455192, 584.5732615190934, 7525.736599219111, 7624.961875190588, 7829.608519573999, 7917.327027202752, 7964.561949092739, 478.1393491714899, 491.4544855210713, 573.3234782956068, 589.9440002395663, 647.4529022975578, 3433.3476639438927, 3543.7342199335153, 3592.8023958682, 3593.13764696471, 3611.2777444511203, 56029.719851400485, 56363.71818140883, 56841.71579142078, 57750.7112464435, 57879.42120578825, 47311.76344118326, 47837.76081119572, 48696.269555956686, 49637.25544116841, 50398.49601504012, 45336.50649214259, 46061.30908036382, 46082.30876536854, 46398.66415362209, 46637.7668111664, 6822.556533825285, 6892.551984121016, 6893.655317234018, 6979.930200697928, 7499.400047996256, 29959.0, 30256.243593909447, 30462.0, 30625.54061689121, 31475.685243147276, 39322.41016384814, 41132.0, 41656.3751543721, 41656.375154373316, 41969.79015104905, 10918.12654987615, 11019.228653994143, 11066.612668556561, 11187.664370068585, 11254.88745112571, 11992.940035299767, 12048.457819398323, 12485.250884946568, 12504.0, 12547.686307842378, 10895.237333386587, 11111.388873611706, 11182.776344473228, 11399.715007124889, 11692.566981436177, 11303.7739245213, 11422.543098276308, 11487.081033517132, 11551.191416601094, 11689.181757276909, 9837.508124593885, 10959.780804384027, 11267.0, 11267.042301404144, 11326.886731132583, 8218.342532597497, 8553.61508732096, 8771.561421929006, 8920.910790892009, 9057.818843623223, 8223.424360294723, 8454.535526720829, 8994.280457563511, 9093.77265568366, 9219.124183202584], "value": 5907.2042982875, "unit": "score"}], "name": "dromaeo_css", "value": 6167.61555199412, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 111.0, 112.0, 114.0, 114.0, 125.0, 114.0, 113.0, 114.0, 114.0], "value": 114.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 134.0, 153.0, 195.0, 134.0, 159.0, 156.0, 134.0, 188.0, 141.0], "value": 155.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 165.0, 165.0, 168.0, 166.0, 161.0, 165.0, 164.0, 167.0, 167.0], "value": 165.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 77.0, 74.0, 179.0, 76.0, 78.0, 74.0, 75.0, 153.0, 74.0], "value": 93.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 97.0, 155.0, 95.0, 94.0, 94.0, 147.0, 94.0, 97.0, 144.0], "value": 111.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 118.0, 117.0, 117.0, 118.0, 117.0, 118.0, 118.0, 118.0, 118.0], "value": 117.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 146.0, 146.0, 147.0, 146.0, 147.0, 147.0, 146.0, 147.0, 148.0], "value": 146.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 95.0, 95.0, 95.0, 96.0, 95.0, 95.0, 95.0, 95.0], "value": 95.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 66.0, 64.0, 65.0, 65.0, 66.0, 66.0, 89.0, 62.0, 61.0], "value": 67.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 66.0, 65.0, 66.0, 65.0, 65.0, 64.0, 69.0, 64.0], "value": 65.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 88.0, 80.0, 80.0, 81.0, 85.0, 80.0, 90.0, 82.0, 81.0], "value": 82.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 135.0, 143.0, 133.0, 133.0, 152.0, 143.0, 132.0, 169.0, 147.0], "value": 143.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [229.0, 224.0, 221.0, 219.0, 221.0, 216.0, 221.0, 212.0, 215.0, 219.0], "value": 219.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 75.0, 77.0, 87.0, 77.0, 77.0, 75.0, 73.0, 75.0, 72.0], "value": 77.0, "unit": "score"}], "name": "kraken", "value": 1654.9000000000003, "alertThreshold": 2.0}]} 07:51:08 INFO - Return code: 0 07:51:09 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 07:51:09 ERROR - # TBPL SUCCESS # 07:51:09 INFO - Running post-action listener: _resource_record_post_action 07:51:09 INFO - Running post-run listener: _resource_record_post_run 07:51:10 INFO - Total resource usage - Wall time: 1197s; CPU: 13.0%; Read bytes: 2482176; Write bytes: 539844608; Read time: 776; Write time: 489912 07:51:10 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 0; Write bytes: 144347136; Read time: 0; Write time: 139904 07:51:10 INFO - run-tests - Wall time: 1181s; CPU: 13.0%; Read bytes: 2482176; Write bytes: 291708928; Read time: 776; Write time: 271500 07:51:10 INFO - Running post-run listener: _upload_blobber_files 07:51:10 INFO - Blob upload gear active. 07:51:10 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:51:10 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 07:51:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 07:51:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 07:51:10 INFO - (blobuploader) - INFO - Open directory for files ... 07:51:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 07:51:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:51:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:51:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 07:51:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:51:11 INFO - (blobuploader) - INFO - Done attempting. 07:51:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 07:51:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:51:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:51:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 07:51:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:51:12 INFO - (blobuploader) - INFO - Done attempting. 07:51:12 INFO - (blobuploader) - INFO - Iteration through files over. 07:51:12 INFO - Return code: 0 07:51:12 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:51:12 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:51:12 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/072ec7fa5ff9cbcd6f426c5c6e616c4a4532b96b2ffc2d6a43dfd58fde393bd21900a8d7050258fa5b7078f1cbf298949c5269141c205d53514cc8db3d15a1a5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/eeaee6ae4416befe0eb057a07e51641dd77cd23247d4ec5bdd1dca633263b0e3bdfedf554742466f00a3d09bb34ccf017ba1291e1fd62294248ee1e006b728b2"} 07:51:12 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:51:12 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:51:12 INFO - Contents: 07:51:12 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/072ec7fa5ff9cbcd6f426c5c6e616c4a4532b96b2ffc2d6a43dfd58fde393bd21900a8d7050258fa5b7078f1cbf298949c5269141c205d53514cc8db3d15a1a5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/eeaee6ae4416befe0eb057a07e51641dd77cd23247d4ec5bdd1dca633263b0e3bdfedf554742466f00a3d09bb34ccf017ba1291e1fd62294248ee1e006b728b2"} 07:51:12 INFO - Running post-run listener: copy_logs_to_upload_dir 07:51:12 INFO - Copying logs to upload dir... 07:51:12 INFO - mkdir: /builds/slave/test/build/upload/logs 07:51:12 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1238.027340 ========= master_lag: 16.16 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 54 secs) (at 2016-04-27 07:51:27.980623) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-27 07:51:27.984486) ========= 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=32c5991c409366f7fec8a718000001d2-1461767131.869006-389222091 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/072ec7fa5ff9cbcd6f426c5c6e616c4a4532b96b2ffc2d6a43dfd58fde393bd21900a8d7050258fa5b7078f1cbf298949c5269141c205d53514cc8db3d15a1a5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/eeaee6ae4416befe0eb057a07e51641dd77cd23247d4ec5bdd1dca633263b0e3bdfedf554742466f00a3d09bb34ccf017ba1291e1fd62294248ee1e006b728b2"} build_url:https://queue.taskcluster.net/v1/task/JNpWz5iKRZqd5ze4Ucr82g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011322 build_url: 'https://queue.taskcluster.net/v1/task/JNpWz5iKRZqd5ze4Ucr82g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/072ec7fa5ff9cbcd6f426c5c6e616c4a4532b96b2ffc2d6a43dfd58fde393bd21900a8d7050258fa5b7078f1cbf298949c5269141c205d53514cc8db3d15a1a5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/eeaee6ae4416befe0eb057a07e51641dd77cd23247d4ec5bdd1dca633263b0e3bdfedf554742466f00a3d09bb34ccf017ba1291e1fd62294248ee1e006b728b2"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-27 07:51:28.008710) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-27 07:51:28.009053) ========= 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=32c5991c409366f7fec8a718000001d2-1461767131.869006-389222091 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004497 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-27 07:51:28.058892) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-27 07:51:28.059221) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-27 07:51:28.059521) ========= ========= Total master_lag: 16.28 =========