builder: autoland_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-004 starttime: 1467864318.0 results: success (0) buildid: 20160706192822 builduid: 901bc8eb198d471ea20489dd63ed7619 revision: 989bd85d2f8e49adb4d41be16795d27647609367 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-06 21:05:17.998464) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-06 21:05:17.998894) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-06 21:05:17.999210) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-06 21:05:18.091327) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-06 21:05:18.091664) ========= 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=c6927cf182745ad25b1d38b9000001fe-1467863947.493684-852873449 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-06 21:05:18-- 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% 21.2M=0.001s 2016-07-06 21:05:18 (21.2 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.201062 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-06 21:05:18.335303) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-06 21:05:18.335659) ========= 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=c6927cf182745ad25b1d38b9000001fe-1467863947.493684-852873449 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018709 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-06 21:05:18.392295) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 7 mins, 11 secs) (at 2016-07-06 21:05:18.392673) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 989bd85d2f8e49adb4d41be16795d27647609367 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev 989bd85d2f8e49adb4d41be16795d27647609367 --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=c6927cf182745ad25b1d38b9000001fe-1467863947.493684-852873449 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-06 21:05:18,454 truncating revision to first 12 chars 2016-07-06 21:05:18,454 Setting DEBUG logging. 2016-07-06 21:05:18,454 attempt 1/10 2016-07-06 21:05:18,454 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/989bd85d2f8e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-06 21:06:18,518 sleeping for 9.00s (attempt 1/10) 2016-07-06 21:06:27,527 attempt 2/10 2016-07-06 21:06:27,527 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/989bd85d2f8e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-06 21:07:27,590 sleeping for 14.50s (attempt 2/10) 2016-07-06 21:07:42,105 attempt 3/10 2016-07-06 21:07:42,105 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/989bd85d2f8e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-06 21:08:42,169 sleeping for 22.75s (attempt 3/10) 2016-07-06 21:09:04,942 attempt 4/10 2016-07-06 21:09:04,942 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/989bd85d2f8e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-06 21:10:05,005 sleeping for 33.12s (attempt 4/10) 2016-07-06 21:10:38,163 attempt 5/10 2016-07-06 21:10:38,163 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/989bd85d2f8e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-06 21:11:38,227 sleeping for 50.69s (attempt 5/10) 2016-07-06 21:12:28,965 attempt 6/10 2016-07-06 21:12:28,965 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/989bd85d2f8e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-06 21:12:29,418 unpacking tar archive at: autoland-989bd85d2f8e/testing/mozharness/ program finished with exit code 0 elapsedTime=431.323994 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 7 mins, 11 secs) (at 2016-07-06 21:12:29.728676) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-06 21:12:29.729490) ========= script_repo_revision: 989bd85d2f8e49adb4d41be16795d27647609367 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-06 21:12:29.730538) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-06 21:12:29.731272) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-06 21:12:29.743634) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 44 secs) (at 2016-07-06 21:12:29.743945) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Autoland-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland-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=c6927cf182745ad25b1d38b9000001fe-1467863947.493684-852873449 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 21:12:29 INFO - MultiFileLogger online at 20160706 21:12:29 in /builds/slave/test 21:12:29 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland-Non-PGO 21:12:29 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 21:12:29 INFO - {'append_to_log': False, 21:12:29 INFO - 'base_work_dir': '/builds/slave/test', 21:12:29 INFO - 'blob_upload_branch': 'Autoland-Non-PGO', 21:12:29 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 21:12:29 INFO - 'branch': 'Autoland-Non-PGO', 21:12:29 INFO - 'buildbot_json_path': 'buildprops.json', 21:12:29 INFO - 'config_files': ('talos/linux_config.py',), 21:12:29 INFO - 'default_actions': ('clobber', 21:12:29 INFO - 'read-buildbot-config', 21:12:29 INFO - 'download-and-extract', 21:12:29 INFO - 'populate-webroot', 21:12:29 INFO - 'create-virtualenv', 21:12:29 INFO - 'install', 21:12:29 INFO - 'run-tests'), 21:12:29 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:12:29 INFO - 'download_minidump_stackwalk': True, 21:12:29 INFO - 'download_symbols': 'ondemand', 21:12:29 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:12:29 INFO - 'tooltool.py': '/tools/tooltool.py', 21:12:29 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:12:29 INFO - '/tools/misc-python/virtualenv.py')}, 21:12:29 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:12:29 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:12:29 INFO - 'installer_path': 'installer.exe', 21:12:29 INFO - 'log_level': 'info', 21:12:29 INFO - 'log_name': 'talos', 21:12:29 INFO - 'log_to_console': True, 21:12:29 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 21:12:29 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 21:12:29 INFO - 'opt_config_files': (), 21:12:29 INFO - 'pip_index': False, 21:12:29 INFO - 'sps_profile': False, 21:12:29 INFO - 'sps_profile_interval': 0, 21:12:29 INFO - 'suite': 'dromaeojs', 21:12:29 INFO - 'system_bits': '32', 21:12:29 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:12:29 INFO - 'title': 'talos-linux64-ix-004', 21:12:29 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:12:29 INFO - 'use_talos_json': True, 21:12:29 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 21:12:29 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:12:29 INFO - 'work_dir': 'build'} 21:12:29 INFO - ##### 21:12:29 INFO - ##### Running clobber step. 21:12:29 INFO - ##### 21:12:29 INFO - Running pre-action listener: _resource_record_pre_action 21:12:29 INFO - Running main action method: clobber 21:12:29 INFO - rmtree: /builds/slave/test/build 21:12:29 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 21:12:30 INFO - Running post-action listener: _resource_record_post_action 21:12:30 INFO - ##### 21:12:30 INFO - ##### Finished clobber step (success) 21:12:30 INFO - ##### 21:12:30 INFO - ##### 21:12:30 INFO - ##### Running read-buildbot-config step. 21:12:30 INFO - ##### 21:12:30 INFO - Running pre-action listener: _resource_record_pre_action 21:12:30 INFO - Running main action method: read_buildbot_config 21:12:30 INFO - Using buildbot properties: 21:12:30 INFO - { 21:12:30 INFO - "project": "", 21:12:30 INFO - "product": "firefox", 21:12:30 INFO - "script_repo_revision": "production", 21:12:30 INFO - "builddir": "autoland_ubuntu64_hw_test-dromaeojs", 21:12:30 INFO - "repository": "", 21:12:30 INFO - "buildername": "Ubuntu HW 12.04 x64 autoland talos dromaeojs", 21:12:30 INFO - "buildid": "20160706192822", 21:12:30 INFO - "pgo_build": "False", 21:12:30 INFO - "basedir": "/builds/slave/test", 21:12:30 INFO - "buildnumber": 72, 21:12:30 INFO - "slavename": "talos-linux64-ix-004", 21:12:30 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 21:12:30 INFO - "platform": "ubuntu64_hw", 21:12:30 INFO - "scheduler": "tests-autoland-linux64-talos", 21:12:30 INFO - "branch": "autoland", 21:12:30 INFO - "repo_path": "integration/autoland", 21:12:30 INFO - "revision": "989bd85d2f8e49adb4d41be16795d27647609367", 21:12:30 INFO - "stage_platform": "linux64", 21:12:30 INFO - "builduid": "901bc8eb198d471ea20489dd63ed7619", 21:12:30 INFO - "slavebuilddir": "test" 21:12:30 INFO - } 21:12:30 INFO - Found installer url https://queue.taskcluster.net/v1/task/Akp9pnM8Speh41N9z8cnWQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 21:12:30 INFO - Running post-action listener: _resource_record_post_action 21:12:30 INFO - ##### 21:12:30 INFO - ##### Finished read-buildbot-config step (success) 21:12:30 INFO - ##### 21:12:30 INFO - ##### 21:12:30 INFO - ##### Running download-and-extract step. 21:12:30 INFO - ##### 21:12:30 INFO - Running pre-action listener: _resource_record_pre_action 21:12:30 INFO - Running main action method: download_and_extract 21:12:30 INFO - mkdir: /builds/slave/test/build/tests 21:12:30 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')]} 21:12:30 INFO - https://queue.taskcluster.net/v1/task/Akp9pnM8Speh41N9z8cnWQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 21:12:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Akp9pnM8Speh41N9z8cnWQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 21:12:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Akp9pnM8Speh41N9z8cnWQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 21:12:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Akp9pnM8Speh41N9z8cnWQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 21:12:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Akp9pnM8Speh41N9z8cnWQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 21:12:31 INFO - Downloaded 1361 bytes. 21:12:31 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 21:12:31 INFO - Using the following test package requirements: 21:12:31 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 21:12:31 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 21:12:31 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 21:12:31 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 21:12:31 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 21:12:31 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 21:12:31 INFO - u'jsshell-linux-x86_64.zip'], 21:12:31 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 21:12:31 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 21:12:31 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 21:12:31 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 21:12:31 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 21:12:31 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 21:12:31 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 21:12:31 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 21:12:31 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 21:12:31 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 21:12:31 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 21:12:31 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 21:12:31 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')]} 21:12:31 INFO - https://queue.taskcluster.net/v1/task/Akp9pnM8Speh41N9z8cnWQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 21:12:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Akp9pnM8Speh41N9z8cnWQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 21:12:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Akp9pnM8Speh41N9z8cnWQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 21:12:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Akp9pnM8Speh41N9z8cnWQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 21:12:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Akp9pnM8Speh41N9z8cnWQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 21:12:32 INFO - Downloaded 22733127 bytes. 21:12:32 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 21:12:32 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 21:12:34 INFO - Return code: 0 21:12:34 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 21:12:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:12:34 INFO - https://queue.taskcluster.net/v1/task/Akp9pnM8Speh41N9z8cnWQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 21:12:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Akp9pnM8Speh41N9z8cnWQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 21:12:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Akp9pnM8Speh41N9z8cnWQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 21:12:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Akp9pnM8Speh41N9z8cnWQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 21:12:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Akp9pnM8Speh41N9z8cnWQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 21:12:35 INFO - Downloaded 22733127 bytes. 21:12:35 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 21:12:35 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 21:12:37 INFO - Return code: 0 21:12:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:12:37 INFO - https://queue.taskcluster.net/v1/task/Akp9pnM8Speh41N9z8cnWQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 21:12:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Akp9pnM8Speh41N9z8cnWQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 21:12:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Akp9pnM8Speh41N9z8cnWQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 21:12:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Akp9pnM8Speh41N9z8cnWQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 21:12:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Akp9pnM8Speh41N9z8cnWQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 21:12:38 INFO - Downloaded 14403333 bytes. 21:12:38 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 21:12:38 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 21:12:38 INFO - Return code: 0 21:12: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')]} 21:12:38 INFO - https://queue.taskcluster.net/v1/task/Akp9pnM8Speh41N9z8cnWQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 21:12:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Akp9pnM8Speh41N9z8cnWQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 21:12:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Akp9pnM8Speh41N9z8cnWQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 21:12:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Akp9pnM8Speh41N9z8cnWQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 21:12:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Akp9pnM8Speh41N9z8cnWQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 21:12:40 INFO - Downloaded 56589128 bytes. 21:12:40 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Akp9pnM8Speh41N9z8cnWQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 21:12:40 INFO - mkdir: /builds/slave/test/properties 21:12:40 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 21:12:40 INFO - Writing to file /builds/slave/test/properties/build_url 21:12:40 INFO - Contents: 21:12:40 INFO - build_url:https://queue.taskcluster.net/v1/task/Akp9pnM8Speh41N9z8cnWQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 21:12:41 INFO - Running post-action listener: _resource_record_post_action 21:12:41 INFO - Running post-action listener: set_extra_try_arguments 21:12:41 INFO - ##### 21:12:41 INFO - ##### Finished download-and-extract step (success) 21:12:41 INFO - ##### 21:12:41 INFO - ##### 21:12:41 INFO - ##### Running populate-webroot step. 21:12:41 INFO - ##### 21:12:41 INFO - Running pre-action listener: _resource_record_pre_action 21:12:41 INFO - Running main action method: populate_webroot 21:12:41 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 21:12:41 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 21:12:41 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 21:12:41 INFO - '--tppagecycles', 21:12:41 INFO - '7'], 21:12:41 INFO - 'tests': ['tsvgm']}}, 21:12:41 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 21:12:41 INFO - 'tests': ['tresize', 'tcanvasmark']}, 21:12:41 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 21:12:41 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 21:12:41 INFO - 'tests': ['dromaeo_css', 'kraken']}, 21:12:41 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 21:12:41 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:12:41 INFO - 'talos_options': ['--disable-e10s'], 21:12:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:12:41 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:12:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:12:41 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:12:41 INFO - 'talos_options': ['--disable-e10s'], 21:12:41 INFO - 'tests': ['damp', 'tps']}, 21:12:41 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:12:41 INFO - 'tests': ['damp', 'tps']}, 21:12:41 INFO - 'g3': {'talos_options': ['--disable-e10s'], 21:12:41 INFO - 'tests': ['dromaeo_dom']}, 21:12:41 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:12:41 INFO - 'g4': {'talos_options': ['--disable-e10s'], 21:12:41 INFO - 'tests': ['basic_compositor_video']}, 21:12:41 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 21:12:41 INFO - 'other': {'talos_options': ['--disable-e10s'], 21:12:41 INFO - 'tests': ['a11yr', 21:12:41 INFO - 'ts_paint', 21:12:41 INFO - 'tpaint', 21:12:41 INFO - 'sessionrestore', 21:12:41 INFO - 'sessionrestore_no_auto_restore', 21:12:41 INFO - 'tabpaint']}, 21:12:41 INFO - 'other-e10s': {'tests': ['a11yr', 21:12:41 INFO - 'ts_paint', 21:12:41 INFO - 'tpaint', 21:12:41 INFO - 'sessionrestore', 21:12:41 INFO - 'sessionrestore_no_auto_restore', 21:12:41 INFO - 'tabpaint']}, 21:12:41 INFO - 'other-e10s_l64': {'tests': ['a11yr', 21:12:41 INFO - 'ts_paint', 21:12:41 INFO - 'tpaint', 21:12:41 INFO - 'sessionrestore', 21:12:41 INFO - 'sessionrestore_no_auto_restore', 21:12:41 INFO - 'tabpaint']}, 21:12:41 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 21:12:41 INFO - 'ts_paint', 21:12:41 INFO - 'tpaint', 21:12:41 INFO - 'sessionrestore', 21:12:41 INFO - 'sessionrestore_no_auto_restore', 21:12:41 INFO - 'tabpaint']}, 21:12:41 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 21:12:41 INFO - 'tests': ['a11yr', 21:12:41 INFO - 'ts_paint', 21:12:41 INFO - 'tpaint', 21:12:41 INFO - 'sessionrestore', 21:12:41 INFO - 'sessionrestore_no_auto_restore', 21:12:41 INFO - 'tabpaint']}, 21:12:41 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 21:12:41 INFO - 'tests': ['a11yr', 21:12:41 INFO - 'ts_paint', 21:12:41 INFO - 'tpaint', 21:12:41 INFO - 'sessionrestore', 21:12:41 INFO - 'sessionrestore_no_auto_restore', 21:12:41 INFO - 'tabpaint']}, 21:12:41 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 21:12:41 INFO - 'tests': ['tsvgx', 21:12:41 INFO - 'tsvgr_opacity', 21:12:41 INFO - 'tart', 21:12:41 INFO - 'tscrollx', 21:12:41 INFO - 'cart']}, 21:12:41 INFO - 'svgr-e10s': {'tests': ['tsvgx', 21:12:41 INFO - 'tsvgr_opacity', 21:12:41 INFO - 'tart', 21:12:41 INFO - 'tscrollx', 21:12:41 INFO - 'cart']}, 21:12:41 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:12:41 INFO - 'talos_options': ['--disable-e10s'], 21:12:41 INFO - 'tests': ['tp5o']}, 21:12:41 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:12:41 INFO - 'tests': ['tp5o']}, 21:12:41 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:12:41 INFO - 'talos_options': ['--disable-e10s', 21:12:41 INFO - '--xperf_path', 21:12:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:12:41 INFO - 'tests': ['tp5n']}, 21:12:41 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:12:41 INFO - 'talos_options': ['--xperf_path', 21:12:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:12:41 INFO - 'tests': ['tp5n']}}, 21:12:41 INFO - 'talos.zip': {'path': '', 21:12:41 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:12:41 INFO - Running post-action listener: _resource_record_post_action 21:12:41 INFO - ##### 21:12:41 INFO - ##### Finished populate-webroot step (success) 21:12:41 INFO - ##### 21:12:41 INFO - ##### 21:12:41 INFO - ##### Running create-virtualenv step. 21:12:41 INFO - ##### 21:12:41 INFO - Running pre-action listener: _resource_record_pre_action 21:12:41 INFO - Running main action method: create_virtualenv 21:12:41 INFO - Creating virtualenv /builds/slave/test/build/venv 21:12:41 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 21:12:41 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 21:12:41 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:12:41 INFO - Using real prefix '/usr' 21:12:41 INFO - New python executable in /builds/slave/test/build/venv/bin/python 21:12:42 INFO - Installing distribute.............................................................................................................................................................................................done. 21:12:44 INFO - Installing pip.................done. 21:12:44 INFO - Return code: 0 21:12:44 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 21:12: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')]} 21:12:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:12:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:12: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')]} 21:12:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:12:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:12:44 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 0x2020030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21838a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22e1180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22df3b0>, '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 0x22e02b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22e0740>, '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': 'c6927cf182745ad25b1d38b9000001fe-1467863947.493684-852873449', '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 21:12:44 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 21:12:44 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 21:12:44 INFO - Using env: {'DISPLAY': ':0', 21:12:44 INFO - 'HOME': '/home/cltbld', 21:12:44 INFO - 'LANG': 'en_US.UTF-8', 21:12:44 INFO - 'LANGUAGE': 'en_US:en', 21:12:44 INFO - 'LOGNAME': 'cltbld', 21:12:44 INFO - 'MAIL': '/var/mail/cltbld', 21:12:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:12:44 INFO - 'MOZ_NO_REMOTE': '1', 21:12:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:12:44 INFO - 'NO_EM_RESTART': '1', 21:12:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:12:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:12:44 INFO - 'PWD': '/builds/slave/test', 21:12:44 INFO - 'SHELL': '/bin/bash', 21:12:44 INFO - 'SHLVL': '1', 21:12:44 INFO - 'TERM': 'linux', 21:12:44 INFO - 'TMOUT': '86400', 21:12:44 INFO - 'USER': 'cltbld', 21:12:44 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1467863947.493684-852873449', 21:12:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:12:44 INFO - '_': '/tools/buildbot/bin/python'} 21:12:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:12:44 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 21:12:44 INFO - Cleaning up... 21:12:44 INFO - Return code: 0 21:12:44 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 21:12: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')]} 21:12:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:12:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:12: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')]} 21:12:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:12:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:12:44 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>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2020030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21838a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22e1180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22df3b0>, '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 0x22e02b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22e0740>, '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': 'c6927cf182745ad25b1d38b9000001fe-1467863947.493684-852873449', '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 21:12:44 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>=3.1.1'] in /builds/slave/test/build 21:12:44 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>=3.1.1 21:12:44 INFO - Using env: {'DISPLAY': ':0', 21:12:44 INFO - 'HOME': '/home/cltbld', 21:12:44 INFO - 'LANG': 'en_US.UTF-8', 21:12:44 INFO - 'LANGUAGE': 'en_US:en', 21:12:44 INFO - 'LOGNAME': 'cltbld', 21:12:44 INFO - 'MAIL': '/var/mail/cltbld', 21:12:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:12:44 INFO - 'MOZ_NO_REMOTE': '1', 21:12:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:12:44 INFO - 'NO_EM_RESTART': '1', 21:12:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:12:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:12:44 INFO - 'PWD': '/builds/slave/test', 21:12:44 INFO - 'SHELL': '/bin/bash', 21:12:44 INFO - 'SHLVL': '1', 21:12:44 INFO - 'TERM': 'linux', 21:12:44 INFO - 'TMOUT': '86400', 21:12:44 INFO - 'USER': 'cltbld', 21:12:44 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1467863947.493684-852873449', 21:12:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:12:44 INFO - '_': '/tools/buildbot/bin/python'} 21:12:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:12:45 INFO - Downloading/unpacking psutil>=3.1.1 21:12:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:12:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:12:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:12:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:12:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:12:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:12:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 21:12:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:12:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:12:47 INFO - Installing collected packages: psutil 21:12:47 INFO - Running setup.py install for psutil 21:12:47 INFO - building 'psutil._psutil_linux' extension 21:12:47 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 21:12:48 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 21:12:48 INFO - building 'psutil._psutil_posix' extension 21:12:48 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 21:12:48 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 21:12:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:12:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:12:48 INFO - Successfully installed psutil 21:12:48 INFO - Cleaning up... 21:12:48 INFO - Return code: 0 21:12:48 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 21:12:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:12:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:12:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:12:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:12:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:12:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:12:48 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.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2020030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21838a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22e1180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22df3b0>, '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 0x22e02b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22e0740>, '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': 'c6927cf182745ad25b1d38b9000001fe-1467863947.493684-852873449', '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 21:12:48 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.3'] in /builds/slave/test/build 21:12:48 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.3 21:12:48 INFO - Using env: {'DISPLAY': ':0', 21:12:48 INFO - 'HOME': '/home/cltbld', 21:12:48 INFO - 'LANG': 'en_US.UTF-8', 21:12:48 INFO - 'LANGUAGE': 'en_US:en', 21:12:48 INFO - 'LOGNAME': 'cltbld', 21:12:48 INFO - 'MAIL': '/var/mail/cltbld', 21:12:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:12:48 INFO - 'MOZ_NO_REMOTE': '1', 21:12:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:12:48 INFO - 'NO_EM_RESTART': '1', 21:12:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:12:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:12:48 INFO - 'PWD': '/builds/slave/test', 21:12:48 INFO - 'SHELL': '/bin/bash', 21:12:48 INFO - 'SHLVL': '1', 21:12:48 INFO - 'TERM': 'linux', 21:12:48 INFO - 'TMOUT': '86400', 21:12:48 INFO - 'USER': 'cltbld', 21:12:48 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1467863947.493684-852873449', 21:12:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:12:48 INFO - '_': '/tools/buildbot/bin/python'} 21:12:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:12:49 INFO - Downloading/unpacking mozsystemmonitor==0.3 21:12:49 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 21:12:49 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 21:12:49 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 21:12:49 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 21:12:49 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 21:12:49 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 21:12:51 INFO - Downloading mozsystemmonitor-0.3.tar.gz 21:12:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:12:51 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 21:12:51 INFO - Installing collected packages: mozsystemmonitor 21:12:51 INFO - Running setup.py install for mozsystemmonitor 21:12:51 INFO - Successfully installed mozsystemmonitor 21:12:51 INFO - Cleaning up... 21:12:51 INFO - Return code: 0 21:12:51 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 21:12:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:12:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:12:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:12:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:12:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:12:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:12:51 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==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2020030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21838a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22e1180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22df3b0>, '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 0x22e02b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22e0740>, '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': 'c6927cf182745ad25b1d38b9000001fe-1467863947.493684-852873449', '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 21:12:51 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==2.5.1'] in /builds/slave/test/build 21:12:51 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==2.5.1 21:12:51 INFO - Using env: {'DISPLAY': ':0', 21:12:51 INFO - 'HOME': '/home/cltbld', 21:12:51 INFO - 'LANG': 'en_US.UTF-8', 21:12:51 INFO - 'LANGUAGE': 'en_US:en', 21:12:51 INFO - 'LOGNAME': 'cltbld', 21:12:51 INFO - 'MAIL': '/var/mail/cltbld', 21:12:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:12:51 INFO - 'MOZ_NO_REMOTE': '1', 21:12:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:12:51 INFO - 'NO_EM_RESTART': '1', 21:12:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:12:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:12:51 INFO - 'PWD': '/builds/slave/test', 21:12:51 INFO - 'SHELL': '/bin/bash', 21:12:51 INFO - 'SHLVL': '1', 21:12:51 INFO - 'TERM': 'linux', 21:12:51 INFO - 'TMOUT': '86400', 21:12:51 INFO - 'USER': 'cltbld', 21:12:51 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1467863947.493684-852873449', 21:12:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:12:51 INFO - '_': '/tools/buildbot/bin/python'} 21:12:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:12:51 INFO - Downloading/unpacking jsonschema==2.5.1 21:12:51 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 21:12:51 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 21:12:51 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 21:12:51 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 21:12:51 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 21:12:51 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 21:12:53 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:12:53 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 21:12: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 21:12: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 21:12: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 21:12: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 21:12: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 21:12: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 21:12:53 INFO - Downloading functools32-3.2.3-2.tar.gz 21:12:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 21:12:53 INFO - warning: no files found matching '*.txt' 21:12:53 INFO - no previously-included directories found matching 'build' 21:12:53 INFO - no previously-included directories found matching 'dist' 21:12:53 INFO - no previously-included directories found matching '.git*' 21:12:53 INFO - Installing collected packages: jsonschema, functools32 21:12:53 INFO - Running setup.py install for functools32 21:12:54 INFO - warning: no files found matching '*.txt' 21:12:54 INFO - no previously-included directories found matching 'build' 21:12:54 INFO - no previously-included directories found matching 'dist' 21:12:54 INFO - no previously-included directories found matching '.git*' 21:12:54 INFO - Successfully installed jsonschema functools32 21:12:54 INFO - Cleaning up... 21:12:54 INFO - Return code: 0 21:12:54 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 21:12:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:12:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:12:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:12:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:12:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:12:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:12:54 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 0x2020030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21838a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22e1180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22df3b0>, '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 0x22e02b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22e0740>, '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': 'c6927cf182745ad25b1d38b9000001fe-1467863947.493684-852873449', '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 21:12:54 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 21:12:54 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 21:12:54 INFO - Using env: {'DISPLAY': ':0', 21:12:54 INFO - 'HOME': '/home/cltbld', 21:12:54 INFO - 'LANG': 'en_US.UTF-8', 21:12:54 INFO - 'LANGUAGE': 'en_US:en', 21:12:54 INFO - 'LOGNAME': 'cltbld', 21:12:54 INFO - 'MAIL': '/var/mail/cltbld', 21:12:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:12:54 INFO - 'MOZ_NO_REMOTE': '1', 21:12:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:12:54 INFO - 'NO_EM_RESTART': '1', 21:12:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:12:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:12:54 INFO - 'PWD': '/builds/slave/test', 21:12:54 INFO - 'SHELL': '/bin/bash', 21:12:54 INFO - 'SHLVL': '1', 21:12:54 INFO - 'TERM': 'linux', 21:12:54 INFO - 'TMOUT': '86400', 21:12:54 INFO - 'USER': 'cltbld', 21:12:54 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1467863947.493684-852873449', 21:12:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:12:54 INFO - '_': '/tools/buildbot/bin/python'} 21:12:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:12:54 INFO - Downloading/unpacking blobuploader==1.2.4 21:12:54 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 21:12:54 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 21:12:54 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 21:12:54 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 21:12:54 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 21:12:54 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 21:12:56 INFO - Downloading blobuploader-1.2.4.tar.gz 21:12:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:12:56 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:12:56 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 21:12:56 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 21:12:56 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 21:12:56 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 21:12:56 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 21:12:56 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 21:12:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 21:12:56 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:12:56 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 21:12:56 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 21:12:56 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 21:12:56 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 21:12:56 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 21:12:56 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 21:12:56 INFO - Downloading docopt-0.6.1.tar.gz 21:12:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 21:12:57 INFO - Installing collected packages: blobuploader, requests, docopt 21:12:57 INFO - Running setup.py install for blobuploader 21:12:57 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:12:57 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 21:12:57 INFO - Running setup.py install for requests 21:12:57 INFO - Running setup.py install for docopt 21:12:57 INFO - Successfully installed blobuploader requests docopt 21:12:57 INFO - Cleaning up... 21:12:57 INFO - Return code: 0 21:12:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:12:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:12:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:12:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:12:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:12:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:12:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:12:57 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 0x2020030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21838a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22e1180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22df3b0>, '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 0x22e02b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22e0740>, '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': 'c6927cf182745ad25b1d38b9000001fe-1467863947.493684-852873449', '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 21:12:57 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 21:12:57 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 21:12:57 INFO - Using env: {'DISPLAY': ':0', 21:12:57 INFO - 'HOME': '/home/cltbld', 21:12:57 INFO - 'LANG': 'en_US.UTF-8', 21:12:57 INFO - 'LANGUAGE': 'en_US:en', 21:12:57 INFO - 'LOGNAME': 'cltbld', 21:12:57 INFO - 'MAIL': '/var/mail/cltbld', 21:12:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:12:57 INFO - 'MOZ_NO_REMOTE': '1', 21:12:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:12:57 INFO - 'NO_EM_RESTART': '1', 21:12:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:12:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:12:57 INFO - 'PWD': '/builds/slave/test', 21:12:57 INFO - 'SHELL': '/bin/bash', 21:12:57 INFO - 'SHLVL': '1', 21:12:57 INFO - 'TERM': 'linux', 21:12:57 INFO - 'TMOUT': '86400', 21:12:57 INFO - 'USER': 'cltbld', 21:12:57 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1467863947.493684-852873449', 21:12:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:12:57 INFO - '_': '/tools/buildbot/bin/python'} 21:12:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:12:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:12:57 INFO - Running setup.py (path:/tmp/pip-t3ciAk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:12:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:12:57 INFO - Running setup.py (path:/tmp/pip-1vx1YK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:12:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:12:57 INFO - Running setup.py (path:/tmp/pip-w6mZMj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:12:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:12:58 INFO - Running setup.py (path:/tmp/pip-Bgp9Yf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:12:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:12:58 INFO - Running setup.py (path:/tmp/pip-BCJ9V7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:12:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:12:58 INFO - Running setup.py (path:/tmp/pip-ispkLw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:12:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:12:58 INFO - Running setup.py (path:/tmp/pip-1e_pLJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:12:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:12:58 INFO - Running setup.py (path:/tmp/pip-JTTGQL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:12:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:12:58 INFO - Running setup.py (path:/tmp/pip-KcB8Eq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:12:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:12:58 INFO - Running setup.py (path:/tmp/pip-Y1YCUI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:12:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:12:58 INFO - Running setup.py (path:/tmp/pip-0Twowo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:12:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:12:58 INFO - Running setup.py (path:/tmp/pip-WmIHNd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:12:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:12:58 INFO - Running setup.py (path:/tmp/pip-MFMjWz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:12:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:12:58 INFO - Running setup.py (path:/tmp/pip-Q383Fd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:12:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:12:58 INFO - Running setup.py (path:/tmp/pip-4EyapC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:12:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:12:58 INFO - Running setup.py (path:/tmp/pip-392wIM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:12:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:12:59 INFO - Running setup.py (path:/tmp/pip-XcxODN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:12:59 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:12:59 INFO - Running setup.py install for manifestparser 21:12:59 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 21:12:59 INFO - Running setup.py install for mozcrash 21:12:59 INFO - Running setup.py install for mozdebug 21:12:59 INFO - Running setup.py install for mozdevice 21:12:59 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 21:12:59 INFO - Installing dm script to /builds/slave/test/build/venv/bin 21:12:59 INFO - Running setup.py install for mozfile 21:12:59 INFO - Running setup.py install for mozhttpd 21:12:59 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 21:12:59 INFO - Running setup.py install for mozinfo 21:12:59 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 21:12:59 INFO - Running setup.py install for mozInstall 21:12:59 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 21:12:59 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 21:12:59 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 21:12:59 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 21:12:59 INFO - Running setup.py install for mozleak 21:12:59 INFO - Running setup.py install for mozlog 21:13:00 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 21:13:00 INFO - Running setup.py install for moznetwork 21:13:00 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 21:13:00 INFO - Running setup.py install for mozprocess 21:13:00 INFO - Running setup.py install for mozprofile 21:13:00 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 21:13:00 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 21:13:00 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 21:13:00 INFO - Running setup.py install for mozrunner 21:13:00 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 21:13:00 INFO - Running setup.py install for mozscreenshot 21:13:00 INFO - Running setup.py install for moztest 21:13:00 INFO - Running setup.py install for mozversion 21:13:00 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 21:13:00 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:13:00 INFO - Cleaning up... 21:13:00 INFO - Return code: 0 21:13:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:13:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:13:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:13:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:13:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:13:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:13:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:13:00 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 0x2020030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21838a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22e1180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22df3b0>, '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 0x22e02b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22e0740>, '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': 'c6927cf182745ad25b1d38b9000001fe-1467863947.493684-852873449', '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 21:13:00 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 21:13:00 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 21:13:00 INFO - Using env: {'DISPLAY': ':0', 21:13:00 INFO - 'HOME': '/home/cltbld', 21:13:00 INFO - 'LANG': 'en_US.UTF-8', 21:13:00 INFO - 'LANGUAGE': 'en_US:en', 21:13:00 INFO - 'LOGNAME': 'cltbld', 21:13:00 INFO - 'MAIL': '/var/mail/cltbld', 21:13:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:13:00 INFO - 'MOZ_NO_REMOTE': '1', 21:13:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:13:00 INFO - 'NO_EM_RESTART': '1', 21:13:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:13:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:13:00 INFO - 'PWD': '/builds/slave/test', 21:13:00 INFO - 'SHELL': '/bin/bash', 21:13:00 INFO - 'SHLVL': '1', 21:13:00 INFO - 'TERM': 'linux', 21:13:00 INFO - 'TMOUT': '86400', 21:13:00 INFO - 'USER': 'cltbld', 21:13:00 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1467863947.493684-852873449', 21:13:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:13:00 INFO - '_': '/tools/buildbot/bin/python'} 21:13:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:13:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:13:01 INFO - Running setup.py (path:/tmp/pip-z_c6LE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:13:01 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)) 21:13:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:13:01 INFO - Running setup.py (path:/tmp/pip-D6mH3c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:13:01 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)) 21:13:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:13:01 INFO - Running setup.py (path:/tmp/pip-qYJnMr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:13:01 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)) 21:13:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:13:01 INFO - Running setup.py (path:/tmp/pip-kcOqHF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:13:01 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)) 21:13:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:13:01 INFO - Running setup.py (path:/tmp/pip-K5zs32-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:13:01 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)) 21:13:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:13:01 INFO - Running setup.py (path:/tmp/pip-3_m7pL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:13:01 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)) 21:13:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:13:01 INFO - Running setup.py (path:/tmp/pip-gzjGTn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:13:01 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)) 21:13:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:13:01 INFO - Running setup.py (path:/tmp/pip-OEwsUr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:13:01 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)) 21:13:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:13:01 INFO - Running setup.py (path:/tmp/pip-719_Vb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:13:01 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)) 21:13:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:13:01 INFO - Running setup.py (path:/tmp/pip-SdBQrS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:13:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:13:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:13:01 INFO - Running setup.py (path:/tmp/pip-rtvGsg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:13:01 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)) 21:13:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:13:01 INFO - Running setup.py (path:/tmp/pip-jUTfhs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:13:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 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)) 21:13:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:13:01 INFO - Running setup.py (path:/tmp/pip-2Ncbx9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:13:02 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)) 21:13:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:13:02 INFO - Running setup.py (path:/tmp/pip-WaqGAo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:13:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 21:13:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:13:02 INFO - Running setup.py (path:/tmp/pip-52HO74-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:13:02 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)) 21:13:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:13:02 INFO - Running setup.py (path:/tmp/pip-lMwELJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:13:02 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)) 21:13:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:13:02 INFO - Running setup.py (path:/tmp/pip-73DTlk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:13:02 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)) 21:13:02 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)) 21:13:02 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)) 21:13:02 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)) 21:13:02 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)) 21:13:02 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)) 21:13:02 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:13:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:13:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:13:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:13:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:13:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:13:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:13:04 INFO - Downloading blessings-1.6.tar.gz 21:13:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 21:13:04 INFO - Installing collected packages: blessings 21:13:04 INFO - Running setup.py install for blessings 21:13:04 INFO - Successfully installed blessings 21:13:04 INFO - Cleaning up... 21:13:04 INFO - Return code: 0 21:13:04 INFO - Done creating virtualenv /builds/slave/test/build/venv. 21:13:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:13:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:13:04 INFO - Reading from file tmpfile_stdout 21:13:04 INFO - Current package versions: 21:13:04 INFO - argparse == 1.2.1 21:13:04 INFO - blessings == 1.6 21:13:04 INFO - blobuploader == 1.2.4 21:13:04 INFO - docopt == 0.6.1 21:13:04 INFO - functools32 == 3.2.3-2 21:13:04 INFO - jsonschema == 2.5.1 21:13:04 INFO - manifestparser == 1.1 21:13:04 INFO - mozInstall == 1.12 21:13:04 INFO - mozcrash == 0.17 21:13:04 INFO - mozdebug == 0.1 21:13:04 INFO - mozdevice == 0.48 21:13:04 INFO - mozfile == 1.2 21:13:04 INFO - mozhttpd == 0.7 21:13:04 INFO - mozinfo == 0.9 21:13:04 INFO - mozleak == 0.1 21:13:04 INFO - mozlog == 3.2 21:13:04 INFO - moznetwork == 0.27 21:13:04 INFO - mozprocess == 0.23 21:13:04 INFO - mozprofile == 0.28 21:13:04 INFO - mozrunner == 6.12 21:13:04 INFO - mozscreenshot == 0.1 21:13:04 INFO - mozsystemmonitor == 0.3 21:13:04 INFO - moztest == 0.7 21:13:04 INFO - mozversion == 1.4 21:13:04 INFO - psutil == 3.1.1 21:13:04 INFO - requests == 1.2.3 21:13:04 INFO - wsgiref == 0.1.2 21:13:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:13:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:13:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:13:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:13:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:13:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:13:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:13:04 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 0x2020030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21838a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22e1180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22df3b0>, '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 0x22e02b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22e0740>, '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': 'c6927cf182745ad25b1d38b9000001fe-1467863947.493684-852873449', '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 21:13:04 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 21:13:04 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 21:13:04 INFO - Using env: {'DISPLAY': ':0', 21:13:04 INFO - 'HOME': '/home/cltbld', 21:13:04 INFO - 'LANG': 'en_US.UTF-8', 21:13:04 INFO - 'LANGUAGE': 'en_US:en', 21:13:04 INFO - 'LOGNAME': 'cltbld', 21:13:04 INFO - 'MAIL': '/var/mail/cltbld', 21:13:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:13:04 INFO - 'MOZ_NO_REMOTE': '1', 21:13:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:13:04 INFO - 'NO_EM_RESTART': '1', 21:13:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:13:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:13:04 INFO - 'PWD': '/builds/slave/test', 21:13:04 INFO - 'SHELL': '/bin/bash', 21:13:04 INFO - 'SHLVL': '1', 21:13:04 INFO - 'TERM': 'linux', 21:13:04 INFO - 'TMOUT': '86400', 21:13:04 INFO - 'USER': 'cltbld', 21:13:04 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1467863947.493684-852873449', 21:13:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:13:04 INFO - '_': '/tools/buildbot/bin/python'} 21:13:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:13:05 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)) 21:13:05 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)) 21:13:05 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)) 21:13:05 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)) 21:13:05 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)) 21:13:05 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)) 21:13:05 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)) 21:13:05 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)) 21:13:05 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)) 21:13:05 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)) 21:13:05 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)) 21:13:05 INFO - Cleaning up... 21:13:05 INFO - Return code: 0 21:13:05 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 21:13: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')]} 21:13:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:13:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:13: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')]} 21:13:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:13:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:13:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2020030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21838a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22e1180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22df3b0>, '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 0x22e02b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22e0740>, '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': 'c6927cf182745ad25b1d38b9000001fe-1467863947.493684-852873449', '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 21:13:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 21:13:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 21:13:05 INFO - Using env: {'DISPLAY': ':0', 21:13:05 INFO - 'HOME': '/home/cltbld', 21:13:05 INFO - 'LANG': 'en_US.UTF-8', 21:13:05 INFO - 'LANGUAGE': 'en_US:en', 21:13:05 INFO - 'LOGNAME': 'cltbld', 21:13:05 INFO - 'MAIL': '/var/mail/cltbld', 21:13:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:13:05 INFO - 'MOZ_NO_REMOTE': '1', 21:13:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:13:05 INFO - 'NO_EM_RESTART': '1', 21:13:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:13:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:13:05 INFO - 'PWD': '/builds/slave/test', 21:13:05 INFO - 'SHELL': '/bin/bash', 21:13:05 INFO - 'SHLVL': '1', 21:13:05 INFO - 'TERM': 'linux', 21:13:05 INFO - 'TMOUT': '86400', 21:13:05 INFO - 'USER': 'cltbld', 21:13:05 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1467863947.493684-852873449', 21:13:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:13:05 INFO - '_': '/tools/buildbot/bin/python'} 21:13:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:13:05 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 21:13:05 INFO - Cleaning up... 21:13:05 INFO - Return code: 0 21:13:05 INFO - Running post-action listener: _resource_record_post_action 21:13:05 INFO - Running post-action listener: _start_resource_monitoring 21:13:05 INFO - Starting resource monitoring. 21:13:05 INFO - ##### 21:13:05 INFO - ##### Finished create-virtualenv step (success) 21:13:05 INFO - ##### 21:13:05 INFO - ##### 21:13:05 INFO - ##### Running install step. 21:13:05 INFO - ##### 21:13:05 INFO - Running pre-action listener: _resource_record_pre_action 21:13:05 INFO - Running main action method: install 21:13:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:13:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:13:05 INFO - Reading from file tmpfile_stdout 21:13:05 INFO - Detecting whether we're running mozinstall >=1.0... 21:13:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 21:13:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 21:13:05 INFO - Reading from file tmpfile_stdout 21:13:05 INFO - Output received: 21:13:05 INFO - Usage: mozinstall [options] installer 21:13:05 INFO - Options: 21:13:05 INFO - -h, --help show this help message and exit 21:13:05 INFO - -d DEST, --destination=DEST 21:13:05 INFO - Directory to install application into. [default: 21:13:05 INFO - "/builds/slave/test"] 21:13:05 INFO - --app=APP Application being installed. [default: firefox] 21:13:05 INFO - mkdir: /builds/slave/test/build/application 21:13:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 21:13:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 21:13:21 INFO - Reading from file tmpfile_stdout 21:13:21 INFO - Output received: 21:13:21 INFO - /builds/slave/test/build/application/firefox/firefox 21:13:21 INFO - Running post-action listener: _resource_record_post_action 21:13:21 INFO - ##### 21:13:21 INFO - ##### Finished install step (success) 21:13:21 INFO - ##### 21:13:21 INFO - ##### 21:13:21 INFO - ##### Running run-tests step. 21:13:21 INFO - ##### 21:13:21 INFO - Running pre-action listener: _resource_record_pre_action 21:13:21 INFO - Running main action method: run_tests 21:13:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 21:13:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 21:13:21 INFO - Python 2.7.3 21:13:21 INFO - Return code: 0 21:13:21 INFO - grabbing minidump binary from tooltool 21:13:21 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')]} 21:13:21 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22df3b0>, '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 0x22e02b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22e0740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 21:13:21 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 21:13:21 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 21:13:21 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 21:13:22 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:13:22 INFO - Return code: 0 21:13:22 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 21:13:22 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 21:13:22 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 21:13:22 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 21:13:22 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 21:13:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Akp9pnM8Speh41N9z8cnWQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-004', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 21:13:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Akp9pnM8Speh41N9z8cnWQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-004 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 21:13:22 INFO - Using env: {'DISPLAY': ':0', 21:13:22 INFO - 'HOME': '/home/cltbld', 21:13:22 INFO - 'LANG': 'en_US.UTF-8', 21:13:22 INFO - 'LANGUAGE': 'en_US:en', 21:13:22 INFO - 'LOGNAME': 'cltbld', 21:13:22 INFO - 'MAIL': '/var/mail/cltbld', 21:13:22 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:13:22 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 21:13:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:13:22 INFO - 'MOZ_NO_REMOTE': '1', 21:13:22 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:13:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:13:22 INFO - 'NO_EM_RESTART': '1', 21:13:22 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:13:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:13:22 INFO - 'PWD': '/builds/slave/test', 21:13:22 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:13:22 INFO - 'SHELL': '/bin/bash', 21:13:22 INFO - 'SHLVL': '1', 21:13:22 INFO - 'TERM': 'linux', 21:13:22 INFO - 'TMOUT': '86400', 21:13:22 INFO - 'USER': 'cltbld', 21:13:22 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1467863947.493684-852873449', 21:13:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:13:22 INFO - '_': '/tools/buildbot/bin/python'} 21:13:22 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Akp9pnM8Speh41N9z8cnWQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-004', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 21:13:22 INFO - mozversion application_buildid: 20160706192822 21:13:22 INFO - mozversion application_changeset: 989bd85d2f8e49adb4d41be16795d27647609367 21:13:22 INFO - mozversion application_display_name: Nightly 21:13:22 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:13:22 INFO - mozversion application_name: Firefox 21:13:22 INFO - mozversion application_remotingname: firefox 21:13:22 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 21:13:22 INFO - mozversion application_vendor: Mozilla 21:13:22 INFO - mozversion application_version: 50.0a1 21:13:22 INFO - mozversion platform_buildid: 20160706192822 21:13:22 INFO - mozversion platform_changeset: 989bd85d2f8e49adb4d41be16795d27647609367 21:13:22 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 21:13:22 INFO - mozversion platform_version: 50.0a1 21:13:22 INFO - using testdate: 1467864802 21:13:22 INFO - actual date: 1467864802 21:13:22 INFO - starting webserver on 'localhost:36606' 21:13:22 INFO - SUITE-START | Running 2 tests 21:13:22 INFO - TEST-START | dromaeo_css 21:13:22 INFO - operating with platform_type : linux_ 21:13:22 INFO - Initialising browser for dromaeo_css test... 21:13:22 INFO - TEST-INFO | started process 3306 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpw6UwRC/profile http://localhost:36606/getInfo.html) 21:13:28 INFO - PROCESS | 3306 | __metrics Screen width/height:1600/1200 21:13:28 INFO - PROCESS | 3306 | colorDepth:24 21:13:28 INFO - PROCESS | 3306 | Browser inner width/height: 1024/697 21:13:28 INFO - PROCESS | 3306 | __metrics 21:13:29 INFO - PROCESS | 3306 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 21:13:30 INFO - TEST-INFO | 3306: exit 0 21:13:30 INFO - Browser initialized. 21:13:30 INFO - Running cycle 1/1 for dromaeo_css test... 21:13:30 INFO - TEST-INFO | started process 3389 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpw6UwRC/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:13:42 INFO - PROCESS | 3389 | RSS: Main: 158388224 21:13:42 INFO - PROCESS | 3389 | 21:14:33 INFO - PROCESS | 3389 | 1467864873393 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] 21:16:46 INFO - PROCESS | 3389 | Cycle 1(1): loaded http://localhost:36606/tests/dromaeo/cssquery-dojo.html (next: http://localhost:36606/tests/dromaeo/cssquery-ext.html) 21:16:47 INFO - PROCESS | 3389 | RSS: Main: 257777664 21:16:47 INFO - PROCESS | 3389 | 21:19:50 INFO - PROCESS | 3389 | Cycle 1(1): loaded http://localhost:36606/tests/dromaeo/cssquery-ext.html (next: http://localhost:36606/tests/dromaeo/cssquery-jquery.html) 21:19:51 INFO - PROCESS | 3389 | RSS: Main: 235552768 21:19:51 INFO - PROCESS | 3389 | 21:22:53 INFO - PROCESS | 3389 | Cycle 1(1): loaded http://localhost:36606/tests/dromaeo/cssquery-jquery.html (next: http://localhost:36606/tests/dromaeo/cssquery-mootools.html) 21:22:54 INFO - PROCESS | 3389 | RSS: Main: 235483136 21:22:54 INFO - PROCESS | 3389 | 21:25:56 INFO - PROCESS | 3389 | Cycle 1(1): loaded http://localhost:36606/tests/dromaeo/cssquery-mootools.html (next: http://localhost:36606/tests/dromaeo/cssquery-prototype.html) 21:25:57 INFO - PROCESS | 3389 | RSS: Main: 231075840 21:25:57 INFO - PROCESS | 3389 | 21:29:00 INFO - PROCESS | 3389 | Cycle 1(1): loaded http://localhost:36606/tests/dromaeo/cssquery-prototype.html (next: http://localhost:36606/tests/dromaeo/cssquery-yui.html) 21:29:00 INFO - PROCESS | 3389 | RSS: Main: 214892544 21:29:00 INFO - PROCESS | 3389 | 21:32:03 INFO - PROCESS | 3389 | Cycle 1(1): loaded http://localhost:36606/tests/dromaeo/cssquery-yui.html (next: http://localhost:36606/tests/dromaeo/cssquery-dojo.html) 21:32:03 INFO - PROCESS | 3389 | RSS: Main: 265838592 21:32:03 INFO - PROCESS | 3389 | 21:32:03 INFO - PROCESS | 3389 | __start_tp_report 21:32:03 INFO - PROCESS | 3389 | _x_x_mozilla_page_load 21:32:03 INFO - PROCESS | 3389 | _x_x_mozilla_page_load_details 21:32:03 INFO - PROCESS | 3389 | |i|pagename|runs| 21:32:03 INFO - PROCESS | 3389 | |0;dojo.html;2673.879675414606;2675;2717.877695503703;2721.414895797403;2725.277801382634;4620.376249206356;4691.718496890189;4727.1727447696685;4834.154023045966;4886.535779100988;4664.953350466494;4690.24956007039;4701.858944231679;4781.880452988686;4787.257975013869;4326.437563116806;4335.6965012449145;4407.647388208936;4450.488193857699;4557.361969324297;4494.213512635292;4511.142882852248;4518.728876267418;4541.613962813149;4569.131864945666;4310.461192350925;4358.389825424443;4359.215341238576;4382;4393.890152746175;4402.823887044515;4413.602775750198;4421.159979603865;4513.345564893072;4514.977425112854;4392.692511524195;4415.293553031532;4416.094700586372;4471.23988921884;4487.057717879216;4669.719816810968;4857;4901.65688401812;4905.117078925792;4925.901481970376;5085.974570127163;5108.157154069574;5129.102407078746;5197.038547868657;5207.765650545734;5163.173892177271;5181.4300426953005;5190.143626301656;5203.375594928632;5206.583473322125;4652.767361631905;4738.431388233434;4836.226203807374;4854.490278520775;4860.17377045896;5232.136697444955;5244.134717771525;5312.654677445954;5319.0957537218765;5323.600729945192;5023.572996295283;5039.992001599695;5067.062593420155;5087.974560127176;5093.821716239914;5031.371078615173;5080.695158290514;5156.793728250828;5158.561522270649;5165.896682066413;5094.6688465249645;5118.334616499831;5125.8718532037;5151.150060240094;5160.354955630546;5079.87300317495;5086.063848230487;5089.185730283137;5091.363579552556;5155.175171972542;4229.302165142779;4253.702240843111;4256.3828244904935;4326.351047342924;4381.890452738707;3779.130799916035;3800.657940785343;3804.0870191154477;3817.4464702617965;3857.4599556062176;4496.955030449653;4498.032922921588;4501.549845015472;4536.56902594253;4546.499885012646;5005.399352077774;5194.27280180775;5205.7917683293;5206.97396513015;5248.107821670249;4387.100644367897;4459.487158976694;4495.595396414273;4509.526499717483;4511.210538155875;4652.790624421909;4659.2312268476;4659.883502912387;4665.930011049837;4704.858854234379;4721.173794586002;4856.660033797601;4887.951120488749;4906.092372911023;4950.6782059166035;5111.412187598474;5148.279240906351;5172.922406163835;5213;5235.790568377222;4848.393950756155;4858.174110401311;4858.319835223142;4861.975690121527;4905.092557876804;5088.821891233789;5094.5669618082875;5094.821681241138;5102.209157580581;5147.330846990018;5020.949790502194;5096.617753668564;5125.79496820138;5156.871078223075;5167.405748338989;5002.124628190125;5012.523810238022;5014.548690617862;5024.070546948826;5063.493650634907;4895.632827537877;4988.201887697951;5000.074986127578;5001.174806156943;5008.69947803133;4917.0411769706;4961.82633607822;4976.676516026447;4980.576650984707;5043.596512279082;5081.949180508148;5082.542571168613;5090.2364645303505;5092.745362731923;5139.92290115656;4752.192127338291;4815.542523460265;4984.7258400788305;4995.550400463976;5001.4998500149695 21:32:03 INFO - PROCESS | 3389 | |1;ext.html;548.1942388065297;583.3612194646862;594.8602078511552;598.1775059293473;604.0124396611538;27562.724372756267;30319.393612127744;30369;41003.79498102505;42144.789276053656;41990.370144447785;42149;42232.36651450223;42421;42483.362749558706;7564.886526702104;7886.447948643583;8019.278264956153;8023.39824513161;8024.518528888256;7470.58911759853;7716.6913323467;7721.884171737428;10068.74828129299;10399.168066554696;10408.791824163513;10478.31890927091;10478.738031549234;10484.213683973694;10507.63223287185;9940.502974851228;9976.451295178762;9986.300958932914;9996.250281228864;10003.499825008721;7473.476856620039;10010.249231307682;10451.320664156805;10451.895481045169;10743.892561074446;39696.60303396958;40074.59925400738;40116.59883401187;40120.79939600283;40129.79935100335;3000.6849280825636;3069.1406406206297;3102.953455698166;3417.8803741868915;3726.1802403471465;29985;30031.399372012438;30131.849340753375;30398.088057358316;30633.387332253453;3559.48387483816;3593.8921832345072;3594.5506811648543;3635.1711253329136;3654.214343916071;250289;250843;251080;251407;251638;54638;54647.726761365935;54901.1764823528;54972;55011.72494137584;32702.672973270437;33652.63473652664;33677.32645347079;35271.64728352735;35471.645283547354;15300.617484562976;15552;15554.677852382449;15564.766528502081;15572.377104915677;10473.319234249902;11671.12466565011;11828.822567661491;11840.171188016757;11847.703807404883;18407.53981150482;18804;18955.241790328233;19565.21739130447;19575.412737617895;6828.317168283132;6930.237673855871;7064.43484521237;7099.503034787515;7105.959934056114;799.5442597719244;874.4534665833854;877.7103555826562;892.4912799704236;1005.9245556583286;28067;28563.714362856106;28702.282442939093;28798.712012880023;29191.416171676447;939;963.0850691842777;966.0918736387773;983.498415807943;986.4820968991337;14918.552443426715;15375.846241537665;15410.306536205897;15427;15447.845521544865;416168;420490;420658;420882;422155;49172.262416063786;49300.50699493031;49431.75284123556;49478.257826132634;49595.75202123966;1058.6718117383637;1107.8615173103524;1168.6201984354948;1192.701824543864;1208.2508844516456;26882;29608;30088.699113009465;30442.39115217639;30463.238419039702;42947.57052429561;43456.565434346514;43482;43502.12995740131;43518.347224792284;325740;325942;328307;328591;328699;21357.67963480518;25557.488850223264;25565.744342556336;25928.351791205372;27195.184144475697;42204.36693449662;42733;42771.57228427676;43454.78272608617;43775;35527;36055.81972090228;36526.817365913;36681.816590916875;36765.63234367622;36465.27069458649;36558.8172059138;36882;36912.44631330478;37141.62858371382;35162.47256291209;35755.927322180374;35943.28113437769;36281;36317.81841090778;31446.68553314529;33080.172995675304;33115.10073610097;33598.66401336053;33647.66352336543;25287.620685690097;25423.87288063622;25502.744972550037;25536.361590960376;25625 21:32:03 INFO - PROCESS | 3389 | |2;jquery.html;340.6542359505101;361.35858850540285;362.66997032700243;363.7272045965526;378.6989343471941;13091.541796037141;16327.26527306271;16717.08056056915;17432.564185895328;18509;17458.38895638653;17514.299428022896;17641.735373969368;17844;17987.4603761887;4975.303457515942;5205.791768329263;5284.867878303035;5301.469853014687;5303.787848486056;3723.6834869036165;5234.110201265794;5870.823875283749;6184.876302473948;6197.349278325779;5985.311689155759;6226.43962043416;6247.06294055893;6251.843703907393;6257.124002639612;6168.907466388007;6177.814665560018;6193.597416167935;6202.41077097675;6211.720472578745;5299.3905700844125;5374.059539580558;5431.402545719968;5433.646812957185;5437.34751829779;21724.782752172436;22108.2262120825;22162.33512994613;22175.44561385962;22229.11083556672;1880.746099276594;1928.631693880595;1930.2857942561202;2200.3288996855954;2234.4190510467233;13362.933185334012;13472.730545389135;13939.233342166177;14005.579832604937;14202.93477989155;1737.5656085978505;1739.4073278006733;1751.2294590380193;1781.7416474611239;1783.8662100342397;70190;70378.2962170382;70464.29535704629;71293.64353178201;71366;8303.916960830435;9110.908890911138;9681;9702.951485242529;10151.340162889388;13470.124441911245;13483;13547.187168769906;13553.322333883267;13564;7694;7771.844563108819;7797.337226335711;7862.52824830512;7956.443048986631;7608.771736847903;7651.808704782314;7836.333911617462;7845.37237021037;7883.6846526138315;6237.937620623827;6272.498200143978;6278.874422511524;6330.177076979963;6331.715072821734;1724.5336872885339;1757.0388144945796;1770.1680210301138;1792.829681180286;1817.736428217901;905.1355955062899;914.2685851319051;917.5779141594941;930.0327659234456;983.7196399026243;17033.574160646083;17416.65166696684;17865;18004.459866204033;18306.816931830777;823.5341092854728;825.4345773145415;879.4064006795387;880.6301353431575;904.2404380320562;9465.384749991366;9714.562844672007;9847.852282215772;9949.253805964438;9979;261458;386057;387538;389902;390530;75810.72567822975;86039.56980215058;86255;88252;89374;437.83143489756037;451.7876597998935;453.34944354850893;453.4332084893883;455.5126015163743;9631.181349585364;9964.402135871871;10062.245331600305;10407;10506.789864202827;22473.88763056174;37061.44407833939;37933.24133517369;38386.80806595949;38493;47172.7641361791;51108.23337649864;51149;51286.743566281926;51340;7217.314355136254;7549.547027178387;8023;8062.314703250289;8096.352291816758;11637.709057273636;12222.694432639255;12239.32683702405;12375.443105060114;12562.87437125617;10075.244356673133;10522.789544209227;10740.194485413469;10959.123270138529;10998.670039898816;10890.782184356427;10919.39943303126;10930.836037459603;10983.39591322485;11091.72270693239;10450.895491045296;11118.444077796239;11232.887671123512;11349.14881383883;11522.193446458667;7319.63401829917;7639.388848892187;7861.567613781297;8060.274575288252;8082.878756818534;10283.43441110746;10471;10704.571817127227;10727.624533141303;11105.444727763741 21:32:03 INFO - PROCESS | 3389 | |3;mootools.html;1337.0573745509416;1395.8115654386659;1451.3614009835671;1704.4119778676352;1708.5130737739746;7566.394688424926;8018;8059.999999999993;8067.717629882955;8074.434789564718;6941.1670599528;7910.208979102087;7957.960210198956;8163.4285600008025;8191.139930307307;2256.4020534558354;2286.4969706664506;2286.7827556382117;2286.965695514569;2290.438842483594;2179.40066481718;2238.149503188786;2238.149503188794;2239.0484044281197;2239.1826983151195;1895.2229585869795;2180.3023032629567;2241.540484200735;2256.8307376946714;2280.7377151627525;2200.2409168836725;2213.579419910228;2224.922127725522;2225.054351900444;2263.502029553512;2236.2732112063645;2238.4963383238805;2241.4172315198;2242.473018840571;2243.8204943604474;8119.269265766109;8260.710875119343;8262.586870656443;8263.462874913112;8264.380171487162;4614.3770590970125;4646.651501137429;4765.8570242892765;4771.904561908777;4775.331453596465;2816.605675205473;2861;2910.8690108945148;2913.2134323732685;2916.47503449379;882.7395918204145;897.6364572348209;898.7483504618717;903.5301643145527;911.6535716427782;10940.12479001678;10963.780724385548;10965.287256328393;10976.725581860517;10982;4093.0995181060716;4218.008767939532;4356.934645980313;4373.562643735601;4379.912401751947;9283.25733941283;9352.438853668802;9353.392029518196;9358.719238422858;9384.108509691569;4710.858674239778;4718.127146477913;4720.244760838249;4720.409948756405;4722.150012997693;4145.730527515701;4270.572942705705;4275.786210689453;4280.5291417944;4299.333603291495;3529.717622590238;3620.8008559528953;3651.123730304708;3678.7608805427562;3698.8705395311035;972.5040229483014;1062.389126252408;1081.4430568257353;1085.7122862441458;1087.494315143462;677.8576498935279;768.1742127213269;772.9033870766154;779.6257796257828;793.5000949401955;5047.16721740916;5286.920696189561;5306.787728490817;5446.019716450997;5556.77772889088;848.3425345357274;876.0582373948031;878.0638691684819;884.5930871798915;886.8492356299445;3607.2965771674794;3657.2685462907525;3687.4100143976834;3705.110773414361;3768.171002379472;9723.611055557698;10068.39589624625;10167.186625069977;10176.796464070678;10181.74545636365;9778.266630002778;10070.04334588213;10083.697489075339;10084.69745907624;10087;6519.348065193538;7157.642117894188;7465.104187497535;7477.140128885248;7515.8121046973165;5498.725063746877;5553.250311207935;5558;5678.5457163426;5727.11229759388;8968.05835387275;9203.907960920305;9214.493202873638;9402.764930876509;9425.905740942502;9196.770080748034;9504.524773761423;9510;9513.571889265138;9714.514274286399;6808.625525595941;6876.587404755731;6890.517663763689;6930.792076237721;6938.618375989369;7532.209118042527;7547.924520754942;7594.392448604209;7599.544027358376;7655.387568994578;6938.028675985467;7086.007958885863;7097.82255443618;7108.502404831612;7209.35115839577;6581.111549940888;6672.199336079508;6953.756618518328;6973.163220413583;7090.220075791555;6284.811455656338;6357.777477788255;6464.644444555406;6487.481001519962;6513.4463570595135;4550.522195169459;4639.86080417588;4771.59441447467;4818.8795280118275;4844.903101938012;4408.096340250305;4584.243599805995;4585.335126406586;4693.319468677091;4746.833860814855 21:32:03 INFO - PROCESS | 3389 | |4;prototype.html;288.41307938345466;292.37577771457944;294.6287677526317;301.8762307453944;307.4849626874985;4818;4896.681715688478;4964.418502378765;5026.773795179217;5028.849134525961;4782;4941.616347422719;4966.975165124179;4970.229614409764;4987.052460032592;3913.5303763548272;3923.784191869446;3924.8037598120122;3935.960640393595;3936.5079365079364;3704.46285288633;3771.2457508498273;3895.7078219133537;3902.4731661225796;3917.843286268546;3867.613238676138;3884.864029758959;3911.8044097795137;3914.9608503915024;3925.764454132747;3862.1310205203886;3862.4013277941963;3885.9028524286837;3889.922201555967;3893.9415908761393;3452.17034971463;3461.1866211440542;3468.9132771680656;3474.774139680913;3476.1483436558206;4616.422947131608;4816.638752093572;4838.588719958772;4843.612510999112;4851.986425633393;4365.759883206422;4373.322135069069;4373.518912919576;4383.101464199832;4397.164538737662;5160.432352441228;5162.251473536354;5181.196914478224;5199.818006369796;5234.241035049897;3548.982255088734;3550.5029295898594;3563.8396272167815;3612.024753316616;3614.746967712235;5328.706921119337;5359.4189714034455;5387.164989426645;5401.837944861661;5408.756605952742;3715.442683597482;3733.253349330145;3747.3816820224356;3760.511133552621;3772.830222639988;3724.7020238380865;3760.7743535387963;3772.867949621805;3803.82882770276;3813.980930095387;4305.461817272841;4325.8269669213505;4357.344209200483;4357.956420435755;4371.409859668967;4875.318015284721;5105.723569107723;5154.226865970135;5173.948260517422;5179.585633149339;1264.64589914824;1315.1845855569609;1320.8970509624571;1321.5044358365612;1324.7549203397402;815.4140197316282;831.4263158420671;844.0125053687201;845.3659755183612;848.0925409811442;4047.433359329696;4070.08423104805;4085.1208444115446;4099.519790526748;4106.938395924063;4644.60520855731;4657.371254880548;4666.85662012809;4706.388169537938;4711.8350857719815;4039.1921615676983;4080.8775736727353;4105.178964207171;4108.506979162519;4123.340265557496;2322.9186978455673;2488.9128880489097;2497.3631723910285;2501.7616279941544;2515.698116226028;4423.314386270134;4463.933041004388;4464.285714285764;4489.663275254369;4489.977550112294;4704.341392205094;4773.188557945157;4798.4001999750035;4823.903521929542;4856.732879691651;4482.932756008662;4492.910141797146;4681.695689780154;5101.005303965691;5128.9230661540105;5369.248305237348;5377;5389.164679474688;5394.649347792381;5401.837944861661;5291.232771248225;5296.179092240709;5297.17893726473;5301.814436494704;5303.321822479623;4954.355933728592;5062.367204099488;5089.262057001788;5100.336956195671;5102.260172274925;3712.8514859405323;3731.664150226384;3753.1367785410057;3765.981170094132;3766.8528938504787;5178.715170665499;5445.918311225256;5451.536619387396;5455.318085239345;5469.917951230815;4881.731504767272;5071.630659721928;5099.87250318745;5108.361454818148;5113.590912726899;5104.285400043923;5201.297824793604;5207.739613019409;5208.921866172086;5213.191955246943;5118.8976220476125;5141.897162056663;5160.690358578498;5166.560842328444;5208.3229180207845;5022.924656130234;5288.709120998382;5300.284461597789;5300.422215004934;5308.309919710413;5156.484351564814;5157.845264641916;5165.147750621256;5165.586753059822;5207 21:32:03 INFO - PROCESS | 3389 | |5;yui.html;589.8377946064829;599.9460048595625;607.3653032580952;680.251723104585;681.8840797064498;13980.091294065878;14569;14585.92707036466;14585.978981471288;14601.853981460183;11960.252035962434;13116.868831311684;13357.933210333958;13381.93309033456;13389.330533473336;770.4067867741821;795;795.1055063054064;797.7048492057946;798.2097723253966;784.0591290451453;787.2285160542672;787.5786454246979;787.7518581646791;788.1212448120333;821.7904434369227;822.5229366967145;823.086374124721;823.6705317872869;824.3116997307255;809.2878267124922;814.9062857771344;815.6248125862111;815.9918400816005;816.1267443835039;606.157441156788;606.6693651959692;606.7603296697823;607.325868286202;607.3683369295927;11206.551737930473;11213.327200367923;11223.102151827834;11245.381504017276;11250.831237531444;11646.650600481998;11687.532498700042;12058.155929084967;12526.185797923106;12851.550195743104;10207.438590877498;10362.222833287533;10748.408837513934;10773;10887.237893347468;3284.244623736554;3306.437905556061;3313.8177400242976;3362.4451965425674;3404.3531728971416;39841.60158398408;42267.57732422698;58724.11913821296;63073.36926630721;68150.3184968149;9939.751506212258;10330.431826249629;10418.270721049605;10506.369617822955;10623.787524249472;19160.904195479212;19303.324383646504;19364.515887102934;19382.321618743557;19384.515387115152;6857.759978400732;6910.7581234656545;6952.443804495628;6990.405815505637;7169.354758071799;3761.8495260189834;3832.329342365075;3872.8257228424786;3912.080661044652;3943.191645712622;2857.157138644105;2950.926226844303;2955.083923983529;2955.7930944833656;2964.3626620276746;484.7939625659081;572.7479908840097;574.307958909513;576.2681394628798;588;454.2488706038175;528.1470425263173;546.4016901492938;547.8822053258489;550.5485501888493;7920.5247685139075;7977.880331795028;8112.089990543006;8128.796780080545;8182.386321025947;456.36109446774117;529.9311089558332;551.1127085392515;557.1058451185869;561.6441760481174;3852.668074761881;4001.239764444746;4012.1071291443286;4029.738067025634;4130.834766609302;107947;110313;110411.34382984262;113597.4320128394;114510;92596.53701731448;96456;96516.51741741247;96549.5172524133;97144;83745;85011.57494212489;85891.57054214814;86416;86751;7395.963020184865;7424.146223184187;7548.094228692592;7551.660175292015;7765.262300081483;39715.40426893657;40258.18838152232;44554.55445445504;44630.3305450412;45759.77120114378;43432.782836085615;62096;66085.66957165183;66970.66514667396;69104.46197368755;11226.66320010401;11260.83108753353;11329.660110196708;11404.087672986307;11414.201005929504;12582.937085314516;12610.684732881384;12627.305498197687;12810.807837882252;13457;10887.510062047386;10928.235023548275;11279.266847655204;11968.521259149884;12369.690757731129;11690.590829320932;11832.585859494877;11885.346305953255;12062.095342849147;12133.878661213275;12133;12180.512779488721;12710.428030738824;12838.871611283768;12907;10040.14658754014;10055.49722513886;10197.388156710622;10369.585216591251;10411.1671066313;10546.472676365998;10657.25399222078;10763.569457221936;10777.353358798184;10801.189910756882 21:32:03 INFO - PROCESS | 3389 | __end_tp_report 21:32:03 INFO - PROCESS | 3389 | __start_cc_report 21:32:03 INFO - PROCESS | 3389 | _x_x_mozilla_cycle_collect,942 21:32:03 INFO - PROCESS | 3389 | __end_cc_report 21:32:03 INFO - PROCESS | 3389 | __startTimestamp1467865923660__endTimestamp 21:32:03 INFO - PROCESS | 3389 | 21:32:03 INFO - PROCESS | 3389 | ------- Summary: start ------- 21:32:03 INFO - PROCESS | 3389 | Number of tests: 6 21:32:03 INFO - PROCESS | 3389 | 21:32:03 INFO - PROCESS | 3389 | [#0] dojo.html Cycles:165 Average:4760.23 Median:4901.66 stddev:497.80 (10.2%) stddev-sans-first:471.66 21:32:03 INFO - PROCESS | 3389 | Values: 2673.9 2675.0 2717.9 2721.4 2725.3 4620.4 4691.7 4727.2 4834.2 4886.5 4665.0 4690.2 4701.9 4781.9 4787.3 4326.4 4335.7 4407.6 4450.5 4557.4 4494.2 4511.1 4518.7 4541.6 4569.1 4310.5 4358.4 4359.2 4382.0 4393.9 4402.8 4413.6 4421.2 4513.3 4515.0 4392.7 4415.3 4416.1 4471.2 4487.1 4669.7 4857.0 4901.7 4905.1 4925.9 5086.0 5108.2 5129.1 5197.0 5207.8 5163.2 5181.4 5190.1 5203.4 5206.6 4652.8 4738.4 4836.2 4854.5 4860.2 5232.1 5244.1 5312.7 5319.1 5323.6 5023.6 5040.0 5067.1 5088.0 5093.8 5031.4 5080.7 5156.8 5158.6 5165.9 5094.7 5118.3 5125.9 5151.2 5160.4 5079.9 5086.1 5089.2 5091.4 5155.2 4229.3 4253.7 4256.4 4326.4 4381.9 3779.1 3800.7 3804.1 3817.4 3857.5 4497.0 4498.0 4501.5 4536.6 4546.5 5005.4 5194.3 5205.8 5207.0 5248.1 4387.1 4459.5 4495.6 4509.5 4511.2 4652.8 4659.2 4659.9 4665.9 4704.9 4721.2 4856.7 4888.0 4906.1 4950.7 5111.4 5148.3 5172.9 5213.0 5235.8 4848.4 4858.2 4858.3 4862.0 4905.1 5088.8 5094.6 5094.8 5102.2 5147.3 5020.9 5096.6 5125.8 5156.9 5167.4 5002.1 5012.5 5014.5 5024.1 5063.5 4895.6 4988.2 5000.1 5001.2 5008.7 4917.0 4961.8 4976.7 4980.6 5043.6 5081.9 5082.5 5090.2 5092.7 5139.9 4752.2 4815.5 4984.7 4995.6 5001.5 21:32:03 INFO - PROCESS | 3389 | 21:32:03 INFO - PROCESS | 3389 | [#1] ext.html Cycles:180 Average:48516.79 Median:35499.32 stddev:89592.49 (252.4%) stddev-sans-first:89771.43 21:32:03 INFO - PROCESS | 3389 | Values: 548.2 583.4 594.9 598.2 604.0 27562.7 30319.4 30369.0 41003.8 42144.8 41990.4 42149.0 42232.4 42421.0 42483.4 7564.9 7886.4 8019.3 8023.4 8024.5 7470.6 7716.7 7721.9 10068.7 10399.2 10408.8 10478.3 10478.7 10484.2 10507.6 9940.5 9976.5 9986.3 9996.3 10003.5 7473.5 10010.2 10451.3 10451.9 10743.9 39696.6 40074.6 40116.6 40120.8 40129.8 3000.7 3069.1 3103.0 3417.9 3726.2 29985.0 30031.4 30131.8 30398.1 30633.4 3559.5 3593.9 3594.6 3635.2 3654.2 250289.0 250843.0 251080.0 251407.0 251638.0 54638.0 54647.7 54901.2 54972.0 55011.7 32702.7 33652.6 33677.3 35271.6 35471.6 15300.6 15552.0 15554.7 15564.8 15572.4 10473.3 11671.1 11828.8 11840.2 11847.7 18407.5 18804.0 18955.2 19565.2 19575.4 6828.3 6930.2 7064.4 7099.5 7106.0 799.5 874.5 877.7 892.5 1005.9 28067.0 28563.7 28702.3 28798.7 29191.4 939.0 963.1 966.1 983.5 986.5 14918.6 15375.8 15410.3 15427.0 15447.8 416168.0 420490.0 420658.0 420882.0 422155.0 49172.3 49300.5 49431.8 49478.3 49595.8 1058.7 1107.9 1168.6 1192.7 1208.3 26882.0 29608.0 30088.7 30442.4 30463.2 42947.6 43456.6 43482.0 43502.1 43518.3 325740.0 325942.0 328307.0 328591.0 328699.0 21357.7 25557.5 25565.7 25928.4 27195.2 42204.4 42733.0 42771.6 43454.8 43775.0 35527.0 36055.8 36526.8 36681.8 36765.6 36465.3 36558.8 36882.0 36912.4 37141.6 35162.5 35755.9 35943.3 36281.0 36317.8 31446.7 33080.2 33115.1 33598.7 33647.7 25287.6 25423.9 25502.7 25536.4 25625.0 21:32:03 INFO - PROCESS | 3389 | 21:32:03 INFO - PROCESS | 3389 | [#2] jquery.html Cycles:180 Average:24013.13 Median:444.81 stddev:60921.47 (13696.1%) stddev-sans-first:61066.44 21:32:03 INFO - PROCESS | 3389 | Values: 340.7 361.4 362.7 363.7 378.7 13091.5 16327.3 16717.1 17432.6 18509.0 17458.4 17514.3 17641.7 17844.0 17987.5 4975.3 5205.8 5284.9 5301.5 5303.8 3723.7 5234.1 5870.8 6184.9 6197.3 5985.3 6226.4 6247.1 6251.8 6257.1 6168.9 6177.8 6193.6 6202.4 6211.7 5299.4 5374.1 5431.4 5433.6 5437.3 21724.8 22108.2 22162.3 22175.4 22229.1 1880.7 1928.6 1930.3 2200.3 2234.4 13362.9 13472.7 13939.2 14005.6 14202.9 1737.6 1739.4 1751.2 1781.7 1783.9 70190.0 70378.3 70464.3 71293.6 71366.0 8303.9 9110.9 9681.0 9703.0 10151.3 13470.1 13483.0 13547.2 13553.3 13564.0 7694.0 7771.8 7797.3 7862.5 7956.4 7608.8 7651.8 7836.3 7845.4 7883.7 6237.9 6272.5 6278.9 6330.2 6331.7 1724.5 1757.0 1770.2 1792.8 1817.7 905.1 914.3 917.6 930.0 983.7 17033.6 17416.7 17865.0 18004.5 18306.8 823.5 825.4 879.4 880.6 904.2 9465.4 9714.6 9847.9 9949.3 9979.0 261458.0 386057.0 387538.0 389902.0 390530.0 75810.7 86039.6 86255.0 88252.0 89374.0 437.8 451.8 453.3 453.4 455.5 9631.2 9964.4 10062.2 10407.0 10506.8 22473.9 37061.4 37933.2 38386.8 38493.0 47172.8 51108.2 51149.0 51286.7 51340.0 7217.3 7549.5 8023.0 8062.3 8096.4 11637.7 12222.7 12239.3 12375.4 12562.9 10075.2 10522.8 10740.2 10959.1 10998.7 10890.8 10919.4 10930.8 10983.4 11091.7 10450.9 11118.4 11232.9 11349.1 11522.2 7319.6 7639.4 7861.6 8060.3 8082.9 10283.4 10471.0 10704.6 10727.6 11105.4 21:32:03 INFO - PROCESS | 3389 | 21:32:03 INFO - PROCESS | 3389 | [#3] mootools.html Cycles:180 Average:5275.23 Median:4831.89 stddev:2997.12 (62.0%) stddev-sans-first:2990.92 21:32:03 INFO - PROCESS | 3389 | Values: 1337.1 1395.8 1451.4 1704.4 1708.5 7566.4 8018.0 8060.0 8067.7 8074.4 6941.2 7910.2 7958.0 8163.4 8191.1 2256.4 2286.5 2286.8 2287.0 2290.4 2179.4 2238.1 2238.1 2239.0 2239.2 1895.2 2180.3 2241.5 2256.8 2280.7 2200.2 2213.6 2224.9 2225.1 2263.5 2236.3 2238.5 2241.4 2242.5 2243.8 8119.3 8260.7 8262.6 8263.5 8264.4 4614.4 4646.7 4765.9 4771.9 4775.3 2816.6 2861.0 2910.9 2913.2 2916.5 882.7 897.6 898.7 903.5 911.7 10940.1 10963.8 10965.3 10976.7 10982.0 4093.1 4218.0 4356.9 4373.6 4379.9 9283.3 9352.4 9353.4 9358.7 9384.1 4710.9 4718.1 4720.2 4720.4 4722.2 4145.7 4270.6 4275.8 4280.5 4299.3 3529.7 3620.8 3651.1 3678.8 3698.9 972.5 1062.4 1081.4 1085.7 1087.5 677.9 768.2 772.9 779.6 793.5 5047.2 5286.9 5306.8 5446.0 5556.8 848.3 876.1 878.1 884.6 886.8 3607.3 3657.3 3687.4 3705.1 3768.2 9723.6 10068.4 10167.2 10176.8 10181.7 9778.3 10070.0 10083.7 10084.7 10087.0 6519.3 7157.6 7465.1 7477.1 7515.8 5498.7 5553.3 5558.0 5678.5 5727.1 8968.1 9203.9 9214.5 9402.8 9425.9 9196.8 9504.5 9510.0 9513.6 9714.5 6808.6 6876.6 6890.5 6930.8 6938.6 7532.2 7547.9 7594.4 7599.5 7655.4 6938.0 7086.0 7097.8 7108.5 7209.4 6581.1 6672.2 6953.8 6973.2 7090.2 6284.8 6357.8 6464.6 6487.5 6513.4 4550.5 4639.9 4771.6 4818.9 4844.9 4408.1 4584.2 4585.3 4693.3 4746.8 21:32:03 INFO - PROCESS | 3389 | 21:32:03 INFO - PROCESS | 3389 | [#4] prototype.html Cycles:180 Average:4213.00 Median:4674.28 stddev:1240.82 (26.5%) stddev-sans-first:1208.83 21:32:03 INFO - PROCESS | 3389 | Values: 288.4 292.4 294.6 301.9 307.5 4818.0 4896.7 4964.4 5026.8 5028.8 4782.0 4941.6 4967.0 4970.2 4987.1 3913.5 3923.8 3924.8 3936.0 3936.5 3704.5 3771.2 3895.7 3902.5 3917.8 3867.6 3884.9 3911.8 3915.0 3925.8 3862.1 3862.4 3885.9 3889.9 3893.9 3452.2 3461.2 3468.9 3474.8 3476.1 4616.4 4816.6 4838.6 4843.6 4852.0 4365.8 4373.3 4373.5 4383.1 4397.2 5160.4 5162.3 5181.2 5199.8 5234.2 3549.0 3550.5 3563.8 3612.0 3614.7 5328.7 5359.4 5387.2 5401.8 5408.8 3715.4 3733.3 3747.4 3760.5 3772.8 3724.7 3760.8 3772.9 3803.8 3814.0 4305.5 4325.8 4357.3 4358.0 4371.4 4875.3 5105.7 5154.2 5173.9 5179.6 1264.6 1315.2 1320.9 1321.5 1324.8 815.4 831.4 844.0 845.4 848.1 4047.4 4070.1 4085.1 4099.5 4106.9 4644.6 4657.4 4666.9 4706.4 4711.8 4039.2 4080.9 4105.2 4108.5 4123.3 2322.9 2488.9 2497.4 2501.8 2515.7 4423.3 4463.9 4464.3 4489.7 4490.0 4704.3 4773.2 4798.4 4823.9 4856.7 4482.9 4492.9 4681.7 5101.0 5128.9 5369.2 5377.0 5389.2 5394.6 5401.8 5291.2 5296.2 5297.2 5301.8 5303.3 4954.4 5062.4 5089.3 5100.3 5102.3 3712.9 3731.7 3753.1 3766.0 3766.9 5178.7 5445.9 5451.5 5455.3 5469.9 4881.7 5071.6 5099.9 5108.4 5113.6 5104.3 5201.3 5207.7 5208.9 5213.2 5118.9 5141.9 5160.7 5166.6 5208.3 5022.9 5288.7 5300.3 5300.4 5308.3 5156.5 5157.8 5165.1 5165.6 5207.0 21:32:03 INFO - PROCESS | 3389 | 21:32:03 INFO - PROCESS | 3389 | [#5] yui.html Cycles:180 Average:18519.15 Median:39778.50 stddev:27868.85 (70.1%) stddev-sans-first:27914.51 21:32:03 INFO - PROCESS | 3389 | Values: 589.8 599.9 607.4 680.3 681.9 13980.1 14569.0 14585.9 14586.0 14601.9 11960.3 13116.9 13357.9 13381.9 13389.3 770.4 795.0 795.1 797.7 798.2 784.1 787.2 787.6 787.8 788.1 821.8 822.5 823.1 823.7 824.3 809.3 814.9 815.6 816.0 816.1 606.2 606.7 606.8 607.3 607.4 11206.6 11213.3 11223.1 11245.4 11250.8 11646.7 11687.5 12058.2 12526.2 12851.6 10207.4 10362.2 10748.4 10773.0 10887.2 3284.2 3306.4 3313.8 3362.4 3404.4 39841.6 42267.6 58724.1 63073.4 68150.3 9939.8 10330.4 10418.3 10506.4 10623.8 19160.9 19303.3 19364.5 19382.3 19384.5 6857.8 6910.8 6952.4 6990.4 7169.4 3761.8 3832.3 3872.8 3912.1 3943.2 2857.2 2950.9 2955.1 2955.8 2964.4 484.8 572.7 574.3 576.3 588.0 454.2 528.1 546.4 547.9 550.5 7920.5 7977.9 8112.1 8128.8 8182.4 456.4 529.9 551.1 557.1 561.6 3852.7 4001.2 4012.1 4029.7 4130.8 107947.0 110313.0 110411.3 113597.4 114510.0 92596.5 96456.0 96516.5 96549.5 97144.0 83745.0 85011.6 85891.6 86416.0 86751.0 7396.0 7424.1 7548.1 7551.7 7765.3 39715.4 40258.2 44554.6 44630.3 45759.8 43432.8 62096.0 66085.7 66970.7 69104.5 11226.7 11260.8 11329.7 11404.1 11414.2 12582.9 12610.7 12627.3 12810.8 13457.0 10887.5 10928.2 11279.3 11968.5 12369.7 11690.6 11832.6 11885.3 12062.1 12133.9 12133.0 12180.5 12710.4 12838.9 12907.0 10040.1 10055.5 10197.4 10369.6 10411.2 10546.5 10657.3 10763.6 10777.4 10801.2 21:32:03 INFO - PROCESS | 3389 | -------- Summary: end -------- 21:32:03 INFO - PROCESS | 3389 | 21:32:04 INFO - TEST-INFO | 3389: exit 0 21:32:04 INFO - TEST-OK | dromaeo_css | took 1121912ms 21:32:04 INFO - TEST-START | kraken 21:32:04 INFO - operating with platform_type : linux_ 21:32:04 INFO - Initialising browser for kraken test... 21:32:04 INFO - TEST-INFO | started process 3585 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp0iEkxV/profile http://localhost:36606/getInfo.html) 21:32:08 INFO - PROCESS | 3585 | __metrics Screen width/height:1600/1200 21:32:08 INFO - PROCESS | 3585 | colorDepth:24 21:32:08 INFO - PROCESS | 3585 | Browser inner width/height: 1024/697 21:32:08 INFO - PROCESS | 3585 | __metrics 21:32:09 INFO - PROCESS | 3585 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 21:32:10 INFO - TEST-INFO | 3585: exit 0 21:32:10 INFO - Browser initialized. 21:32:10 INFO - Running cycle 1/1 for kraken test... 21:32:10 INFO - TEST-INFO | started process 3667 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp0iEkxV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:32:22 INFO - PROCESS | 3667 | RSS: Main: 158863360 21:32:22 INFO - PROCESS | 3667 | 21:32:25 INFO - PROCESS | 3667 | Cycle 1(1): loaded http://localhost:36606/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:36606/tests/kraken/driver.html?testName=audio-beat-detection) 21:32:26 INFO - PROCESS | 3667 | RSS: Main: 216276992 21:32:26 INFO - PROCESS | 3667 | 21:32:28 INFO - PROCESS | 3667 | Cycle 1(1): loaded http://localhost:36606/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:36606/tests/kraken/driver.html?testName=audio-dft) 21:32:29 INFO - PROCESS | 3667 | RSS: Main: 204353536 21:32:29 INFO - PROCESS | 3667 | 21:32:32 INFO - PROCESS | 3667 | Cycle 1(1): loaded http://localhost:36606/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:36606/tests/kraken/driver.html?testName=audio-fft) 21:32:33 INFO - PROCESS | 3667 | RSS: Main: 271433728 21:32:33 INFO - PROCESS | 3667 | 21:32:35 INFO - PROCESS | 3667 | Cycle 1(1): loaded http://localhost:36606/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:36606/tests/kraken/driver.html?testName=audio-oscillator) 21:32:36 INFO - PROCESS | 3667 | RSS: Main: 181268480 21:32:36 INFO - PROCESS | 3667 | 21:32:38 INFO - PROCESS | 3667 | Cycle 1(1): loaded http://localhost:36606/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:36606/tests/kraken/driver.html?testName=imaging-gaussian-blur) 21:32:38 INFO - PROCESS | 3667 | RSS: Main: 186052608 21:32:38 INFO - PROCESS | 3667 | 21:32:42 INFO - PROCESS | 3667 | Cycle 1(1): loaded http://localhost:36606/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:36606/tests/kraken/driver.html?testName=imaging-darkroom) 21:32:42 INFO - PROCESS | 3667 | RSS: Main: 221769728 21:32:42 INFO - PROCESS | 3667 | 21:32:46 INFO - PROCESS | 3667 | Cycle 1(1): loaded http://localhost:36606/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:36606/tests/kraken/driver.html?testName=imaging-desaturate) 21:32:46 INFO - PROCESS | 3667 | RSS: Main: 222052352 21:32:46 INFO - PROCESS | 3667 | 21:32:49 INFO - PROCESS | 3667 | Cycle 1(1): loaded http://localhost:36606/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:36606/tests/kraken/driver.html?testName=json-parse-financial) 21:32:50 INFO - PROCESS | 3667 | RSS: Main: 222089216 21:32:50 INFO - PROCESS | 3667 | 21:32:51 INFO - PROCESS | 3667 | Cycle 1(1): loaded http://localhost:36606/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:36606/tests/kraken/driver.html?testName=json-stringify-tinderbox) 21:32:52 INFO - PROCESS | 3667 | RSS: Main: 210010112 21:32:52 INFO - PROCESS | 3667 | 21:32:54 INFO - PROCESS | 3667 | Cycle 1(1): loaded http://localhost:36606/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:36606/tests/kraken/driver.html?testName=stanford-crypto-aes) 21:32:55 INFO - PROCESS | 3667 | RSS: Main: 214413312 21:32:55 INFO - PROCESS | 3667 | 21:32:57 INFO - PROCESS | 3667 | Cycle 1(1): loaded http://localhost:36606/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:36606/tests/kraken/driver.html?testName=stanford-crypto-ccm) 21:32:57 INFO - PROCESS | 3667 | RSS: Main: 212545536 21:32:57 INFO - PROCESS | 3667 | 21:33:00 INFO - PROCESS | 3667 | Cycle 1(1): loaded http://localhost:36606/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:36606/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 21:33:01 INFO - PROCESS | 3667 | RSS: Main: 223969280 21:33:01 INFO - PROCESS | 3667 | 21:33:04 INFO - PROCESS | 3667 | Cycle 1(1): loaded http://localhost:36606/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:36606/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 21:33:04 INFO - PROCESS | 3667 | RSS: Main: 224899072 21:33:04 INFO - PROCESS | 3667 | 21:33:06 INFO - PROCESS | 3667 | Cycle 1(1): loaded http://localhost:36606/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:36606/tests/kraken/driver.html?testName=ai-astar) 21:33:06 INFO - PROCESS | 3667 | RSS: Main: 267579392 21:33:06 INFO - PROCESS | 3667 | 21:33:06 INFO - PROCESS | 3667 | __start_tp_report 21:33:06 INFO - PROCESS | 3667 | _x_x_mozilla_page_load 21:33:06 INFO - PROCESS | 3667 | _x_x_mozilla_page_load_details 21:33:06 INFO - PROCESS | 3667 | |i|pagename|runs| 21:33:06 INFO - PROCESS | 3667 | |0;ai-astar;103;114;115;114;113;113;111;113;112;113 21:33:06 INFO - PROCESS | 3667 | |1;audio-beat-detection;146;121;122;180;121;124;146;122;183;126 21:33:06 INFO - PROCESS | 3667 | |2;audio-dft;162;160;160;163;162;161;161;161;161;162 21:33:06 INFO - PROCESS | 3667 | |3;audio-fft;76;76;76;119;77;77;77;76;103;75 21:33:06 INFO - PROCESS | 3667 | |4;audio-oscillator;86;85;119;84;86;86;120;86;84;127 21:33:06 INFO - PROCESS | 3667 | |5;imaging-gaussian-blur;117;117;117;117;117;117;118;118;117;116 21:33:06 INFO - PROCESS | 3667 | |6;imaging-darkroom;146;148;147;147;146;146;146;147;146;146 21:33:06 INFO - PROCESS | 3667 | |7;imaging-desaturate;97;95;96;96;95;96;95;95;96;94 21:33:06 INFO - PROCESS | 3667 | |8;json-parse-financial;65;64;65;65;65;65;64;64;65;65 21:33:06 INFO - PROCESS | 3667 | |9;json-stringify-tinderbox;65;65;65;65;66;65;65;65;65;85 21:33:06 INFO - PROCESS | 3667 | |10;stanford-crypto-aes;87;81;84;78;77;77;77;77;80;81 21:33:06 INFO - PROCESS | 3667 | |11;stanford-crypto-ccm;127;129;135;128;125;142;137;144;168;128 21:33:06 INFO - PROCESS | 3667 | |12;stanford-crypto-pbkdf2;169;166;164;165;169;165;167;166;164;165 21:33:06 INFO - PROCESS | 3667 | |13;stanford-crypto-sha256-iterative;66;69;70;68;66;67;66;66;69;65 21:33:06 INFO - PROCESS | 3667 | __end_tp_report 21:33:06 INFO - PROCESS | 3667 | __start_cc_report 21:33:06 INFO - PROCESS | 3667 | _x_x_mozilla_cycle_collect,1924 21:33:06 INFO - PROCESS | 3667 | __end_cc_report 21:33:06 INFO - PROCESS | 3667 | __startTimestamp1467865986784__endTimestamp 21:33:06 INFO - PROCESS | 3667 | 21:33:06 INFO - PROCESS | 3667 | ------- Summary: start ------- 21:33:06 INFO - PROCESS | 3667 | Number of tests: 14 21:33:06 INFO - PROCESS | 3667 | 21:33:06 INFO - PROCESS | 3667 | [#0] ai-astar Cycles:10 Average:112.10 Median:113.00 stddev:3.38 (3.0%) stddev-sans-first:1.17 21:33:06 INFO - PROCESS | 3667 | Values: 103.0 114.0 115.0 114.0 113.0 113.0 111.0 113.0 112.0 113.0 21:33:06 INFO - PROCESS | 3667 | 21:33:06 INFO - PROCESS | 3667 | [#1] audio-beat-detection Cycles:10 Average:139.10 Median:136.00 stddev:24.35 (17.9%) stddev-sans-first:25.70 21:33:06 INFO - PROCESS | 3667 | Values: 146.0 121.0 122.0 180.0 121.0 124.0 146.0 122.0 183.0 126.0 21:33:06 INFO - PROCESS | 3667 | 21:33:06 INFO - PROCESS | 3667 | [#2] audio-dft Cycles:10 Average:161.30 Median:161.50 stddev:0.95 (0.6%) stddev-sans-first:0.97 21:33:06 INFO - PROCESS | 3667 | Values: 162.0 160.0 160.0 163.0 162.0 161.0 161.0 161.0 161.0 162.0 21:33:06 INFO - PROCESS | 3667 | 21:33:06 INFO - PROCESS | 3667 | [#3] audio-fft Cycles:10 Average:83.20 Median:76.00 stddev:15.14 (19.9%) stddev-sans-first:15.84 21:33:06 INFO - PROCESS | 3667 | Values: 76.0 76.0 76.0 119.0 77.0 77.0 77.0 76.0 103.0 75.0 21:33:06 INFO - PROCESS | 3667 | 21:33:06 INFO - PROCESS | 3667 | [#4] audio-oscillator Cycles:10 Average:96.30 Median:85.50 stddev:17.87 (20.9%) stddev-sans-first:18.56 21:33:06 INFO - PROCESS | 3667 | Values: 86.0 85.0 119.0 84.0 86.0 86.0 120.0 86.0 84.0 127.0 21:33:06 INFO - PROCESS | 3667 | 21:33:06 INFO - PROCESS | 3667 | [#5] imaging-gaussian-blur Cycles:10 Average:117.10 Median:117.00 stddev:0.57 (0.5%) stddev-sans-first:0.60 21:33:06 INFO - PROCESS | 3667 | Values: 117.0 117.0 117.0 117.0 117.0 117.0 118.0 118.0 117.0 116.0 21:33:06 INFO - PROCESS | 3667 | 21:33:06 INFO - PROCESS | 3667 | [#6] imaging-darkroom Cycles:10 Average:146.50 Median:146.50 stddev:0.71 (0.5%) stddev-sans-first:0.73 21:33:06 INFO - PROCESS | 3667 | Values: 146.0 148.0 147.0 147.0 146.0 146.0 146.0 147.0 146.0 146.0 21:33:06 INFO - PROCESS | 3667 | 21:33:06 INFO - PROCESS | 3667 | [#7] imaging-desaturate Cycles:10 Average:95.50 Median:96.00 stddev:0.85 (0.9%) stddev-sans-first:0.71 21:33:06 INFO - PROCESS | 3667 | Values: 97.0 95.0 96.0 96.0 95.0 96.0 95.0 95.0 96.0 94.0 21:33:06 INFO - PROCESS | 3667 | 21:33:06 INFO - PROCESS | 3667 | [#8] json-parse-financial Cycles:10 Average:64.70 Median:65.00 stddev:0.48 (0.7%) stddev-sans-first:0.50 21:33:06 INFO - PROCESS | 3667 | Values: 65.0 64.0 65.0 65.0 65.0 65.0 64.0 64.0 65.0 65.0 21:33:06 INFO - PROCESS | 3667 | 21:33:06 INFO - PROCESS | 3667 | [#9] json-stringify-tinderbox Cycles:10 Average:67.10 Median:65.00 stddev:6.30 (9.7%) stddev-sans-first:6.63 21:33:06 INFO - PROCESS | 3667 | Values: 65.0 65.0 65.0 65.0 66.0 65.0 65.0 65.0 65.0 85.0 21:33:06 INFO - PROCESS | 3667 | 21:33:06 INFO - PROCESS | 3667 | [#10] stanford-crypto-aes Cycles:10 Average:79.90 Median:80.50 stddev:3.45 (4.3%) stddev-sans-first:2.52 21:33:06 INFO - PROCESS | 3667 | Values: 87.0 81.0 84.0 78.0 77.0 77.0 77.0 77.0 80.0 81.0 21:33:06 INFO - PROCESS | 3667 | 21:33:06 INFO - PROCESS | 3667 | [#11] stanford-crypto-ccm Cycles:10 Average:136.30 Median:136.00 stddev:12.93 (9.5%) stddev-sans-first:13.27 21:33:06 INFO - PROCESS | 3667 | Values: 127.0 129.0 135.0 128.0 125.0 142.0 137.0 144.0 168.0 128.0 21:33:06 INFO - PROCESS | 3667 | 21:33:06 INFO - PROCESS | 3667 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.00 Median:166.00 stddev:1.83 (1.1%) stddev-sans-first:1.58 21:33:06 INFO - PROCESS | 3667 | Values: 169.0 166.0 164.0 165.0 169.0 165.0 167.0 166.0 164.0 165.0 21:33:06 INFO - PROCESS | 3667 | 21:33:06 INFO - PROCESS | 3667 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.20 Median:67.50 stddev:1.69 (2.5%) stddev-sans-first:1.73 21:33:06 INFO - PROCESS | 3667 | Values: 66.0 69.0 70.0 68.0 66.0 67.0 66.0 66.0 69.0 65.0 21:33:06 INFO - PROCESS | 3667 | -------- Summary: end -------- 21:33:06 INFO - PROCESS | 3667 | 21:33:07 INFO - TEST-INFO | 3667: exit 0 21:33:07 INFO - TEST-OK | kraken | took 63390ms 21:33:07 INFO - SUITE-END | took 1185s 21:33:07 INFO - Completed test suite (00:19:45) 21:33:07 INFO - javascript benchmark 21:33:08 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2673.879675414606, 2675.0, 2717.877695503703, 2721.414895797403, 2725.277801382634, 4620.376249206356, 4691.718496890189, 4727.1727447696685, 4834.154023045966, 4886.535779100988, 4664.953350466494, 4690.24956007039, 4701.858944231679, 4781.880452988686, 4787.257975013869, 4326.437563116806, 4335.6965012449145, 4407.647388208936, 4450.488193857699, 4557.361969324297, 4494.213512635292, 4511.142882852248, 4518.728876267418, 4541.613962813149, 4569.131864945666, 4310.461192350925, 4358.389825424443, 4359.215341238576, 4382.0, 4393.890152746175, 4402.823887044515, 4413.602775750198, 4421.159979603865, 4513.345564893072, 4514.977425112854, 4392.692511524195, 4415.293553031532, 4416.094700586372, 4471.23988921884, 4487.057717879216, 4669.719816810968, 4857.0, 4901.65688401812, 4905.117078925792, 4925.901481970376, 5085.974570127163, 5108.157154069574, 5129.102407078746, 5197.038547868657, 5207.765650545734, 5163.173892177271, 5181.4300426953005, 5190.143626301656, 5203.375594928632, 5206.583473322125, 4652.767361631905, 4738.431388233434, 4836.226203807374, 4854.490278520775, 4860.17377045896, 5232.136697444955, 5244.134717771525, 5312.654677445954, 5319.0957537218765, 5323.600729945192, 5023.572996295283, 5039.992001599695, 5067.062593420155, 5087.974560127176, 5093.821716239914, 5031.371078615173, 5080.695158290514, 5156.793728250828, 5158.561522270649, 5165.896682066413, 5094.6688465249645, 5118.334616499831, 5125.8718532037, 5151.150060240094, 5160.354955630546, 5079.87300317495, 5086.063848230487, 5089.185730283137, 5091.363579552556, 5155.175171972542, 4229.302165142779, 4253.702240843111, 4256.3828244904935, 4326.351047342924, 4381.890452738707, 3779.130799916035, 3800.657940785343, 3804.0870191154477, 3817.4464702617965, 3857.4599556062176, 4496.955030449653, 4498.032922921588, 4501.549845015472, 4536.56902594253, 4546.499885012646, 5005.399352077774, 5194.27280180775, 5205.7917683293, 5206.97396513015, 5248.107821670249, 4387.100644367897, 4459.487158976694, 4495.595396414273, 4509.526499717483, 4511.210538155875, 4652.790624421909, 4659.2312268476, 4659.883502912387, 4665.930011049837, 4704.858854234379, 4721.173794586002, 4856.660033797601, 4887.951120488749, 4906.092372911023, 4950.6782059166035, 5111.412187598474, 5148.279240906351, 5172.922406163835, 5213.0, 5235.790568377222, 4848.393950756155, 4858.174110401311, 4858.319835223142, 4861.975690121527, 4905.092557876804, 5088.821891233789, 5094.5669618082875, 5094.821681241138, 5102.209157580581, 5147.330846990018, 5020.949790502194, 5096.617753668564, 5125.79496820138, 5156.871078223075, 5167.405748338989, 5002.124628190125, 5012.523810238022, 5014.548690617862, 5024.070546948826, 5063.493650634907, 4895.632827537877, 4988.201887697951, 5000.074986127578, 5001.174806156943, 5008.69947803133, 4917.0411769706, 4961.82633607822, 4976.676516026447, 4980.576650984707, 5043.596512279082, 5081.949180508148, 5082.542571168613, 5090.2364645303505, 5092.745362731923, 5139.92290115656, 4752.192127338291, 4815.542523460265, 4984.7258400788305, 4995.550400463976, 5001.4998500149695], "value": 4728.651938621656, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [548.1942388065297, 583.3612194646862, 594.8602078511552, 598.1775059293473, 604.0124396611538, 27562.724372756267, 30319.393612127744, 30369.0, 41003.79498102505, 42144.789276053656, 41990.370144447785, 42149.0, 42232.36651450223, 42421.0, 42483.362749558706, 7564.886526702104, 7886.447948643583, 8019.278264956153, 8023.39824513161, 8024.518528888256, 7470.58911759853, 7716.6913323467, 7721.884171737428, 10068.74828129299, 10399.168066554696, 10408.791824163513, 10478.31890927091, 10478.738031549234, 10484.213683973694, 10507.63223287185, 9940.502974851228, 9976.451295178762, 9986.300958932914, 9996.250281228864, 10003.499825008721, 7473.476856620039, 10010.249231307682, 10451.320664156805, 10451.895481045169, 10743.892561074446, 39696.60303396958, 40074.59925400738, 40116.59883401187, 40120.79939600283, 40129.79935100335, 3000.6849280825636, 3069.1406406206297, 3102.953455698166, 3417.8803741868915, 3726.1802403471465, 29985.0, 30031.399372012438, 30131.849340753375, 30398.088057358316, 30633.387332253453, 3559.48387483816, 3593.8921832345072, 3594.5506811648543, 3635.1711253329136, 3654.214343916071, 250289.0, 250843.0, 251080.0, 251407.0, 251638.0, 54638.0, 54647.726761365935, 54901.1764823528, 54972.0, 55011.72494137584, 32702.672973270437, 33652.63473652664, 33677.32645347079, 35271.64728352735, 35471.645283547354, 15300.617484562976, 15552.0, 15554.677852382449, 15564.766528502081, 15572.377104915677, 10473.319234249902, 11671.12466565011, 11828.822567661491, 11840.171188016757, 11847.703807404883, 18407.53981150482, 18804.0, 18955.241790328233, 19565.21739130447, 19575.412737617895, 6828.317168283132, 6930.237673855871, 7064.43484521237, 7099.503034787515, 7105.959934056114, 799.5442597719244, 874.4534665833854, 877.7103555826562, 892.4912799704236, 1005.9245556583286, 28067.0, 28563.714362856106, 28702.282442939093, 28798.712012880023, 29191.416171676447, 939.0, 963.0850691842777, 966.0918736387773, 983.498415807943, 986.4820968991337, 14918.552443426715, 15375.846241537665, 15410.306536205897, 15427.0, 15447.845521544865, 416168.0, 420490.0, 420658.0, 420882.0, 422155.0, 49172.262416063786, 49300.50699493031, 49431.75284123556, 49478.257826132634, 49595.75202123966, 1058.6718117383637, 1107.8615173103524, 1168.6201984354948, 1192.701824543864, 1208.2508844516456, 26882.0, 29608.0, 30088.699113009465, 30442.39115217639, 30463.238419039702, 42947.57052429561, 43456.565434346514, 43482.0, 43502.12995740131, 43518.347224792284, 325740.0, 325942.0, 328307.0, 328591.0, 328699.0, 21357.67963480518, 25557.488850223264, 25565.744342556336, 25928.351791205372, 27195.184144475697, 42204.36693449662, 42733.0, 42771.57228427676, 43454.78272608617, 43775.0, 35527.0, 36055.81972090228, 36526.817365913, 36681.816590916875, 36765.63234367622, 36465.27069458649, 36558.8172059138, 36882.0, 36912.44631330478, 37141.62858371382, 35162.47256291209, 35755.927322180374, 35943.28113437769, 36281.0, 36317.81841090778, 31446.68553314529, 33080.172995675304, 33115.10073610097, 33598.66401336053, 33647.66352336543, 25287.620685690097, 25423.87288063622, 25502.744972550037, 25536.361590960376, 25625.0], "value": 18008.831036305095, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [340.6542359505101, 361.35858850540285, 362.66997032700243, 363.7272045965526, 378.6989343471941, 13091.541796037141, 16327.26527306271, 16717.08056056915, 17432.564185895328, 18509.0, 17458.38895638653, 17514.299428022896, 17641.735373969368, 17844.0, 17987.4603761887, 4975.303457515942, 5205.791768329263, 5284.867878303035, 5301.469853014687, 5303.787848486056, 3723.6834869036165, 5234.110201265794, 5870.823875283749, 6184.876302473948, 6197.349278325779, 5985.311689155759, 6226.43962043416, 6247.06294055893, 6251.843703907393, 6257.124002639612, 6168.907466388007, 6177.814665560018, 6193.597416167935, 6202.41077097675, 6211.720472578745, 5299.3905700844125, 5374.059539580558, 5431.402545719968, 5433.646812957185, 5437.34751829779, 21724.782752172436, 22108.2262120825, 22162.33512994613, 22175.44561385962, 22229.11083556672, 1880.746099276594, 1928.631693880595, 1930.2857942561202, 2200.3288996855954, 2234.4190510467233, 13362.933185334012, 13472.730545389135, 13939.233342166177, 14005.579832604937, 14202.93477989155, 1737.5656085978505, 1739.4073278006733, 1751.2294590380193, 1781.7416474611239, 1783.8662100342397, 70190.0, 70378.2962170382, 70464.29535704629, 71293.64353178201, 71366.0, 8303.916960830435, 9110.908890911138, 9681.0, 9702.951485242529, 10151.340162889388, 13470.124441911245, 13483.0, 13547.187168769906, 13553.322333883267, 13564.0, 7694.0, 7771.844563108819, 7797.337226335711, 7862.52824830512, 7956.443048986631, 7608.771736847903, 7651.808704782314, 7836.333911617462, 7845.37237021037, 7883.6846526138315, 6237.937620623827, 6272.498200143978, 6278.874422511524, 6330.177076979963, 6331.715072821734, 1724.5336872885339, 1757.0388144945796, 1770.1680210301138, 1792.829681180286, 1817.736428217901, 905.1355955062899, 914.2685851319051, 917.5779141594941, 930.0327659234456, 983.7196399026243, 17033.574160646083, 17416.65166696684, 17865.0, 18004.459866204033, 18306.816931830777, 823.5341092854728, 825.4345773145415, 879.4064006795387, 880.6301353431575, 904.2404380320562, 9465.384749991366, 9714.562844672007, 9847.852282215772, 9949.253805964438, 9979.0, 261458.0, 386057.0, 387538.0, 389902.0, 390530.0, 75810.72567822975, 86039.56980215058, 86255.0, 88252.0, 89374.0, 437.83143489756037, 451.7876597998935, 453.34944354850893, 453.4332084893883, 455.5126015163743, 9631.181349585364, 9964.402135871871, 10062.245331600305, 10407.0, 10506.789864202827, 22473.88763056174, 37061.44407833939, 37933.24133517369, 38386.80806595949, 38493.0, 47172.7641361791, 51108.23337649864, 51149.0, 51286.743566281926, 51340.0, 7217.314355136254, 7549.547027178387, 8023.0, 8062.314703250289, 8096.352291816758, 11637.709057273636, 12222.694432639255, 12239.32683702405, 12375.443105060114, 12562.87437125617, 10075.244356673133, 10522.789544209227, 10740.194485413469, 10959.123270138529, 10998.670039898816, 10890.782184356427, 10919.39943303126, 10930.836037459603, 10983.39591322485, 11091.72270693239, 10450.895491045296, 11118.444077796239, 11232.887671123512, 11349.14881383883, 11522.193446458667, 7319.63401829917, 7639.388848892187, 7861.567613781297, 8060.274575288252, 8082.878756818534, 10283.43441110746, 10471.0, 10704.571817127227, 10727.624533141303, 11105.444727763741], "value": 8340.818125857415, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1337.0573745509416, 1395.8115654386659, 1451.3614009835671, 1704.4119778676352, 1708.5130737739746, 7566.394688424926, 8018.0, 8059.999999999993, 8067.717629882955, 8074.434789564718, 6941.1670599528, 7910.208979102087, 7957.960210198956, 8163.4285600008025, 8191.139930307307, 2256.4020534558354, 2286.4969706664506, 2286.7827556382117, 2286.965695514569, 2290.438842483594, 2179.40066481718, 2238.149503188786, 2238.149503188794, 2239.0484044281197, 2239.1826983151195, 1895.2229585869795, 2180.3023032629567, 2241.540484200735, 2256.8307376946714, 2280.7377151627525, 2200.2409168836725, 2213.579419910228, 2224.922127725522, 2225.054351900444, 2263.502029553512, 2236.2732112063645, 2238.4963383238805, 2241.4172315198, 2242.473018840571, 2243.8204943604474, 8119.269265766109, 8260.710875119343, 8262.586870656443, 8263.462874913112, 8264.380171487162, 4614.3770590970125, 4646.651501137429, 4765.8570242892765, 4771.904561908777, 4775.331453596465, 2816.605675205473, 2861.0, 2910.8690108945148, 2913.2134323732685, 2916.47503449379, 882.7395918204145, 897.6364572348209, 898.7483504618717, 903.5301643145527, 911.6535716427782, 10940.12479001678, 10963.780724385548, 10965.287256328393, 10976.725581860517, 10982.0, 4093.0995181060716, 4218.008767939532, 4356.934645980313, 4373.562643735601, 4379.912401751947, 9283.25733941283, 9352.438853668802, 9353.392029518196, 9358.719238422858, 9384.108509691569, 4710.858674239778, 4718.127146477913, 4720.244760838249, 4720.409948756405, 4722.150012997693, 4145.730527515701, 4270.572942705705, 4275.786210689453, 4280.5291417944, 4299.333603291495, 3529.717622590238, 3620.8008559528953, 3651.123730304708, 3678.7608805427562, 3698.8705395311035, 972.5040229483014, 1062.389126252408, 1081.4430568257353, 1085.7122862441458, 1087.494315143462, 677.8576498935279, 768.1742127213269, 772.9033870766154, 779.6257796257828, 793.5000949401955, 5047.16721740916, 5286.920696189561, 5306.787728490817, 5446.019716450997, 5556.77772889088, 848.3425345357274, 876.0582373948031, 878.0638691684819, 884.5930871798915, 886.8492356299445, 3607.2965771674794, 3657.2685462907525, 3687.4100143976834, 3705.110773414361, 3768.171002379472, 9723.611055557698, 10068.39589624625, 10167.186625069977, 10176.796464070678, 10181.74545636365, 9778.266630002778, 10070.04334588213, 10083.697489075339, 10084.69745907624, 10087.0, 6519.348065193538, 7157.642117894188, 7465.104187497535, 7477.140128885248, 7515.8121046973165, 5498.725063746877, 5553.250311207935, 5558.0, 5678.5457163426, 5727.11229759388, 8968.05835387275, 9203.907960920305, 9214.493202873638, 9402.764930876509, 9425.905740942502, 9196.770080748034, 9504.524773761423, 9510.0, 9513.571889265138, 9714.514274286399, 6808.625525595941, 6876.587404755731, 6890.517663763689, 6930.792076237721, 6938.618375989369, 7532.209118042527, 7547.924520754942, 7594.392448604209, 7599.544027358376, 7655.387568994578, 6938.028675985467, 7086.007958885863, 7097.82255443618, 7108.502404831612, 7209.35115839577, 6581.111549940888, 6672.199336079508, 6953.756618518328, 6973.163220413583, 7090.220075791555, 6284.811455656338, 6357.777477788255, 6464.644444555406, 6487.481001519962, 6513.4463570595135, 4550.522195169459, 4639.86080417588, 4771.59441447467, 4818.8795280118275, 4844.903101938012, 4408.096340250305, 4584.243599805995, 4585.335126406586, 4693.319468677091, 4746.833860814855], "value": 4208.8063504999445, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [288.41307938345466, 292.37577771457944, 294.6287677526317, 301.8762307453944, 307.4849626874985, 4818.0, 4896.681715688478, 4964.418502378765, 5026.773795179217, 5028.849134525961, 4782.0, 4941.616347422719, 4966.975165124179, 4970.229614409764, 4987.052460032592, 3913.5303763548272, 3923.784191869446, 3924.8037598120122, 3935.960640393595, 3936.5079365079364, 3704.46285288633, 3771.2457508498273, 3895.7078219133537, 3902.4731661225796, 3917.843286268546, 3867.613238676138, 3884.864029758959, 3911.8044097795137, 3914.9608503915024, 3925.764454132747, 3862.1310205203886, 3862.4013277941963, 3885.9028524286837, 3889.922201555967, 3893.9415908761393, 3452.17034971463, 3461.1866211440542, 3468.9132771680656, 3474.774139680913, 3476.1483436558206, 4616.422947131608, 4816.638752093572, 4838.588719958772, 4843.612510999112, 4851.986425633393, 4365.759883206422, 4373.322135069069, 4373.518912919576, 4383.101464199832, 4397.164538737662, 5160.432352441228, 5162.251473536354, 5181.196914478224, 5199.818006369796, 5234.241035049897, 3548.982255088734, 3550.5029295898594, 3563.8396272167815, 3612.024753316616, 3614.746967712235, 5328.706921119337, 5359.4189714034455, 5387.164989426645, 5401.837944861661, 5408.756605952742, 3715.442683597482, 3733.253349330145, 3747.3816820224356, 3760.511133552621, 3772.830222639988, 3724.7020238380865, 3760.7743535387963, 3772.867949621805, 3803.82882770276, 3813.980930095387, 4305.461817272841, 4325.8269669213505, 4357.344209200483, 4357.956420435755, 4371.409859668967, 4875.318015284721, 5105.723569107723, 5154.226865970135, 5173.948260517422, 5179.585633149339, 1264.64589914824, 1315.1845855569609, 1320.8970509624571, 1321.5044358365612, 1324.7549203397402, 815.4140197316282, 831.4263158420671, 844.0125053687201, 845.3659755183612, 848.0925409811442, 4047.433359329696, 4070.08423104805, 4085.1208444115446, 4099.519790526748, 4106.938395924063, 4644.60520855731, 4657.371254880548, 4666.85662012809, 4706.388169537938, 4711.8350857719815, 4039.1921615676983, 4080.8775736727353, 4105.178964207171, 4108.506979162519, 4123.340265557496, 2322.9186978455673, 2488.9128880489097, 2497.3631723910285, 2501.7616279941544, 2515.698116226028, 4423.314386270134, 4463.933041004388, 4464.285714285764, 4489.663275254369, 4489.977550112294, 4704.341392205094, 4773.188557945157, 4798.4001999750035, 4823.903521929542, 4856.732879691651, 4482.932756008662, 4492.910141797146, 4681.695689780154, 5101.005303965691, 5128.9230661540105, 5369.248305237348, 5377.0, 5389.164679474688, 5394.649347792381, 5401.837944861661, 5291.232771248225, 5296.179092240709, 5297.17893726473, 5301.814436494704, 5303.321822479623, 4954.355933728592, 5062.367204099488, 5089.262057001788, 5100.336956195671, 5102.260172274925, 3712.8514859405323, 3731.664150226384, 3753.1367785410057, 3765.981170094132, 3766.8528938504787, 5178.715170665499, 5445.918311225256, 5451.536619387396, 5455.318085239345, 5469.917951230815, 4881.731504767272, 5071.630659721928, 5099.87250318745, 5108.361454818148, 5113.590912726899, 5104.285400043923, 5201.297824793604, 5207.739613019409, 5208.921866172086, 5213.191955246943, 5118.8976220476125, 5141.897162056663, 5160.690358578498, 5166.560842328444, 5208.3229180207845, 5022.924656130234, 5288.709120998382, 5300.284461597789, 5300.422215004934, 5308.309919710413, 5156.484351564814, 5157.845264641916, 5165.147750621256, 5165.586753059822, 5207.0], "value": 3816.5699200859863, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [589.8377946064829, 599.9460048595625, 607.3653032580952, 680.251723104585, 681.8840797064498, 13980.091294065878, 14569.0, 14585.92707036466, 14585.978981471288, 14601.853981460183, 11960.252035962434, 13116.868831311684, 13357.933210333958, 13381.93309033456, 13389.330533473336, 770.4067867741821, 795.0, 795.1055063054064, 797.7048492057946, 798.2097723253966, 784.0591290451453, 787.2285160542672, 787.5786454246979, 787.7518581646791, 788.1212448120333, 821.7904434369227, 822.5229366967145, 823.086374124721, 823.6705317872869, 824.3116997307255, 809.2878267124922, 814.9062857771344, 815.6248125862111, 815.9918400816005, 816.1267443835039, 606.157441156788, 606.6693651959692, 606.7603296697823, 607.325868286202, 607.3683369295927, 11206.551737930473, 11213.327200367923, 11223.102151827834, 11245.381504017276, 11250.831237531444, 11646.650600481998, 11687.532498700042, 12058.155929084967, 12526.185797923106, 12851.550195743104, 10207.438590877498, 10362.222833287533, 10748.408837513934, 10773.0, 10887.237893347468, 3284.244623736554, 3306.437905556061, 3313.8177400242976, 3362.4451965425674, 3404.3531728971416, 39841.60158398408, 42267.57732422698, 58724.11913821296, 63073.36926630721, 68150.3184968149, 9939.751506212258, 10330.431826249629, 10418.270721049605, 10506.369617822955, 10623.787524249472, 19160.904195479212, 19303.324383646504, 19364.515887102934, 19382.321618743557, 19384.515387115152, 6857.759978400732, 6910.7581234656545, 6952.443804495628, 6990.405815505637, 7169.354758071799, 3761.8495260189834, 3832.329342365075, 3872.8257228424786, 3912.080661044652, 3943.191645712622, 2857.157138644105, 2950.926226844303, 2955.083923983529, 2955.7930944833656, 2964.3626620276746, 484.7939625659081, 572.7479908840097, 574.307958909513, 576.2681394628798, 588.0, 454.2488706038175, 528.1470425263173, 546.4016901492938, 547.8822053258489, 550.5485501888493, 7920.5247685139075, 7977.880331795028, 8112.089990543006, 8128.796780080545, 8182.386321025947, 456.36109446774117, 529.9311089558332, 551.1127085392515, 557.1058451185869, 561.6441760481174, 3852.668074761881, 4001.239764444746, 4012.1071291443286, 4029.738067025634, 4130.834766609302, 107947.0, 110313.0, 110411.34382984262, 113597.4320128394, 114510.0, 92596.53701731448, 96456.0, 96516.51741741247, 96549.5172524133, 97144.0, 83745.0, 85011.57494212489, 85891.57054214814, 86416.0, 86751.0, 7395.963020184865, 7424.146223184187, 7548.094228692592, 7551.660175292015, 7765.262300081483, 39715.40426893657, 40258.18838152232, 44554.55445445504, 44630.3305450412, 45759.77120114378, 43432.782836085615, 62096.0, 66085.66957165183, 66970.66514667396, 69104.46197368755, 11226.66320010401, 11260.83108753353, 11329.660110196708, 11404.087672986307, 11414.201005929504, 12582.937085314516, 12610.684732881384, 12627.305498197687, 12810.807837882252, 13457.0, 10887.510062047386, 10928.235023548275, 11279.266847655204, 11968.521259149884, 12369.690757731129, 11690.590829320932, 11832.585859494877, 11885.346305953255, 12062.095342849147, 12133.878661213275, 12133.0, 12180.512779488721, 12710.428030738824, 12838.871611283768, 12907.0, 10040.14658754014, 10055.49722513886, 10197.388156710622, 10369.585216591251, 10411.1671066313, 10546.472676365998, 10657.25399222078, 10763.569457221936, 10777.353358798184, 10801.189910756882], "value": 6599.607664499309, "unit": "score"}], "name": "dromaeo_css", "value": 6498.38248729034, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 114.0, 115.0, 114.0, 113.0, 113.0, 111.0, 113.0, 112.0, 113.0], "value": 112.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 121.0, 122.0, 180.0, 121.0, 124.0, 146.0, 122.0, 183.0, 126.0], "value": 139.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 160.0, 160.0, 163.0, 162.0, 161.0, 161.0, 161.0, 161.0, 162.0], "value": 161.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 76.0, 119.0, 77.0, 77.0, 77.0, 76.0, 103.0, 75.0], "value": 83.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 119.0, 84.0, 86.0, 86.0, 120.0, 86.0, 84.0, 127.0], "value": 96.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 118.0, 118.0, 117.0, 116.0], "value": 117.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 148.0, 147.0, 147.0, 146.0, 146.0, 146.0, 147.0, 146.0, 146.0], "value": 146.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 95.0, 96.0, 96.0, 95.0, 96.0, 95.0, 95.0, 96.0, 94.0], "value": 95.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 64.0, 65.0, 65.0, 65.0, 65.0, 64.0, 64.0, 65.0, 65.0], "value": 64.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 65.0, 65.0, 66.0, 65.0, 65.0, 65.0, 65.0, 85.0], "value": 67.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 81.0, 84.0, 78.0, 77.0, 77.0, 77.0, 77.0, 80.0, 81.0], "value": 79.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 129.0, 135.0, 128.0, 125.0, 142.0, 137.0, 144.0, 168.0, 128.0], "value": 136.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 166.0, 164.0, 165.0, 169.0, 165.0, 167.0, 166.0, 164.0, 165.0], "value": 166.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 69.0, 70.0, 68.0, 66.0, 67.0, 66.0, 66.0, 69.0, 65.0], "value": 67.2, "unit": "score"}], "name": "kraken", "value": 1532.3000000000002, "alertThreshold": 2.0}]} 21:33:08 INFO - Return code: 0 21:33:08 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 21:33:08 INFO - # TBPL SUCCESS # 21:33:08 INFO - Running post-action listener: _resource_record_post_action 21:33:08 INFO - ##### 21:33:08 INFO - ##### Finished run-tests step (success) 21:33:08 INFO - ##### 21:33:08 INFO - Running post-run listener: _resource_record_post_run 21:33:08 INFO - Total resource usage - Wall time: 1202s; CPU: 13.0%; Read bytes: 7671808; Write bytes: 371838976; Read time: 1216; Write time: 376384 21:33:08 INFO - TinderboxPrint: CPU usage
13.0% 21:33:08 INFO - TinderboxPrint: I/O read bytes / time
7,671,808 / 1,216 21:33:08 INFO - TinderboxPrint: I/O write bytes / time
371,838,976 / 376,384 21:33:08 INFO - TinderboxPrint: CPU idle
8,346.6 (87.0%) 21:33:08 INFO - TinderboxPrint: CPU user
1,204.5 (12.6%) 21:33:08 INFO - TinderboxPrint: Swap in / out
0 / 0 21:33:08 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 13340672; Read time: 0; Write time: 7560 21:33:08 INFO - run-tests - Wall time: 1186s; CPU: 13.0%; Read bytes: 5648384; Write bytes: 308039680; Read time: 920; Write time: 342452 21:33:08 INFO - Running post-run listener: _upload_blobber_files 21:33:08 INFO - Blob upload gear active. 21:33:08 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 21:33:08 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 21:33:08 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', 'Autoland-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 21:33:08 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 Autoland-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 21:33:09 INFO - (blobuploader) - INFO - Open directory for files ... 21:33:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 21:33:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:33:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:33:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 21:33:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:33:09 INFO - (blobuploader) - INFO - Done attempting. 21:33:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 21:33:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:33:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:33:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 21:33:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:33:10 INFO - (blobuploader) - INFO - Done attempting. 21:33:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 21:33:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:33:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:33:14 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 21:33:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:33:14 INFO - (blobuploader) - INFO - Done attempting. 21:33:14 INFO - (blobuploader) - INFO - Iteration through files over. 21:33:14 INFO - Return code: 0 21:33:14 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 21:33:14 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 21:33:14 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/4855cd8d9eac69edd475d6abf5d599230b0bda9f8bc6f44aa7a51a55c37ec085e0e745f0ab60405050914686aa0a7383c3ad4380221ede87939109c48d670af6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/e9bee259e888b0b4f5ef94192b5099a8e234ae838d7a66e6c8397cef412eab640578b6611c4f524f35a9fa762469914d923139e5f4f7ec3b40899bc1cd666ba8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/49f298b3520148efe0f1744ca6926216e1b70feae455a210aff2eb55cd9cc7126cba56d5fa0cf9589a7db3dcf05afb08c29d24f69a50eaec92cbfdd33c4ac11b"} 21:33:14 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 21:33:14 INFO - Writing to file /builds/slave/test/properties/blobber_files 21:33:14 INFO - Contents: 21:33:14 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/4855cd8d9eac69edd475d6abf5d599230b0bda9f8bc6f44aa7a51a55c37ec085e0e745f0ab60405050914686aa0a7383c3ad4380221ede87939109c48d670af6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/e9bee259e888b0b4f5ef94192b5099a8e234ae838d7a66e6c8397cef412eab640578b6611c4f524f35a9fa762469914d923139e5f4f7ec3b40899bc1cd666ba8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/49f298b3520148efe0f1744ca6926216e1b70feae455a210aff2eb55cd9cc7126cba56d5fa0cf9589a7db3dcf05afb08c29d24f69a50eaec92cbfdd33c4ac11b"} 21:33:14 INFO - Running post-run listener: copy_logs_to_upload_dir 21:33:14 INFO - Copying logs to upload dir... 21:33:14 INFO - mkdir: /builds/slave/test/build/upload/logs 21:33:14 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1244.452228 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 44 secs) (at 2016-07-06 21:33:14.215477) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-06 21:33:14.218703) ========= 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=c6927cf182745ad25b1d38b9000001fe-1467863947.493684-852873449 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/4855cd8d9eac69edd475d6abf5d599230b0bda9f8bc6f44aa7a51a55c37ec085e0e745f0ab60405050914686aa0a7383c3ad4380221ede87939109c48d670af6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/e9bee259e888b0b4f5ef94192b5099a8e234ae838d7a66e6c8397cef412eab640578b6611c4f524f35a9fa762469914d923139e5f4f7ec3b40899bc1cd666ba8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/49f298b3520148efe0f1744ca6926216e1b70feae455a210aff2eb55cd9cc7126cba56d5fa0cf9589a7db3dcf05afb08c29d24f69a50eaec92cbfdd33c4ac11b"} build_url:https://queue.taskcluster.net/v1/task/Akp9pnM8Speh41N9z8cnWQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011050 build_url: 'https://queue.taskcluster.net/v1/task/Akp9pnM8Speh41N9z8cnWQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/4855cd8d9eac69edd475d6abf5d599230b0bda9f8bc6f44aa7a51a55c37ec085e0e745f0ab60405050914686aa0a7383c3ad4380221ede87939109c48d670af6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/e9bee259e888b0b4f5ef94192b5099a8e234ae838d7a66e6c8397cef412eab640578b6611c4f524f35a9fa762469914d923139e5f4f7ec3b40899bc1cd666ba8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/49f298b3520148efe0f1744ca6926216e1b70feae455a210aff2eb55cd9cc7126cba56d5fa0cf9589a7db3dcf05afb08c29d24f69a50eaec92cbfdd33c4ac11b"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-06 21:33:14.249818) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-06 21:33:14.250584) ========= 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=c6927cf182745ad25b1d38b9000001fe-1467863947.493684-852873449 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004440 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-06 21:33:14.332020) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-06 21:33:14.332547) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-06 21:33:14.332847) ========= ========= Total master_lag: 0.21 =========