builder: autoland_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-010 starttime: 1467943743.17 results: success (0) buildid: 20160707173726 builduid: 82ec1beadc434685951532b440a4561f revision: 06e1c91a55c5e4bb8f5b0890c3fe850cbf2d4f31 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-07 19:09:03.172385) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-07 19:09:03.172813) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-07 19:09:03.173087) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-07 19:09:03.336049) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-07 19:09:03.336381) ========= 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=eca2ab57546de7b41f256b19000001ef-1467943739.668630-1411652204 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-07 19:09:03-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 225M=0s 2016-07-07 19:09:03 (225 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.304581 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-07 19:09:03.655962) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-07 19:09:03.656289) ========= 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=eca2ab57546de7b41f256b19000001ef-1467943739.668630-1411652204 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.013788 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-07 19:09:03.706132) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-07 19:09:03.706483) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 06e1c91a55c5e4bb8f5b0890c3fe850cbf2d4f31 --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 06e1c91a55c5e4bb8f5b0890c3fe850cbf2d4f31 --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=eca2ab57546de7b41f256b19000001ef-1467943739.668630-1411652204 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-07 19:09:03,976 truncating revision to first 12 chars 2016-07-07 19:09:03,977 Setting DEBUG logging. 2016-07-07 19:09:03,977 attempt 1/10 2016-07-07 19:09:03,977 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/06e1c91a55c5?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-07 19:09:05,343 unpacking tar archive at: autoland-06e1c91a55c5/testing/mozharness/ program finished with exit code 0 elapsedTime=1.692134 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-07 19:09:05.411973) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-07 19:09:05.412307) ========= script_repo_revision: 06e1c91a55c5e4bb8f5b0890c3fe850cbf2d4f31 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-07 19:09:05.412725) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-07 19:09:05.413000) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-07 19:09:05.425332) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 44 secs) (at 2016-07-07 19:09:05.425611) ========= /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=eca2ab57546de7b41f256b19000001ef-1467943739.668630-1411652204 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 19:09:05 INFO - MultiFileLogger online at 20160707 19:09:05 in /builds/slave/test 19:09:05 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 19:09:05 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:09:05 INFO - {'append_to_log': False, 19:09:05 INFO - 'base_work_dir': '/builds/slave/test', 19:09:05 INFO - 'blob_upload_branch': 'Autoland-Non-PGO', 19:09:05 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:09:05 INFO - 'branch': 'Autoland-Non-PGO', 19:09:05 INFO - 'buildbot_json_path': 'buildprops.json', 19:09:05 INFO - 'config_files': ('talos/linux_config.py',), 19:09:05 INFO - 'default_actions': ('clobber', 19:09:05 INFO - 'read-buildbot-config', 19:09:05 INFO - 'download-and-extract', 19:09:05 INFO - 'populate-webroot', 19:09:05 INFO - 'create-virtualenv', 19:09:05 INFO - 'install', 19:09:05 INFO - 'run-tests'), 19:09:05 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:09:05 INFO - 'download_minidump_stackwalk': True, 19:09:05 INFO - 'download_symbols': 'ondemand', 19:09:05 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:09:05 INFO - 'tooltool.py': '/tools/tooltool.py', 19:09:05 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:09:05 INFO - '/tools/misc-python/virtualenv.py')}, 19:09:05 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:09:05 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:09:05 INFO - 'installer_path': 'installer.exe', 19:09:05 INFO - 'log_level': 'info', 19:09:05 INFO - 'log_name': 'talos', 19:09:05 INFO - 'log_to_console': True, 19:09:05 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 19:09:05 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 19:09:05 INFO - 'opt_config_files': (), 19:09:05 INFO - 'pip_index': False, 19:09:05 INFO - 'sps_profile': False, 19:09:05 INFO - 'sps_profile_interval': 0, 19:09:05 INFO - 'suite': 'dromaeojs', 19:09:05 INFO - 'system_bits': '32', 19:09:05 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:09:05 INFO - 'title': 'talos-linux64-ix-010', 19:09:05 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:09:05 INFO - 'use_talos_json': True, 19:09:05 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:09:05 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:09:05 INFO - 'work_dir': 'build'} 19:09:05 INFO - ##### 19:09:05 INFO - ##### Running clobber step. 19:09:05 INFO - ##### 19:09:05 INFO - Running pre-action listener: _resource_record_pre_action 19:09:05 INFO - Running main action method: clobber 19:09:05 INFO - rmtree: /builds/slave/test/build 19:09:05 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:09:06 INFO - Running post-action listener: _resource_record_post_action 19:09:06 INFO - ##### 19:09:06 INFO - ##### Finished clobber step (success) 19:09:06 INFO - ##### 19:09:06 INFO - ##### 19:09:06 INFO - ##### Running read-buildbot-config step. 19:09:06 INFO - ##### 19:09:06 INFO - Running pre-action listener: _resource_record_pre_action 19:09:06 INFO - Running main action method: read_buildbot_config 19:09:06 INFO - Using buildbot properties: 19:09:06 INFO - { 19:09:06 INFO - "project": "", 19:09:06 INFO - "product": "firefox", 19:09:06 INFO - "script_repo_revision": "production", 19:09:06 INFO - "builddir": "autoland_ubuntu64_hw_test-dromaeojs", 19:09:06 INFO - "repository": "", 19:09:06 INFO - "buildername": "Ubuntu HW 12.04 x64 autoland talos dromaeojs", 19:09:06 INFO - "buildid": "20160707173726", 19:09:06 INFO - "pgo_build": "False", 19:09:06 INFO - "basedir": "/builds/slave/test", 19:09:06 INFO - "buildnumber": 83, 19:09:06 INFO - "slavename": "talos-linux64-ix-010", 19:09:06 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 19:09:06 INFO - "platform": "ubuntu64_hw", 19:09:06 INFO - "scheduler": "tests-autoland-linux64-talos", 19:09:06 INFO - "branch": "autoland", 19:09:06 INFO - "repo_path": "integration/autoland", 19:09:06 INFO - "revision": "06e1c91a55c5e4bb8f5b0890c3fe850cbf2d4f31", 19:09:06 INFO - "stage_platform": "linux64", 19:09:06 INFO - "builduid": "82ec1beadc434685951532b440a4561f", 19:09:06 INFO - "slavebuilddir": "test" 19:09:06 INFO - } 19:09:06 INFO - Found installer url https://queue.taskcluster.net/v1/task/KkQu1Lb5Slm7Nanvt0EsHQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 19:09:06 INFO - Running post-action listener: _resource_record_post_action 19:09:06 INFO - ##### 19:09:06 INFO - ##### Finished read-buildbot-config step (success) 19:09:06 INFO - ##### 19:09:06 INFO - ##### 19:09:06 INFO - ##### Running download-and-extract step. 19:09:06 INFO - ##### 19:09:06 INFO - Running pre-action listener: _resource_record_pre_action 19:09:06 INFO - Running main action method: download_and_extract 19:09:06 INFO - mkdir: /builds/slave/test/build/tests 19:09:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:09:06 INFO - https://queue.taskcluster.net/v1/task/KkQu1Lb5Slm7Nanvt0EsHQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 19:09:06 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KkQu1Lb5Slm7Nanvt0EsHQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 19:09:06 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KkQu1Lb5Slm7Nanvt0EsHQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 19:09:06 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KkQu1Lb5Slm7Nanvt0EsHQ/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 19:09:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KkQu1Lb5Slm7Nanvt0EsHQ/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 19:09:07 INFO - Downloaded 1361 bytes. 19:09:07 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 19:09:07 INFO - Using the following test package requirements: 19:09:07 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 19:09:07 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 19:09:07 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 19:09:07 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 19:09:07 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 19:09:07 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 19:09:07 INFO - u'jsshell-linux-x86_64.zip'], 19:09:07 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 19:09:07 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 19:09:07 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 19:09:07 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 19:09:07 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 19:09:07 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 19:09:07 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 19:09:07 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 19:09:07 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 19:09:07 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 19:09:07 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 19:09:07 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 19:09:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:09:07 INFO - https://queue.taskcluster.net/v1/task/KkQu1Lb5Slm7Nanvt0EsHQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 19:09:07 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KkQu1Lb5Slm7Nanvt0EsHQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 19:09:07 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KkQu1Lb5Slm7Nanvt0EsHQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 19:09:07 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KkQu1Lb5Slm7Nanvt0EsHQ/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 19:09:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KkQu1Lb5Slm7Nanvt0EsHQ/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 19:09:10 INFO - Downloaded 22734681 bytes. 19:09:10 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'] 19:09:10 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 19:09:12 INFO - Return code: 0 19:09:12 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 19:09:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:09:12 INFO - https://queue.taskcluster.net/v1/task/KkQu1Lb5Slm7Nanvt0EsHQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 19:09:12 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KkQu1Lb5Slm7Nanvt0EsHQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 19:09:12 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KkQu1Lb5Slm7Nanvt0EsHQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 19:09:12 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KkQu1Lb5Slm7Nanvt0EsHQ/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 19:09:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KkQu1Lb5Slm7Nanvt0EsHQ/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 19:09:13 INFO - Downloaded 22734681 bytes. 19:09:13 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'] 19:09:13 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 19:09:14 INFO - Return code: 0 19:09:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:09:14 INFO - https://queue.taskcluster.net/v1/task/KkQu1Lb5Slm7Nanvt0EsHQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 19:09:14 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KkQu1Lb5Slm7Nanvt0EsHQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 19:09:14 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KkQu1Lb5Slm7Nanvt0EsHQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 19:09:14 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KkQu1Lb5Slm7Nanvt0EsHQ/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 19:09:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KkQu1Lb5Slm7Nanvt0EsHQ/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 19:09:16 INFO - Downloaded 14403333 bytes. 19:09:16 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'] 19:09:16 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 19:09:16 INFO - Return code: 0 19:09:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:09:16 INFO - https://queue.taskcluster.net/v1/task/KkQu1Lb5Slm7Nanvt0EsHQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 19:09:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KkQu1Lb5Slm7Nanvt0EsHQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 19:09:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KkQu1Lb5Slm7Nanvt0EsHQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 19:09:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KkQu1Lb5Slm7Nanvt0EsHQ/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 19:09:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KkQu1Lb5Slm7Nanvt0EsHQ/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 19:09:20 INFO - Downloaded 56650888 bytes. 19:09:20 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/KkQu1Lb5Slm7Nanvt0EsHQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 19:09:20 INFO - mkdir: /builds/slave/test/properties 19:09:20 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:09:20 INFO - Writing to file /builds/slave/test/properties/build_url 19:09:20 INFO - Contents: 19:09:20 INFO - build_url:https://queue.taskcluster.net/v1/task/KkQu1Lb5Slm7Nanvt0EsHQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 19:09:21 INFO - Running post-action listener: _resource_record_post_action 19:09:21 INFO - Running post-action listener: set_extra_try_arguments 19:09:21 INFO - ##### 19:09:21 INFO - ##### Finished download-and-extract step (success) 19:09:21 INFO - ##### 19:09:21 INFO - ##### 19:09:21 INFO - ##### Running populate-webroot step. 19:09:21 INFO - ##### 19:09:21 INFO - Running pre-action listener: _resource_record_pre_action 19:09:21 INFO - Running main action method: populate_webroot 19:09:21 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:09:21 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:09:21 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:09:21 INFO - '--tppagecycles', 19:09:21 INFO - '7'], 19:09:21 INFO - 'tests': ['tsvgm']}}, 19:09:21 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:09:21 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:09:21 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:09:21 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:09:21 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:09:21 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:09:21 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:09:21 INFO - 'talos_options': ['--disable-e10s'], 19:09:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:09:21 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:09:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:09:21 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:09:21 INFO - 'talos_options': ['--disable-e10s'], 19:09:21 INFO - 'tests': ['damp', 'tps']}, 19:09:21 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:09:21 INFO - 'tests': ['damp', 'tps']}, 19:09:21 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:09:21 INFO - 'tests': ['dromaeo_dom']}, 19:09:21 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:09:21 INFO - 'g4': {'talos_options': ['--disable-e10s'], 19:09:21 INFO - 'tests': ['basic_compositor_video']}, 19:09:21 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 19:09:21 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:09:21 INFO - 'tests': ['a11yr', 19:09:21 INFO - 'ts_paint', 19:09:21 INFO - 'tpaint', 19:09:21 INFO - 'sessionrestore', 19:09:21 INFO - 'sessionrestore_no_auto_restore', 19:09:21 INFO - 'tabpaint']}, 19:09:21 INFO - 'other-e10s': {'tests': ['a11yr', 19:09:21 INFO - 'ts_paint', 19:09:21 INFO - 'tpaint', 19:09:21 INFO - 'sessionrestore', 19:09:21 INFO - 'sessionrestore_no_auto_restore', 19:09:21 INFO - 'tabpaint']}, 19:09:21 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:09:21 INFO - 'ts_paint', 19:09:21 INFO - 'tpaint', 19:09:21 INFO - 'sessionrestore', 19:09:21 INFO - 'sessionrestore_no_auto_restore', 19:09:21 INFO - 'tabpaint']}, 19:09:21 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:09:21 INFO - 'ts_paint', 19:09:21 INFO - 'tpaint', 19:09:21 INFO - 'sessionrestore', 19:09:21 INFO - 'sessionrestore_no_auto_restore', 19:09:21 INFO - 'tabpaint']}, 19:09:21 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:09:21 INFO - 'tests': ['a11yr', 19:09:21 INFO - 'ts_paint', 19:09:21 INFO - 'tpaint', 19:09:21 INFO - 'sessionrestore', 19:09:21 INFO - 'sessionrestore_no_auto_restore', 19:09:21 INFO - 'tabpaint']}, 19:09:21 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:09:21 INFO - 'tests': ['a11yr', 19:09:21 INFO - 'ts_paint', 19:09:21 INFO - 'tpaint', 19:09:21 INFO - 'sessionrestore', 19:09:21 INFO - 'sessionrestore_no_auto_restore', 19:09:21 INFO - 'tabpaint']}, 19:09:21 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:09:21 INFO - 'tests': ['tsvgx', 19:09:21 INFO - 'tsvgr_opacity', 19:09:21 INFO - 'tart', 19:09:21 INFO - 'tscrollx', 19:09:21 INFO - 'cart']}, 19:09:21 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:09:21 INFO - 'tsvgr_opacity', 19:09:21 INFO - 'tart', 19:09:21 INFO - 'tscrollx', 19:09:21 INFO - 'cart']}, 19:09:21 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:09:21 INFO - 'talos_options': ['--disable-e10s'], 19:09:21 INFO - 'tests': ['tp5o']}, 19:09:21 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:09:21 INFO - 'tests': ['tp5o']}, 19:09:21 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:09:21 INFO - 'talos_options': ['--disable-e10s', 19:09:21 INFO - '--xperf_path', 19:09:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:09:21 INFO - 'tests': ['tp5n']}, 19:09:21 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:09:21 INFO - 'talos_options': ['--xperf_path', 19:09:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:09:21 INFO - 'tests': ['tp5n']}}, 19:09:21 INFO - 'talos.zip': {'path': '', 19:09:21 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:09:21 INFO - Running post-action listener: _resource_record_post_action 19:09:21 INFO - ##### 19:09:21 INFO - ##### Finished populate-webroot step (success) 19:09:21 INFO - ##### 19:09:21 INFO - ##### 19:09:21 INFO - ##### Running create-virtualenv step. 19:09:21 INFO - ##### 19:09:21 INFO - Running pre-action listener: _resource_record_pre_action 19:09:21 INFO - Running main action method: create_virtualenv 19:09:21 INFO - Creating virtualenv /builds/slave/test/build/venv 19:09:21 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 19:09:21 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:09:21 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:09:21 INFO - Using real prefix '/usr' 19:09:21 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:09:21 INFO - Installing distribute.............................................................................................................................................................................................done. 19:09:23 INFO - Installing pip.................done. 19:09:23 INFO - Return code: 0 19:09:23 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:09:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:09:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:09:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:09:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:09:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:23 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 0x24aa030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x260d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x276b180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27693b0>, '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 0x276a2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x276a740>, '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': 'eca2ab57546de7b41f256b19000001ef-1467943739.668630-1411652204', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 19:09:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 19:09:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 19:09:23 INFO - Using env: {'DISPLAY': ':0', 19:09:23 INFO - 'HOME': '/home/cltbld', 19:09:23 INFO - 'LANG': 'en_US.UTF-8', 19:09:23 INFO - 'LANGUAGE': 'en_US:en', 19:09:23 INFO - 'LOGNAME': 'cltbld', 19:09:23 INFO - 'MAIL': '/var/mail/cltbld', 19:09:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:09:23 INFO - 'MOZ_NO_REMOTE': '1', 19:09:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:09:23 INFO - 'NO_EM_RESTART': '1', 19:09:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:09:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:09:23 INFO - 'PWD': '/builds/slave/test', 19:09:23 INFO - 'SHELL': '/bin/bash', 19:09:23 INFO - 'SHLVL': '1', 19:09:23 INFO - 'TERM': 'linux', 19:09:23 INFO - 'TMOUT': '86400', 19:09:23 INFO - 'USER': 'cltbld', 19:09:23 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1467943739.668630-1411652204', 19:09:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:09:23 INFO - '_': '/tools/buildbot/bin/python'} 19:09:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:09:24 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 19:09:24 INFO - Cleaning up... 19:09:24 INFO - Return code: 0 19:09:24 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:09:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:09:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:09:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:09:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:09:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:24 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 0x24aa030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x260d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x276b180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27693b0>, '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 0x276a2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x276a740>, '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': 'eca2ab57546de7b41f256b19000001ef-1467943739.668630-1411652204', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 19:09:24 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 19:09:24 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 19:09:24 INFO - Using env: {'DISPLAY': ':0', 19:09:24 INFO - 'HOME': '/home/cltbld', 19:09:24 INFO - 'LANG': 'en_US.UTF-8', 19:09:24 INFO - 'LANGUAGE': 'en_US:en', 19:09:24 INFO - 'LOGNAME': 'cltbld', 19:09:24 INFO - 'MAIL': '/var/mail/cltbld', 19:09:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:09:24 INFO - 'MOZ_NO_REMOTE': '1', 19:09:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:09:24 INFO - 'NO_EM_RESTART': '1', 19:09:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:09:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:09:24 INFO - 'PWD': '/builds/slave/test', 19:09:24 INFO - 'SHELL': '/bin/bash', 19:09:24 INFO - 'SHLVL': '1', 19:09:24 INFO - 'TERM': 'linux', 19:09:24 INFO - 'TMOUT': '86400', 19:09:24 INFO - 'USER': 'cltbld', 19:09:24 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1467943739.668630-1411652204', 19:09:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:09:24 INFO - '_': '/tools/buildbot/bin/python'} 19:09:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:09:24 INFO - Downloading/unpacking psutil>=3.1.1 19:09:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:09:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:09:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:09:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:09:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:09:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:09:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:09:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:09:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:09:26 INFO - Installing collected packages: psutil 19:09:26 INFO - Running setup.py install for psutil 19:09:26 INFO - building 'psutil._psutil_linux' extension 19:09:26 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 19:09:26 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 19:09:26 INFO - building 'psutil._psutil_posix' extension 19:09:26 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 19:09:26 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 19:09:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:09:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:09:26 INFO - Successfully installed psutil 19:09:26 INFO - Cleaning up... 19:09:26 INFO - Return code: 0 19:09:26 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:09:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:09:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:09:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:09:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:09:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24aa030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x260d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x276b180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27693b0>, '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 0x276a2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x276a740>, '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': 'eca2ab57546de7b41f256b19000001ef-1467943739.668630-1411652204', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 19:09:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 19:09:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 19:09:26 INFO - Using env: {'DISPLAY': ':0', 19:09:26 INFO - 'HOME': '/home/cltbld', 19:09:26 INFO - 'LANG': 'en_US.UTF-8', 19:09:26 INFO - 'LANGUAGE': 'en_US:en', 19:09:26 INFO - 'LOGNAME': 'cltbld', 19:09:26 INFO - 'MAIL': '/var/mail/cltbld', 19:09:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:09:26 INFO - 'MOZ_NO_REMOTE': '1', 19:09:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:09:26 INFO - 'NO_EM_RESTART': '1', 19:09:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:09:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:09:26 INFO - 'PWD': '/builds/slave/test', 19:09:26 INFO - 'SHELL': '/bin/bash', 19:09:26 INFO - 'SHLVL': '1', 19:09:26 INFO - 'TERM': 'linux', 19:09:26 INFO - 'TMOUT': '86400', 19:09:26 INFO - 'USER': 'cltbld', 19:09:26 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1467943739.668630-1411652204', 19:09:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:09:26 INFO - '_': '/tools/buildbot/bin/python'} 19:09:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:09:27 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:09:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:09:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:09:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:09:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:09:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:09:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:09:28 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:09:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:09:29 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:09:29 INFO - Installing collected packages: mozsystemmonitor 19:09:29 INFO - Running setup.py install for mozsystemmonitor 19:09:29 INFO - Successfully installed mozsystemmonitor 19:09:29 INFO - Cleaning up... 19:09:29 INFO - Return code: 0 19:09:29 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 19:09:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:09:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:09:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:09:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:09:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:29 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 0x24aa030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x260d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x276b180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27693b0>, '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 0x276a2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x276a740>, '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': 'eca2ab57546de7b41f256b19000001ef-1467943739.668630-1411652204', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 19:09:29 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 19:09:29 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 19:09:29 INFO - Using env: {'DISPLAY': ':0', 19:09:29 INFO - 'HOME': '/home/cltbld', 19:09:29 INFO - 'LANG': 'en_US.UTF-8', 19:09:29 INFO - 'LANGUAGE': 'en_US:en', 19:09:29 INFO - 'LOGNAME': 'cltbld', 19:09:29 INFO - 'MAIL': '/var/mail/cltbld', 19:09:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:09:29 INFO - 'MOZ_NO_REMOTE': '1', 19:09:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:09:29 INFO - 'NO_EM_RESTART': '1', 19:09:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:09:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:09:29 INFO - 'PWD': '/builds/slave/test', 19:09:29 INFO - 'SHELL': '/bin/bash', 19:09:29 INFO - 'SHLVL': '1', 19:09:29 INFO - 'TERM': 'linux', 19:09:29 INFO - 'TMOUT': '86400', 19:09:29 INFO - 'USER': 'cltbld', 19:09:29 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1467943739.668630-1411652204', 19:09:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:09:29 INFO - '_': '/tools/buildbot/bin/python'} 19:09:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:09:29 INFO - Downloading/unpacking jsonschema==2.5.1 19:09:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:09:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:09:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:09:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:09:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:09:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:09:31 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:09:31 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:09:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:09:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:09:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:09:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:09:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:09:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:09:31 INFO - Downloading functools32-3.2.3-2.tar.gz 19:09:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:09:31 INFO - warning: no files found matching '*.txt' 19:09:31 INFO - no previously-included directories found matching 'build' 19:09:31 INFO - no previously-included directories found matching 'dist' 19:09:31 INFO - no previously-included directories found matching '.git*' 19:09:31 INFO - Installing collected packages: jsonschema, functools32 19:09:31 INFO - Running setup.py install for functools32 19:09:31 INFO - warning: no files found matching '*.txt' 19:09:31 INFO - no previously-included directories found matching 'build' 19:09:31 INFO - no previously-included directories found matching 'dist' 19:09:31 INFO - no previously-included directories found matching '.git*' 19:09:31 INFO - Successfully installed jsonschema functools32 19:09:31 INFO - Cleaning up... 19:09:31 INFO - Return code: 0 19:09:31 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:09: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')]} 19:09:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:09:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09: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')]} 19:09:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:09:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:31 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 0x24aa030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x260d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x276b180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27693b0>, '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 0x276a2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x276a740>, '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': 'eca2ab57546de7b41f256b19000001ef-1467943739.668630-1411652204', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 19:09:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 19:09:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 19:09:31 INFO - Using env: {'DISPLAY': ':0', 19:09:31 INFO - 'HOME': '/home/cltbld', 19:09:31 INFO - 'LANG': 'en_US.UTF-8', 19:09:31 INFO - 'LANGUAGE': 'en_US:en', 19:09:31 INFO - 'LOGNAME': 'cltbld', 19:09:31 INFO - 'MAIL': '/var/mail/cltbld', 19:09:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:09:31 INFO - 'MOZ_NO_REMOTE': '1', 19:09:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:09:31 INFO - 'NO_EM_RESTART': '1', 19:09:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:09:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:09:31 INFO - 'PWD': '/builds/slave/test', 19:09:31 INFO - 'SHELL': '/bin/bash', 19:09:31 INFO - 'SHLVL': '1', 19:09:31 INFO - 'TERM': 'linux', 19:09:31 INFO - 'TMOUT': '86400', 19:09:31 INFO - 'USER': 'cltbld', 19:09:31 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1467943739.668630-1411652204', 19:09:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:09:31 INFO - '_': '/tools/buildbot/bin/python'} 19:09:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:09:31 INFO - Downloading/unpacking blobuploader==1.2.4 19:09:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:09:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:09:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:09:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:09:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:09:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:09:33 INFO - Downloading blobuploader-1.2.4.tar.gz 19:09:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:09:33 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:09:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:09:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:09:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:09:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:09:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:09:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:09:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:09:34 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:09:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:09:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:09:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:09:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:09:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:09:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:09:34 INFO - Downloading docopt-0.6.1.tar.gz 19:09:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:09:34 INFO - Installing collected packages: blobuploader, requests, docopt 19:09:34 INFO - Running setup.py install for blobuploader 19:09:34 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:09:34 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:09:34 INFO - Running setup.py install for requests 19:09:35 INFO - Running setup.py install for docopt 19:09:35 INFO - Successfully installed blobuploader requests docopt 19:09:35 INFO - Cleaning up... 19:09:35 INFO - Return code: 0 19:09:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:09:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:09:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:09:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:09:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:09:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:35 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 0x24aa030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x260d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x276b180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27693b0>, '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 0x276a2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x276a740>, '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': 'eca2ab57546de7b41f256b19000001ef-1467943739.668630-1411652204', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 19:09:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 19:09:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 19:09:35 INFO - Using env: {'DISPLAY': ':0', 19:09:35 INFO - 'HOME': '/home/cltbld', 19:09:35 INFO - 'LANG': 'en_US.UTF-8', 19:09:35 INFO - 'LANGUAGE': 'en_US:en', 19:09:35 INFO - 'LOGNAME': 'cltbld', 19:09:35 INFO - 'MAIL': '/var/mail/cltbld', 19:09:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:09:35 INFO - 'MOZ_NO_REMOTE': '1', 19:09:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:09:35 INFO - 'NO_EM_RESTART': '1', 19:09:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:09:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:09:35 INFO - 'PWD': '/builds/slave/test', 19:09:35 INFO - 'SHELL': '/bin/bash', 19:09:35 INFO - 'SHLVL': '1', 19:09:35 INFO - 'TERM': 'linux', 19:09:35 INFO - 'TMOUT': '86400', 19:09:35 INFO - 'USER': 'cltbld', 19:09:35 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1467943739.668630-1411652204', 19:09:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:09:35 INFO - '_': '/tools/buildbot/bin/python'} 19:09:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:09:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:09:35 INFO - Running setup.py (path:/tmp/pip-x1ZgPN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:09:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:09:35 INFO - Running setup.py (path:/tmp/pip-lL4PT0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:09:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:09:35 INFO - Running setup.py (path:/tmp/pip-ou96Pm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:09:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:09:35 INFO - Running setup.py (path:/tmp/pip-1vYZUB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:09:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:09:35 INFO - Running setup.py (path:/tmp/pip-g5ZCGl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:09:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:09:35 INFO - Running setup.py (path:/tmp/pip-yYngiu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:09:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:09:35 INFO - Running setup.py (path:/tmp/pip-Jk9gQd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:09:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:09:35 INFO - Running setup.py (path:/tmp/pip-Y6c2Nu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:09:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:09:36 INFO - Running setup.py (path:/tmp/pip-JNXQJ1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:09:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:09:36 INFO - Running setup.py (path:/tmp/pip-4DScMv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:09:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:09:36 INFO - Running setup.py (path:/tmp/pip-8d4gzd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:09:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:09:36 INFO - Running setup.py (path:/tmp/pip-2hFknW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:09:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:09:36 INFO - Running setup.py (path:/tmp/pip-5So180-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:09:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:09:36 INFO - Running setup.py (path:/tmp/pip-bLyNi2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:09:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:09:36 INFO - Running setup.py (path:/tmp/pip-TEosPb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:09:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:09:36 INFO - Running setup.py (path:/tmp/pip-GWPvyo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:09:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:09:36 INFO - Running setup.py (path:/tmp/pip-Bfmugy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:09:36 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:09:36 INFO - Running setup.py install for manifestparser 19:09:36 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:09:36 INFO - Running setup.py install for mozcrash 19:09:36 INFO - Running setup.py install for mozdebug 19:09:36 INFO - Running setup.py install for mozdevice 19:09:37 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:09:37 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:09:37 INFO - Running setup.py install for mozfile 19:09:37 INFO - Running setup.py install for mozhttpd 19:09:37 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:09:37 INFO - Running setup.py install for mozinfo 19:09:37 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:09:37 INFO - Running setup.py install for mozInstall 19:09:37 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:09:37 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:09:37 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:09:37 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:09:37 INFO - Running setup.py install for mozleak 19:09:37 INFO - Running setup.py install for mozlog 19:09:37 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:09:37 INFO - Running setup.py install for moznetwork 19:09:37 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:09:37 INFO - Running setup.py install for mozprocess 19:09:37 INFO - Running setup.py install for mozprofile 19:09:38 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:09:38 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:09:38 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:09:38 INFO - Running setup.py install for mozrunner 19:09:38 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:09:38 INFO - Running setup.py install for mozscreenshot 19:09:38 INFO - Running setup.py install for moztest 19:09:38 INFO - Running setup.py install for mozversion 19:09:38 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:09:38 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:09:38 INFO - Cleaning up... 19:09:38 INFO - Return code: 0 19:09:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:09: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')]} 19:09:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:09:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09: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')]} 19:09:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:09:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:38 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 0x24aa030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x260d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x276b180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27693b0>, '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 0x276a2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x276a740>, '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': 'eca2ab57546de7b41f256b19000001ef-1467943739.668630-1411652204', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 19:09:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 19:09:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 19:09:38 INFO - Using env: {'DISPLAY': ':0', 19:09:38 INFO - 'HOME': '/home/cltbld', 19:09:38 INFO - 'LANG': 'en_US.UTF-8', 19:09:38 INFO - 'LANGUAGE': 'en_US:en', 19:09:38 INFO - 'LOGNAME': 'cltbld', 19:09:38 INFO - 'MAIL': '/var/mail/cltbld', 19:09:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:09:38 INFO - 'MOZ_NO_REMOTE': '1', 19:09:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:09:38 INFO - 'NO_EM_RESTART': '1', 19:09:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:09:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:09:38 INFO - 'PWD': '/builds/slave/test', 19:09:38 INFO - 'SHELL': '/bin/bash', 19:09:38 INFO - 'SHLVL': '1', 19:09:38 INFO - 'TERM': 'linux', 19:09:38 INFO - 'TMOUT': '86400', 19:09:38 INFO - 'USER': 'cltbld', 19:09:38 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1467943739.668630-1411652204', 19:09:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:09:38 INFO - '_': '/tools/buildbot/bin/python'} 19:09:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:09:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:09:38 INFO - Running setup.py (path:/tmp/pip-Wd5bVJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:09:38 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 19:09:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:09:38 INFO - Running setup.py (path:/tmp/pip-tD7i3_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:09:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:09:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:09:38 INFO - Running setup.py (path:/tmp/pip-Ho1Xlf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:09:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 19:09:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:09:38 INFO - Running setup.py (path:/tmp/pip-bz_74V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:09:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:09:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:09:38 INFO - Running setup.py (path:/tmp/pip-G6D3U7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:09:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 19:09:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:09:39 INFO - Running setup.py (path:/tmp/pip-j44J9_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:09:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 19:09:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:09:39 INFO - Running setup.py (path:/tmp/pip-MTIJFE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:09:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 19:09:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:09:39 INFO - Running setup.py (path:/tmp/pip-VR9J_Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:09:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 19:09:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:09:39 INFO - Running setup.py (path:/tmp/pip-wQCWSS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:09:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 19:09:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:09:39 INFO - Running setup.py (path:/tmp/pip-AdUrjK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:09:39 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)) 19:09:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:09:39 INFO - Running setup.py (path:/tmp/pip-nVGyzn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:09:39 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 19:09:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:09:39 INFO - Running setup.py (path:/tmp/pip-Gt75Xd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:09:39 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)) 19:09:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:09:39 INFO - Running setup.py (path:/tmp/pip-u68lZp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:09:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 19:09:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:09:39 INFO - Running setup.py (path:/tmp/pip-JdJjiK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:09:39 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)) 19:09:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:09:39 INFO - Running setup.py (path:/tmp/pip-jV1jGT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:09:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 19:09:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:09:39 INFO - Running setup.py (path:/tmp/pip-HxLQc6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:09:39 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 19:09:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:09:39 INFO - Running setup.py (path:/tmp/pip-tdtbCO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:09:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 19:09:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:09:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:09:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 19:09:39 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:09:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:09:39 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:09:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:09:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:09:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:09:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:09:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:09:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:09:41 INFO - Downloading blessings-1.6.tar.gz 19:09:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:09:41 INFO - Installing collected packages: blessings 19:09:41 INFO - Running setup.py install for blessings 19:09:42 INFO - Successfully installed blessings 19:09:42 INFO - Cleaning up... 19:09:42 INFO - Return code: 0 19:09:42 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:09:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:09:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:09:42 INFO - Reading from file tmpfile_stdout 19:09:42 INFO - Current package versions: 19:09:42 INFO - argparse == 1.2.1 19:09:42 INFO - blessings == 1.6 19:09:42 INFO - blobuploader == 1.2.4 19:09:42 INFO - docopt == 0.6.1 19:09:42 INFO - functools32 == 3.2.3-2 19:09:42 INFO - jsonschema == 2.5.1 19:09:42 INFO - manifestparser == 1.1 19:09:42 INFO - mozInstall == 1.12 19:09:42 INFO - mozcrash == 0.17 19:09:42 INFO - mozdebug == 0.1 19:09:42 INFO - mozdevice == 0.48 19:09:42 INFO - mozfile == 1.2 19:09:42 INFO - mozhttpd == 0.7 19:09:42 INFO - mozinfo == 0.9 19:09:42 INFO - mozleak == 0.1 19:09:42 INFO - mozlog == 3.2 19:09:42 INFO - moznetwork == 0.27 19:09:42 INFO - mozprocess == 0.23 19:09:42 INFO - mozprofile == 0.28 19:09:42 INFO - mozrunner == 6.12 19:09:42 INFO - mozscreenshot == 0.1 19:09:42 INFO - mozsystemmonitor == 0.3 19:09:42 INFO - moztest == 0.7 19:09:42 INFO - mozversion == 1.4 19:09:42 INFO - psutil == 3.1.1 19:09:42 INFO - requests == 1.2.3 19:09:42 INFO - wsgiref == 0.1.2 19:09:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:09:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:09:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:09:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:09:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:09:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:42 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 0x24aa030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x260d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x276b180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27693b0>, '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 0x276a2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x276a740>, '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': 'eca2ab57546de7b41f256b19000001ef-1467943739.668630-1411652204', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 19:09:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 19:09:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 19:09:42 INFO - Using env: {'DISPLAY': ':0', 19:09:42 INFO - 'HOME': '/home/cltbld', 19:09:42 INFO - 'LANG': 'en_US.UTF-8', 19:09:42 INFO - 'LANGUAGE': 'en_US:en', 19:09:42 INFO - 'LOGNAME': 'cltbld', 19:09:42 INFO - 'MAIL': '/var/mail/cltbld', 19:09:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:09:42 INFO - 'MOZ_NO_REMOTE': '1', 19:09:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:09:42 INFO - 'NO_EM_RESTART': '1', 19:09:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:09:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:09:42 INFO - 'PWD': '/builds/slave/test', 19:09:42 INFO - 'SHELL': '/bin/bash', 19:09:42 INFO - 'SHLVL': '1', 19:09:42 INFO - 'TERM': 'linux', 19:09:42 INFO - 'TMOUT': '86400', 19:09:42 INFO - 'USER': 'cltbld', 19:09:42 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1467943739.668630-1411652204', 19:09:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:09:42 INFO - '_': '/tools/buildbot/bin/python'} 19:09:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:09:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 19:09:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 19:09:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 19:09:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 19:09:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 19:09:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 19:09:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 19:09:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 19:09:42 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 19:09:42 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 19:09:42 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 19:09:42 INFO - Cleaning up... 19:09:42 INFO - Return code: 0 19:09:42 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:09:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:09:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:09:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:09:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:09:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:42 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 0x24aa030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x260d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x276b180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27693b0>, '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 0x276a2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x276a740>, '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': 'eca2ab57546de7b41f256b19000001ef-1467943739.668630-1411652204', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 19:09:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 19:09:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 19:09:42 INFO - Using env: {'DISPLAY': ':0', 19:09:42 INFO - 'HOME': '/home/cltbld', 19:09:42 INFO - 'LANG': 'en_US.UTF-8', 19:09:42 INFO - 'LANGUAGE': 'en_US:en', 19:09:42 INFO - 'LOGNAME': 'cltbld', 19:09:42 INFO - 'MAIL': '/var/mail/cltbld', 19:09:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:09:42 INFO - 'MOZ_NO_REMOTE': '1', 19:09:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:09:42 INFO - 'NO_EM_RESTART': '1', 19:09:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:09:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:09:42 INFO - 'PWD': '/builds/slave/test', 19:09:42 INFO - 'SHELL': '/bin/bash', 19:09:42 INFO - 'SHLVL': '1', 19:09:42 INFO - 'TERM': 'linux', 19:09:42 INFO - 'TMOUT': '86400', 19:09:42 INFO - 'USER': 'cltbld', 19:09:42 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1467943739.668630-1411652204', 19:09:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:09:42 INFO - '_': '/tools/buildbot/bin/python'} 19:09:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:09:42 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:09:42 INFO - Cleaning up... 19:09:42 INFO - Return code: 0 19:09:42 INFO - Running post-action listener: _resource_record_post_action 19:09:42 INFO - Running post-action listener: _start_resource_monitoring 19:09:42 INFO - Starting resource monitoring. 19:09:42 INFO - ##### 19:09:42 INFO - ##### Finished create-virtualenv step (success) 19:09:42 INFO - ##### 19:09:42 INFO - ##### 19:09:42 INFO - ##### Running install step. 19:09:42 INFO - ##### 19:09:42 INFO - Running pre-action listener: _resource_record_pre_action 19:09:42 INFO - Running main action method: install 19:09:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:09:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:09:42 INFO - Reading from file tmpfile_stdout 19:09:42 INFO - Detecting whether we're running mozinstall >=1.0... 19:09:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:09:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:09:43 INFO - Reading from file tmpfile_stdout 19:09:43 INFO - Output received: 19:09:43 INFO - Usage: mozinstall [options] installer 19:09:43 INFO - Options: 19:09:43 INFO - -h, --help show this help message and exit 19:09:43 INFO - -d DEST, --destination=DEST 19:09:43 INFO - Directory to install application into. [default: 19:09:43 INFO - "/builds/slave/test"] 19:09:43 INFO - --app=APP Application being installed. [default: firefox] 19:09:43 INFO - mkdir: /builds/slave/test/build/application 19:09:43 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'] 19:09:43 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 19:09:59 INFO - Reading from file tmpfile_stdout 19:09:59 INFO - Output received: 19:09:59 INFO - /builds/slave/test/build/application/firefox/firefox 19:09:59 INFO - Running post-action listener: _resource_record_post_action 19:09:59 INFO - ##### 19:09:59 INFO - ##### Finished install step (success) 19:09:59 INFO - ##### 19:09:59 INFO - ##### 19:09:59 INFO - ##### Running run-tests step. 19:09:59 INFO - ##### 19:09:59 INFO - Running pre-action listener: _resource_record_pre_action 19:09:59 INFO - Running main action method: run_tests 19:09:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:09:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:09:59 INFO - Python 2.7.3 19:09:59 INFO - Return code: 0 19:09:59 INFO - grabbing minidump binary from tooltool 19:09:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:09:59 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 0x27693b0>, '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 0x276a2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x276a740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:09:59 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 19:09:59 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 19:09:59 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 19:09:59 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:09:59 INFO - Return code: 0 19:09:59 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 19:09:59 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:09:59 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:09:59 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 19:09:59 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:09:59 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/KkQu1Lb5Slm7Nanvt0EsHQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-010', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 19:09:59 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/KkQu1Lb5Slm7Nanvt0EsHQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-010 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 19:09:59 INFO - Using env: {'DISPLAY': ':0', 19:09:59 INFO - 'HOME': '/home/cltbld', 19:09:59 INFO - 'LANG': 'en_US.UTF-8', 19:09:59 INFO - 'LANGUAGE': 'en_US:en', 19:09:59 INFO - 'LOGNAME': 'cltbld', 19:09:59 INFO - 'MAIL': '/var/mail/cltbld', 19:09:59 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:09:59 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 19:09:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:09:59 INFO - 'MOZ_NO_REMOTE': '1', 19:09:59 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:09:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:09:59 INFO - 'NO_EM_RESTART': '1', 19:09:59 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:09:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:09:59 INFO - 'PWD': '/builds/slave/test', 19:09:59 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:09:59 INFO - 'SHELL': '/bin/bash', 19:09:59 INFO - 'SHLVL': '1', 19:09:59 INFO - 'TERM': 'linux', 19:09:59 INFO - 'TMOUT': '86400', 19:09:59 INFO - 'USER': 'cltbld', 19:09:59 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1467943739.668630-1411652204', 19:09:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:09:59 INFO - '_': '/tools/buildbot/bin/python'} 19:09:59 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/KkQu1Lb5Slm7Nanvt0EsHQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-010', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 19:09:59 INFO - mozversion application_buildid: 20160707173726 19:09:59 INFO - mozversion application_changeset: 06e1c91a55c5e4bb8f5b0890c3fe850cbf2d4f31 19:09:59 INFO - mozversion application_display_name: Nightly 19:09:59 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:09:59 INFO - mozversion application_name: Firefox 19:09:59 INFO - mozversion application_remotingname: firefox 19:09:59 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 19:09:59 INFO - mozversion application_vendor: Mozilla 19:09:59 INFO - mozversion application_version: 50.0a1 19:09:59 INFO - mozversion platform_buildid: 20160707173726 19:09:59 INFO - mozversion platform_changeset: 06e1c91a55c5e4bb8f5b0890c3fe850cbf2d4f31 19:09:59 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 19:09:59 INFO - mozversion platform_version: 50.0a1 19:09:59 INFO - using testdate: 1467943799 19:09:59 INFO - actual date: 1467943799 19:09:59 INFO - starting webserver on 'localhost:36817' 19:09:59 INFO - SUITE-START | Running 2 tests 19:09:59 INFO - TEST-START | dromaeo_css 19:09:59 INFO - operating with platform_type : linux_ 19:09:59 INFO - Initialising browser for dromaeo_css test... 19:09:59 INFO - TEST-INFO | started process 493 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpmx9zVI/profile http://localhost:36817/getInfo.html) 19:10:04 INFO - PROCESS | 493 | __metrics Screen width/height:1600/1200 19:10:04 INFO - PROCESS | 493 | colorDepth:24 19:10:04 INFO - PROCESS | 493 | Browser inner width/height: 1024/697 19:10:04 INFO - PROCESS | 493 | __metrics 19:10:05 INFO - PROCESS | 493 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:10:06 INFO - TEST-INFO | 493: exit 0 19:10:06 INFO - Browser initialized. 19:10:06 INFO - Running cycle 1/1 for dromaeo_css test... 19:10:06 INFO - TEST-INFO | started process 576 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpmx9zVI/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:10:18 INFO - PROCESS | 576 | RSS: Main: 159305728 19:10:18 INFO - PROCESS | 576 | 19:11:09 INFO - PROCESS | 576 | 1467943869270 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 19:13:21 INFO - PROCESS | 576 | Cycle 1(1): loaded http://localhost:36817/tests/dromaeo/cssquery-dojo.html (next: http://localhost:36817/tests/dromaeo/cssquery-ext.html) 19:13:23 INFO - PROCESS | 576 | RSS: Main: 231862272 19:13:23 INFO - PROCESS | 576 | 19:16:25 INFO - PROCESS | 576 | Cycle 1(1): loaded http://localhost:36817/tests/dromaeo/cssquery-ext.html (next: http://localhost:36817/tests/dromaeo/cssquery-jquery.html) 19:16:26 INFO - PROCESS | 576 | RSS: Main: 245219328 19:16:26 INFO - PROCESS | 576 | 19:19:28 INFO - PROCESS | 576 | Cycle 1(1): loaded http://localhost:36817/tests/dromaeo/cssquery-jquery.html (next: http://localhost:36817/tests/dromaeo/cssquery-mootools.html) 19:19:29 INFO - PROCESS | 576 | RSS: Main: 250744832 19:19:29 INFO - PROCESS | 576 | 19:22:31 INFO - PROCESS | 576 | Cycle 1(1): loaded http://localhost:36817/tests/dromaeo/cssquery-mootools.html (next: http://localhost:36817/tests/dromaeo/cssquery-prototype.html) 19:22:32 INFO - PROCESS | 576 | RSS: Main: 246112256 19:22:32 INFO - PROCESS | 576 | 19:25:35 INFO - PROCESS | 576 | Cycle 1(1): loaded http://localhost:36817/tests/dromaeo/cssquery-prototype.html (next: http://localhost:36817/tests/dromaeo/cssquery-yui.html) 19:25:35 INFO - PROCESS | 576 | RSS: Main: 218415104 19:25:35 INFO - PROCESS | 576 | 19:28:38 INFO - PROCESS | 576 | Cycle 1(1): loaded http://localhost:36817/tests/dromaeo/cssquery-yui.html (next: http://localhost:36817/tests/dromaeo/cssquery-dojo.html) 19:28:39 INFO - PROCESS | 576 | RSS: Main: 280858624 19:28:39 INFO - PROCESS | 576 | 19:28:39 INFO - PROCESS | 576 | __start_tp_report 19:28:39 INFO - PROCESS | 576 | _x_x_mozilla_page_load 19:28:39 INFO - PROCESS | 576 | _x_x_mozilla_page_load_details 19:28:39 INFO - PROCESS | 576 | |i|pagename|runs| 19:28:39 INFO - PROCESS | 576 | |0;dojo.html;2586.909458168964;2587;2616.8691565421736;2625.566781481056;2633.315338012116;4574.794134263958;4674.1352849722825;4711;4731.124741922738;4749.430068391794;4654.6276297896175;4662.626989840813;4677.555632214942;4700.929486057703;4736.573708366246;4101.651359634435;4221.451211342521;4338.327559228324;4346.78266086696;4508.549145085498;4402.647788176938;4442.644588432918;4448.221561226788;4458.598726114649;4498;4316.82732690692;4326.069894972565;4361.214981303364;4366.912661746747;4370.912581748379;4279.593438623326;4283.764392958386;4295.699301048928;4389.736615803062;4434.5343738907595;4302.462192225972;4317.244482215599;4324.545922678137;4367.781610919441;4371.125774845043;4606.124836281096;4650.25595904657;4658.2779669151;4732.668713190078;4735.384400028009;5042.3949126104735;5055.266986286968;5056.191009438509;5057.317262169597;5117.897642047175;5124.436312005676;5129.204973229118;5136;5143.434222235551;5146;4627.629789616823;4671.346011558385;4721.126591580569;4726.692764970266;4750.049990001979;5164.457731938168;5183.170692689227;5238.345206849144;5238.73806309683;5240.737963101829;4968.850934471971;4970.304157417964;5016.423111342242;5022.045811295842;5028.17035189197;4941.530554597308;5002.049610574052;5040.042391945592;5062.645614806928;5083.923741143886;4989.850304490871;5006.4743201963265;5023.52276533736;5024.84925452237;5072.061668591323;4899.534544218293;4972;4991.950080499221;4993.450720420751;5049.772760225798;4199.0552125771455;4205.621494065487;4244.639205667553;4247.277962746341;4298.849540266076;3692.538432695913;3702.611225821304;3709.851605935786;3743.401055831054;3749.3251214781058;4376.321670141133;4377.934330985037;4384.342348647665;4387.495438024668;4421.53573874745;4894.975525122423;4946.282788995575;5007.12375334315;5039.546440820343;5056.898862022739;4327.8485253016;4335.024619460596;4339.153864996359;4340.544242854518;4391.912161756747;4401.515833258339;4494.775261236925;4498.550144985541;4498.820047198075;4536.591706746409;4749.5487928646035;4819.855404337875;4840.322354870392;4843.055604157155;4850.102730994778;5117.948820511747;5142.408623008402;5148.665336753099;5149.819756308511;5200.895982080261;4626.467956185082;4740.288956656529;4830.7826147822725;4841.951580484291;4893.73084480357;4979.502049794992;5004.224345226496;5022.4977502249485;5029.245613158056;5067.898642027212;5077;5089.694618322913;5089.720065396439;5096.872578185575;5163.483651634807;4803.351547541177;4880.1215781159735;4897.681650692693;4956.454789973028;4967.379077615298;4908.361912951294;4938.111139994836;4942.233953737176;4942.406911170682;4980.153373926369;4873.51264873524;4874.390701162355;4882.487338829372;4916.139675556692;4934.309196712535;4824.517548245148;4917.1149193145575;4950.183219768698;4961.082199792905;5001.724905130252;4818.927716084191;4822.14897298709;4843.709377437365;4853.854384368475;4888.60891128716 19:28:39 INFO - PROCESS | 576 | |1;ext.html;568.5792513539982;571.8570357410647;573.3836126164374;575.0856138739404;590.530528230057;27682.723172768292;39462.60537394625;40358.59641403585;40425.3936190957;41567.16865662688;37613.62386376136;38106.047348816224;40373.7981310093;40985;41185.97035074125;7504.24957504248;7797.999999999985;7934.246246606592;7949.0858551266765;7952.284294413502;7487.064116985377;10300.381977081362;10561.683149505488;10567.630132945349;10568.524416401242;9994.250431217652;10041.698749037541;10045.447500387476;10052.14556762676;10061.64784232552;9978;10021.348612340247;10042.748931276776;10044.045815647502;10053.095221430107;10158.187345012455;10207.183425325957;10220.591176352937;10229;10241.948790256001;38197.80901095505;38444.231115377814;38496;38547.22905541902;38560.42159367612;2611.246457515055;2672.2116975492277;2851.7148285171315;3086.24387025178;3298.307355455357;26380.8680956594;26607.46785064288;27526.449471010666;27793.16620501388;28075.57886631702;3192.576238581035;3220.6135263768497;3259.9022029339158;3285.8357082146035;3313.519539666759;181005;181149;181430;181476.9999999987;181483.0184339861;50934;51336;51757.2236416454;51815;51833;54128;54242.186367203736;54265.72867135639;54333;54381.728091360084;11655.358955257543;12015;12039.097067719957;12048.277103373826;12085;8153.673853045931;8986.281097512183;9039.412438191497;9099.499527525917;9137.77155571116;14886.553403397915;15161.166135862633;15354.539363819104;15399.615009624626;15441.691166176613;5599.440055994368;5728.398518155617;5738.340090889601;5853.590248682552;5861;690.6378010409115;695.5861262548775;762.8169239382619;893.1202765276198;918.609590923868;26965.460690786076;27878.3030424241;28041;28154.718452815618;28525.14424567266;897.5601955042113;901.5672477210976;920.2316066084894;946.4983558713893;967.7387105481621;13394.397252123677;13726.725465490834;13963;13974.510892118727;14026.649333766738;421924;445101;455666;457354;661355;49386.50613493891;49667.25499117516;49784.25323620148;49950.25074623884;50054.49945500498;1031.8761748590218;1137.3062431916524;1179.8407215026034;1184.088252045937;1190.6309044196328;28040.579391309555;29068.854655726587;29150.854245728635;29400.55899161557;29914.551281730357;40663.1867362657;41000.38499422571;41255.7937210312;41296.79351603343;41354.793226033675;327250;327323;329457;329848;330326;22586.20948266803;23691.28926132219;24507.877460612584;24697.135600253903;25208.24375268745;40734.592654074266;40964;41132.79433602933;41790.582094178666;41830.1633967325;35188;35287.64712352843;35379.82310088536;35611;36048.81975590105;35460.29079418449;35648.821755892095;36091.639083608825;36254.27491450103;36272.63727362798;34146.48780268348;34990.65009349874;35179;35304.823475882455;35429;28968.855155724086;29767.404651906407;30139.09582712522;30141.246468838453;30176.24559385945;24739.659852087807;24766.133185339146;24768.133115340876;25130.62304065477;25168.622470662587 19:28:39 INFO - PROCESS | 576 | |2;jquery.html;348.6652813299233;370.26502392750405;372.1514945923295;372.3706935279378;391.48910671573583;13214.933925330371;13951.79072313915;15375.61560960978;18246.908765456188;18256.908715456404;17185.226664800084;17227.310907563682;17230;17238.39665611704;17426.215820288086;4435.7116787408795;4713.080949214905;4728.385309909707;4763.833265835697;4781.90436191276;3501.247231845153;5191.974040129813;5404.513593776559;5410.8917821643545;5505.587080968923;5420.620556561043;5450.127979523277;5461.481159289861;5466.753996070186;5467.480589344006;6057.697115144248;6196.721147548371;6226.564140510166;6250.374962503736;6252.71862766172;4629.23617603095;4935.087008903364;5485.204645326446;5500.202470641774;5505.174223866412;12172.2696638202;12192.085593580518;12291.508339666403;12373.814392784116;12390.31853248071;1674.8492635662726;1819.4814477873742;1850.3061351993003;1860.255897640941;2009.2465325502938;9232.6306947722;10870.456477176109;12570.874291257061;13263.86736132636;14242.430302787876;1677.1614192903548;1687.333503266203;1709.9658006839918;1715.5882588178747;1733.8699597530235;68508;73002;74377;74936.25063749401;75880.62059689667;10049.29654924163;10136.847947280796;10229.335093218917;10450.790984180274;10590.205734569938;13830.65423364424;13915.860841391659;13980.44078236859;14000.089994150349;14012.369443375073;6452.935470645233;6717.093192419059;6737;6754.257031726506;6808.795736127924;6099;6112.908306375408;6242.563020588515;6281.560290779693;6296.307406185316;4343.457067866517;4415.094905544356;4440.35614835847;4446.399736035593;4506.211413002711;1781.1628534588722;1823.186786882631;1827.3969590035256;1841.4935892629635;1907.3419670214032;806.5160903457995;850.6384786465727;879.4503435352905;882.969096081634;901.4906577783531;16611.91694041522;16638.08490533008;17347.392841250497;17814.821851781577;17950.371736989404;838.4256784102909;844.6705784743832;851.0765819086361;856.4261944497201;895.2435192262528;9539.952300238594;9666.420014799134;9777.120059194707;9897.716603072504;10004.749881253027;396581;404780;406126;406199;406383;59440.70279648574;76581.61709191419;78943.8158427624;86144.56927715322;87094.56452717696;902.0393281155548;904.8054668246359;921.1525396634978;934.2992755433424;935.4995077633433;12100.697482562655;15554.533363999099;15556.444355564805;16489.505314840575;16568.420105296256;38830;39140.804295978334;39527.60472395239;40527;41616.95957601084;50378.748106259234;53423;53438.19842702441;53449;53767.731161343945;7708.344790692854;7738.729144479917;7827.373810095292;8009.599520024092;8028.59857007159;11394.316341019565;11653.88346116528;11876.812318768054;12095.455704493143;12259.632211033684;10386.221033422373;10704.946475267574;10746.570137194425;10869.565217391531;11396;10220.540075696761;10704.839427408438;10908.290961087503;10946.343219406861;10974.451277436256;10735.248532602644;10860.511276992396;10988.560457581927;11196.83204751913;11199.160062995144;7591.367855911006;7626.6186690664135;7685.923140768521;7765.495242809199;7765.922340776746;9377.343585948918;10748.89251107479;11035.503402347074;11223.270487418291;11420.143489238175 19:28:39 INFO - PROCESS | 576 | |3;mootools.html;1349.8582648821873;1391.8190635217422;1443.2927865345978;1686.477192070459;1690.7210310298801;7593.506422082568;8079.111297757242;8194.959025204873;8197.549134797599;8204.548749818758;6875.9312406875915;7027.859442811154;7496.250374962501;7539.208383119776;7942.285194332509;2173.739151301842;2229.1306390507716;2236.5974124657555;2238;2244.517428752818;2165.9891700541475;2191.7041199438113;2215.4129155773735;2216.445888527868;2218.589560931233;1896.9051547422644;2177.586258610867;2240.8543444676043;2246.809021233197;2258.4918393361527;2193.747719012318;2196.7803219678062;2204.3386983904766;2214.7895949884737;2235.642297232435;2225.5215128747236;2230.3643461613524;2232.3079845248026;2233.2630232023557;2233.7766223377694;7862.68549258029;7954.323882469997;7954.562499062549;7978.960105199495;7981.880271795928;4570.931436028429;4704.200285951362;4704.317873908264;4704.435467743892;4712.387389639325;2803.43931213756;2811.1566530040905;2934.838583877886;2944.573036909636;2947.911562653124;880.471716969813;889.0709208876739;897.7396554999042;908.7546362482159;908.9363744537885;10644.467776611138;10832.295900766425;10856.837147442795;10859.78280434387;10865.782684346268;4212.389203565466;4290.184864875664;4448.199324121689;4448.777561121931;4452.732836029849;9290.117438843434;9400.858987115198;9403.529823508798;9420.434773913586;9433.151016408556;4712.64655150865;4716.127516409475;4718.31584420264;4719.787609557578;4722.244440889442;4094.119764250641;4200.894977625584;4208.600182982612;4216.51510076339;4217;3519.2433626769857;3568.57177138745;3600.5499312585926;3630.4554316852154;3639.7088232941833;1034.4775888176423;1085.9728506787235;1090.7491277006334;1097.0948967102172;1104.2711810205226;567.8041075828833;614.7817524778728;647.058529839083;654.1202083198091;685.674304705261;5077.974610126926;5115.897682046339;5253.50091741284;5315.6013299002725;5364.624476286701;435.1427687455707;436.55471419152286;600.7807150390164;825.1748251748252;942.8585712143097;3447.500112483676;3588.6231945645854;3625.3836847735947;3649.7627654202392;3690.372636651776;9804.215662747105;10019.649312274036;10036.799264014824;10101.747456313651;10110.39337639744;8721.389502734875;9331.486768227676;9650.227981761442;9678.128968392737;10021.649242276631;7479.626018699152;7649;7651.579163146081;7653.4642575020325;7658.961705191438;5473.67157970523;5595.636283641713;5609.382967873449;5651.8304450866535;5720.799772007959;9350.67272645454;9403.4828084456;9453.054694530767;9465.574049167666;9491.905080949102;8833.381663283508;9081.091890810865;9189.678361257324;9417.246620270225;9418.152366287068;6802;6969.1985421677155;6971.372576468142;6976.127984002;6981.650917454005;7255.2019277880445;7323.890141647773;7361.926380736339;7386.224446433047;7412.592307423143;5651.265335506522;5802.65184088956;6405.967970160119;6510.18622672166;6605.702743376656;6289.685515724287;6367.108604795425;6445.677716114269;6451;6516.674166291761;6220.564560480723;6323.936760632335;6325.304216536086;6326.2092238470195;6340.524460665376;4556.2937744649635;4652.581267685924;4682.2508398656055;4696.8121275148615;4747.643926705442;4626;4659.347691323285;4665.883352916204;4728.38530990969;4748.453927798209 19:28:39 INFO - PROCESS | 576 | |4;prototype.html;297.40518962075845;304.41096478314455;306.1153267058202;311.97192252697266;312.82950791818456;4849.884526558889;4871.415430148383;4889.291052797343;4890.290907818364;4900.632452566054;4786.401699787527;4795.640326975482;4864.610831133509;4867.586255168315;4871.683340582868;3633.836477358512;3729.552453705559;3740.8503659853573;3781.9221521866275;3790.848366065354;3739.756915800478;3775.452559378902;3802.8668996585125;3812.0850995761093;3817.5800661927306;3740.382836831933;3751.737378383514;3778.5843557208686;3786.053486628343;3795.8802153364686;3594.3530164570366;3654.5614526256754;3773.13217959868;3778.527684039495;3787.1857550626746;3339.632640409553;3345.8327083645963;3347.815870127142;3348.1294863335465;3365.2259980204444;4712.693674911154;4720.197566413718;4720.362751028601;4723.039938425549;4737.644676649265;4199.997002143482;4285.528591854893;4313.309870420749;4314.80583373749;4319.632831209351;4967.55292023716;4980.452150263468;4989.276554899519;4991.226359914219;4992.276119962621;3602.963970360315;3611.9638803611892;3617.5116359291687;3624.1845584743487;3654.524911761454;5126.974365128188;5290.285811415448;5293.841184764425;5296.232046353257;5304.681719096867;3716.1267102230954;3720.925581488355;3725.869594564177;3737.0844143185095;3745.4943582616543;3784.5647750509042;3789.8484060637816;3792.7345085843726;3794.5446546414605;3800.220954704279;4267.1892340455215;4270.935935960963;4285.742855428684;4286.699931004862;4318.654507639381;4833.17259403246;4962.057209130254;4982.9252561211615;5002.424721157041;5025.899482010339;1297.8838199148913;1309.8297221361163;1310.842698876141;1317.571789168505;1318.5055604148445;843.5866425451485;859.9613017414231;870.6430363550913;880.2561835249204;902.9954850225838;4419.867403977886;4421.115776844644;4429.645628349725;4435;4437.7781110944325;5084.161113416319;5120.820771272987;5125.1799712045895;5140.306058682105;5143.331366922276;4213.97893010533;4385.561443855589;4388.27593447084;4413.602775750133;4423.248047831877;2717.904873329424;2739.356251280947;2767.9169624910883;2768.695443501213;2774.890043982383;4858;4893.632977526629;4897.534734200317;4897.926531102036;4907.435644900811;5082.517160869711;5306.6550674206055;5345.973270133624;5353.411124776271;5379.825902717337;4878.780454879468;4893.797790994067;4905.092557876804;4915.820203151289;4921.188003979375;5285.912064734485;5295.814646487354;5297.682139071668;5298.3906850712665;5300.787968481218;4878.999999999858;5108.846734597968;5157.535821776059;5200.503758196011;5207.29701490294;4879.829205977774;4898.804047838046;4900.387451568554;4904.558589726942;4940.802367905244;3691.427828686558;3694.2980833641523;3696.4270538066644;3699.389600715959;3700.1674623209565;5109.718965456936;5226.947730522798;5238.449962753856;5250.527452529291;5262.158244614355;4994.101061808909;5006.974965125297;5020.849374518771;5027.497250274943;5029.572486338702;5020.648554601143;5051.469595692546;5059.974700126476;5068.822591209438;5080.872978175576;4686.742229177292;4990.925136122888;5008.77460514285;5011.624128190328;5017.4731653175895;4850.345203397496;4941.65408421407;5094.2103973884105;5106.693598384109;5128.666636668605;5008.649394542347;5009.123403404463;5015.423226329019;5017.3226614406585;5029.044481548494 19:28:39 INFO - PROCESS | 576 | |5;yui.html;537.6102325813785;574.7787101965743;604.7913469852899;614.8616561273711;621.3693101501976;10372.585096596136;10839.458027098643;10885.401302928356;10897.945510272437;10932.508037138328;11216.383098929555;11347.092232621391;11354.716132096702;11358.60244891429;11369.488373023212;606.1362558354349;722.2236096196583;728.6538894025349;741.7478057460462;751.9849603007937;755.6599530211399;758.7723682895137;760.9277118673717;761.3300295739741;761.4289283037722;807.5235610989515;813.0730966697969;813.9430239883211;814.4136221920209;814.5153633588005;634.9428551430393;652.0284775684244;652.2140820311513;656.4846595422619;807.7980504873782;568.0882184094552;581.6684489840792;582.4292193650205;585.0814221671976;585.5783835638333;11558.53765849365;11568.074554035657;11588.942055289754;11594.246373985749;11609.361485118316;12721.363931803373;13270.734585308337;13372.799408008888;13386.19682819034;13497.460101595925;11840.763184736343;11945.522179112824;12016.279023258545;12124.636260912186;12352.505899763999;4024.9195016099516;4053.148838743867;4130.359794231868;4180.205760905449;4193.308104162809;58577.707111464595;58832;59058.11412828768;59335.40664593342;59413.702931485495;9671.371360861522;9794.853077203848;10117.342372745748;10131.442770647538;10330.741731456774;18464.169112389976;18525.90737046333;18548.907255463906;18561.25754969786;18587.535311617317;6455.0317452382515;6482.805515834532;6498.252700939453;6522.902156467657;6550.737970481223;3394.694477496988;3471.149568355769;3560.893173204808;3590.9640903591153;3596.1009747563107;2645.2593273883344;2719.3065768228976;2726.2775364528416;2728.7953403494816;2735.097417852104;478.2443738892611;479.89922116355257;482.642844295219;492.4902725678905;494.1426624805959;471.7853376713587;473.51701264709806;476.7235003697847;486.4357345479227;512.6261618861413;6885.724571017103;6997.195322537871;7002.271006813077;7513.361364284097;7666.540007599562;413.8716997730713;484.12373603777274;485.33266758207463;503.58957449678394;538.4373329870295;3460.8615655374006;3482.8780992665133;3489.2149266414854;3546.0248431681493;3627.4558816177782;89456.3003105159;95793.52103239439;98310;99278;99541;52805.47194528082;77994.61002694951;78499.21500785033;78502.6074869622;83232;43391.56608433875;43608.56391436108;45143;56682.716586416806;65511.67244163844;6755.189377274758;6890.345417185359;7557.659905304385;7587.924120758943;8018.238267364591;33913.32173356568;39339.60660393359;40173.19653606853;42828.14343713171;45244.54755452403;60422.69788651029;62367;63713.36286637074;64273.357266426734;67355;9349.252059835333;9353.298502612468;9364.157225849705;9409.85885211736;9589.712308630753;10366.792664146826;11063.834042489209;11313.264637798517;13085.280309583064;13167;12096.092793040732;12167.39163041862;12257.142000060267;12636.30500322491;12716.74566508646;12151.696207594527;12177.269363838199;12326.938365308475;12488.25070495773;12920.28938408359;11946.581869634521;11961.581344652895;12093.455794489446;12560.434780435085;12992.285424301375;10357.844632330673;10530.526126324487;10658.147348211969;10686.519106640375;10838.783224335311;11001.339919604849;11049.17131215146;11109.166812488935;11171.776564468828;11291.830622540832 19:28:39 INFO - PROCESS | 576 | __end_tp_report 19:28:39 INFO - PROCESS | 576 | __start_cc_report 19:28:39 INFO - PROCESS | 576 | _x_x_mozilla_cycle_collect,1007 19:28:39 INFO - PROCESS | 576 | __end_cc_report 19:28:39 INFO - PROCESS | 576 | __startTimestamp1467944919073__endTimestamp 19:28:39 INFO - PROCESS | 576 | 19:28:39 INFO - PROCESS | 576 | ------- Summary: start ------- 19:28:39 INFO - PROCESS | 576 | Number of tests: 6 19:28:39 INFO - PROCESS | 576 | 19:28:39 INFO - PROCESS | 576 | [#0] dojo.html Cycles:165 Average:4671.25 Median:4824.52 stddev:500.53 (10.4%) stddev-sans-first:474.61 19:28:39 INFO - PROCESS | 576 | Values: 2586.9 2587.0 2616.9 2625.6 2633.3 4574.8 4674.1 4711.0 4731.1 4749.4 4654.6 4662.6 4677.6 4700.9 4736.6 4101.7 4221.5 4338.3 4346.8 4508.5 4402.6 4442.6 4448.2 4458.6 4498.0 4316.8 4326.1 4361.2 4366.9 4370.9 4279.6 4283.8 4295.7 4389.7 4434.5 4302.5 4317.2 4324.5 4367.8 4371.1 4606.1 4650.3 4658.3 4732.7 4735.4 5042.4 5055.3 5056.2 5057.3 5117.9 5124.4 5129.2 5136.0 5143.4 5146.0 4627.6 4671.3 4721.1 4726.7 4750.0 5164.5 5183.2 5238.3 5238.7 5240.7 4968.9 4970.3 5016.4 5022.0 5028.2 4941.5 5002.0 5040.0 5062.6 5083.9 4989.9 5006.5 5023.5 5024.8 5072.1 4899.5 4972.0 4992.0 4993.5 5049.8 4199.1 4205.6 4244.6 4247.3 4298.8 3692.5 3702.6 3709.9 3743.4 3749.3 4376.3 4377.9 4384.3 4387.5 4421.5 4895.0 4946.3 5007.1 5039.5 5056.9 4327.8 4335.0 4339.2 4340.5 4391.9 4401.5 4494.8 4498.6 4498.8 4536.6 4749.5 4819.9 4840.3 4843.1 4850.1 5117.9 5142.4 5148.7 5149.8 5200.9 4626.5 4740.3 4830.8 4842.0 4893.7 4979.5 5004.2 5022.5 5029.2 5067.9 5077.0 5089.7 5089.7 5096.9 5163.5 4803.4 4880.1 4897.7 4956.5 4967.4 4908.4 4938.1 4942.2 4942.4 4980.2 4873.5 4874.4 4882.5 4916.1 4934.3 4824.5 4917.1 4950.2 4961.1 5001.7 4818.9 4822.1 4843.7 4853.9 4888.6 19:28:39 INFO - PROCESS | 576 | 19:28:39 INFO - PROCESS | 576 | [#1] ext.html Cycles:180 Average:48203.85 Median:35183.50 stddev:95839.32 (272.4%) stddev-sans-first:96041.44 19:28:39 INFO - PROCESS | 576 | Values: 568.6 571.9 573.4 575.1 590.5 27682.7 39462.6 40358.6 40425.4 41567.2 37613.6 38106.0 40373.8 40985.0 41186.0 7504.2 7798.0 7934.2 7949.1 7952.3 7487.1 10300.4 10561.7 10567.6 10568.5 9994.3 10041.7 10045.4 10052.1 10061.6 9978.0 10021.3 10042.7 10044.0 10053.1 10158.2 10207.2 10220.6 10229.0 10241.9 38197.8 38444.2 38496.0 38547.2 38560.4 2611.2 2672.2 2851.7 3086.2 3298.3 26380.9 26607.5 27526.4 27793.2 28075.6 3192.6 3220.6 3259.9 3285.8 3313.5 181005.0 181149.0 181430.0 181477.0 181483.0 50934.0 51336.0 51757.2 51815.0 51833.0 54128.0 54242.2 54265.7 54333.0 54381.7 11655.4 12015.0 12039.1 12048.3 12085.0 8153.7 8986.3 9039.4 9099.5 9137.8 14886.6 15161.2 15354.5 15399.6 15441.7 5599.4 5728.4 5738.3 5853.6 5861.0 690.6 695.6 762.8 893.1 918.6 26965.5 27878.3 28041.0 28154.7 28525.1 897.6 901.6 920.2 946.5 967.7 13394.4 13726.7 13963.0 13974.5 14026.6 421924.0 445101.0 455666.0 457354.0 661355.0 49386.5 49667.3 49784.3 49950.3 50054.5 1031.9 1137.3 1179.8 1184.1 1190.6 28040.6 29068.9 29150.9 29400.6 29914.6 40663.2 41000.4 41255.8 41296.8 41354.8 327250.0 327323.0 329457.0 329848.0 330326.0 22586.2 23691.3 24507.9 24697.1 25208.2 40734.6 40964.0 41132.8 41790.6 41830.2 35188.0 35287.6 35379.8 35611.0 36048.8 35460.3 35648.8 36091.6 36254.3 36272.6 34146.5 34990.7 35179.0 35304.8 35429.0 28968.9 29767.4 30139.1 30141.2 30176.2 24739.7 24766.1 24768.1 25130.6 25168.6 19:28:39 INFO - PROCESS | 576 | 19:28:39 INFO - PROCESS | 576 | [#2] jquery.html Cycles:180 Average:24854.39 Median:223326.50 stddev:66785.99 (29.9%) stddev-sans-first:66947.99 19:28:39 INFO - PROCESS | 576 | Values: 348.7 370.3 372.2 372.4 391.5 13214.9 13951.8 15375.6 18246.9 18256.9 17185.2 17227.3 17230.0 17238.4 17426.2 4435.7 4713.1 4728.4 4763.8 4781.9 3501.2 5192.0 5404.5 5410.9 5505.6 5420.6 5450.1 5461.5 5466.8 5467.5 6057.7 6196.7 6226.6 6250.4 6252.7 4629.2 4935.1 5485.2 5500.2 5505.2 12172.3 12192.1 12291.5 12373.8 12390.3 1674.8 1819.5 1850.3 1860.3 2009.2 9232.6 10870.5 12570.9 13263.9 14242.4 1677.2 1687.3 1710.0 1715.6 1733.9 68508.0 73002.0 74377.0 74936.3 75880.6 10049.3 10136.8 10229.3 10450.8 10590.2 13830.7 13915.9 13980.4 14000.1 14012.4 6452.9 6717.1 6737.0 6754.3 6808.8 6099.0 6112.9 6242.6 6281.6 6296.3 4343.5 4415.1 4440.4 4446.4 4506.2 1781.2 1823.2 1827.4 1841.5 1907.3 806.5 850.6 879.5 883.0 901.5 16611.9 16638.1 17347.4 17814.8 17950.4 838.4 844.7 851.1 856.4 895.2 9540.0 9666.4 9777.1 9897.7 10004.7 396581.0 404780.0 406126.0 406199.0 406383.0 59440.7 76581.6 78943.8 86144.6 87094.6 902.0 904.8 921.2 934.3 935.5 12100.7 15554.5 15556.4 16489.5 16568.4 38830.0 39140.8 39527.6 40527.0 41617.0 50378.7 53423.0 53438.2 53449.0 53767.7 7708.3 7738.7 7827.4 8009.6 8028.6 11394.3 11653.9 11876.8 12095.5 12259.6 10386.2 10704.9 10746.6 10869.6 11396.0 10220.5 10704.8 10908.3 10946.3 10974.5 10735.2 10860.5 10988.6 11196.8 11199.2 7591.4 7626.6 7685.9 7765.5 7765.9 9377.3 10748.9 11035.5 11223.3 11420.1 19:28:39 INFO - PROCESS | 576 | 19:28:39 INFO - PROCESS | 576 | [#3] mootools.html Cycles:180 Average:5181.81 Median:4748.05 stddev:2950.37 (62.1%) stddev-sans-first:2944.59 19:28:39 INFO - PROCESS | 576 | Values: 1349.9 1391.8 1443.3 1686.5 1690.7 7593.5 8079.1 8195.0 8197.5 8204.5 6875.9 7027.9 7496.3 7539.2 7942.3 2173.7 2229.1 2236.6 2238.0 2244.5 2166.0 2191.7 2215.4 2216.4 2218.6 1896.9 2177.6 2240.9 2246.8 2258.5 2193.7 2196.8 2204.3 2214.8 2235.6 2225.5 2230.4 2232.3 2233.3 2233.8 7862.7 7954.3 7954.6 7979.0 7981.9 4570.9 4704.2 4704.3 4704.4 4712.4 2803.4 2811.2 2934.8 2944.6 2947.9 880.5 889.1 897.7 908.8 908.9 10644.5 10832.3 10856.8 10859.8 10865.8 4212.4 4290.2 4448.2 4448.8 4452.7 9290.1 9400.9 9403.5 9420.4 9433.2 4712.6 4716.1 4718.3 4719.8 4722.2 4094.1 4200.9 4208.6 4216.5 4217.0 3519.2 3568.6 3600.5 3630.5 3639.7 1034.5 1086.0 1090.7 1097.1 1104.3 567.8 614.8 647.1 654.1 685.7 5078.0 5115.9 5253.5 5315.6 5364.6 435.1 436.6 600.8 825.2 942.9 3447.5 3588.6 3625.4 3649.8 3690.4 9804.2 10019.6 10036.8 10101.7 10110.4 8721.4 9331.5 9650.2 9678.1 10021.6 7479.6 7649.0 7651.6 7653.5 7659.0 5473.7 5595.6 5609.4 5651.8 5720.8 9350.7 9403.5 9453.1 9465.6 9491.9 8833.4 9081.1 9189.7 9417.2 9418.2 6802.0 6969.2 6971.4 6976.1 6981.7 7255.2 7323.9 7361.9 7386.2 7412.6 5651.3 5802.7 6406.0 6510.2 6605.7 6289.7 6367.1 6445.7 6451.0 6516.7 6220.6 6323.9 6325.3 6326.2 6340.5 4556.3 4652.6 4682.3 4696.8 4747.6 4626.0 4659.3 4665.9 4728.4 4748.5 19:28:39 INFO - PROCESS | 576 | 19:28:39 INFO - PROCESS | 576 | [#4] prototype.html Cycles:180 Average:4201.50 Median:4854.17 stddev:1213.35 (25.0%) stddev-sans-first:1180.84 19:28:39 INFO - PROCESS | 576 | Values: 297.4 304.4 306.1 312.0 312.8 4849.9 4871.4 4889.3 4890.3 4900.6 4786.4 4795.6 4864.6 4867.6 4871.7 3633.8 3729.6 3740.9 3781.9 3790.8 3739.8 3775.5 3802.9 3812.1 3817.6 3740.4 3751.7 3778.6 3786.1 3795.9 3594.4 3654.6 3773.1 3778.5 3787.2 3339.6 3345.8 3347.8 3348.1 3365.2 4712.7 4720.2 4720.4 4723.0 4737.6 4200.0 4285.5 4313.3 4314.8 4319.6 4967.6 4980.5 4989.3 4991.2 4992.3 3603.0 3612.0 3617.5 3624.2 3654.5 5127.0 5290.3 5293.8 5296.2 5304.7 3716.1 3720.9 3725.9 3737.1 3745.5 3784.6 3789.8 3792.7 3794.5 3800.2 4267.2 4270.9 4285.7 4286.7 4318.7 4833.2 4962.1 4982.9 5002.4 5025.9 1297.9 1309.8 1310.8 1317.6 1318.5 843.6 860.0 870.6 880.3 903.0 4419.9 4421.1 4429.6 4435.0 4437.8 5084.2 5120.8 5125.2 5140.3 5143.3 4214.0 4385.6 4388.3 4413.6 4423.2 2717.9 2739.4 2767.9 2768.7 2774.9 4858.0 4893.6 4897.5 4897.9 4907.4 5082.5 5306.7 5346.0 5353.4 5379.8 4878.8 4893.8 4905.1 4915.8 4921.2 5285.9 5295.8 5297.7 5298.4 5300.8 4879.0 5108.8 5157.5 5200.5 5207.3 4879.8 4898.8 4900.4 4904.6 4940.8 3691.4 3694.3 3696.4 3699.4 3700.2 5109.7 5226.9 5238.4 5250.5 5262.2 4994.1 5007.0 5020.8 5027.5 5029.6 5020.6 5051.5 5060.0 5068.8 5080.9 4686.7 4990.9 5008.8 5011.6 5017.5 4850.3 4941.7 5094.2 5106.7 5128.7 5008.6 5009.1 5015.4 5017.3 5029.0 19:28:39 INFO - PROCESS | 576 | 19:28:39 INFO - PROCESS | 576 | [#5] yui.html Cycles:180 Average:16462.72 Median:45193.77 stddev:23220.73 (51.4%) stddev-sans-first:23255.08 19:28:39 INFO - PROCESS | 576 | Values: 537.6 574.8 604.8 614.9 621.4 10372.6 10839.5 10885.4 10897.9 10932.5 11216.4 11347.1 11354.7 11358.6 11369.5 606.1 722.2 728.7 741.7 752.0 755.7 758.8 760.9 761.3 761.4 807.5 813.1 813.9 814.4 814.5 634.9 652.0 652.2 656.5 807.8 568.1 581.7 582.4 585.1 585.6 11558.5 11568.1 11588.9 11594.2 11609.4 12721.4 13270.7 13372.8 13386.2 13497.5 11840.8 11945.5 12016.3 12124.6 12352.5 4024.9 4053.1 4130.4 4180.2 4193.3 58577.7 58832.0 59058.1 59335.4 59413.7 9671.4 9794.9 10117.3 10131.4 10330.7 18464.2 18525.9 18548.9 18561.3 18587.5 6455.0 6482.8 6498.3 6522.9 6550.7 3394.7 3471.1 3560.9 3591.0 3596.1 2645.3 2719.3 2726.3 2728.8 2735.1 478.2 479.9 482.6 492.5 494.1 471.8 473.5 476.7 486.4 512.6 6885.7 6997.2 7002.3 7513.4 7666.5 413.9 484.1 485.3 503.6 538.4 3460.9 3482.9 3489.2 3546.0 3627.5 89456.3 95793.5 98310.0 99278.0 99541.0 52805.5 77994.6 78499.2 78502.6 83232.0 43391.6 43608.6 45143.0 56682.7 65511.7 6755.2 6890.3 7557.7 7587.9 8018.2 33913.3 39339.6 40173.2 42828.1 45244.5 60422.7 62367.0 63713.4 64273.4 67355.0 9349.3 9353.3 9364.2 9409.9 9589.7 10366.8 11063.8 11313.3 13085.3 13167.0 12096.1 12167.4 12257.1 12636.3 12716.7 12151.7 12177.3 12326.9 12488.3 12920.3 11946.6 11961.6 12093.5 12560.4 12992.3 10357.8 10530.5 10658.1 10686.5 10838.8 11001.3 11049.2 11109.2 11171.8 11291.8 19:28:39 INFO - PROCESS | 576 | -------- Summary: end -------- 19:28:39 INFO - PROCESS | 576 | 19:28:39 INFO - TEST-INFO | 576: exit 0 19:28:39 INFO - TEST-OK | dromaeo_css | took 1120261ms 19:28:39 INFO - TEST-START | kraken 19:28:39 INFO - operating with platform_type : linux_ 19:28:39 INFO - Initialising browser for kraken test... 19:28:39 INFO - TEST-INFO | started process 776 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp5ic7lZ/profile http://localhost:36817/getInfo.html) 19:28:44 INFO - PROCESS | 776 | __metrics Screen width/height:1600/1200 19:28:44 INFO - PROCESS | 776 | colorDepth:24 19:28:44 INFO - PROCESS | 776 | Browser inner width/height: 1024/697 19:28:44 INFO - PROCESS | 776 | __metrics 19:28:45 INFO - PROCESS | 776 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:28:46 INFO - TEST-INFO | 776: exit 0 19:28:46 INFO - Browser initialized. 19:28:46 INFO - Running cycle 1/1 for kraken test... 19:28:46 INFO - TEST-INFO | started process 865 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp5ic7lZ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:28:58 INFO - PROCESS | 865 | RSS: Main: 159293440 19:28:58 INFO - PROCESS | 865 | 19:29:00 INFO - PROCESS | 865 | Cycle 1(1): loaded http://localhost:36817/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:36817/tests/kraken/driver.html?testName=audio-beat-detection) 19:29:01 INFO - PROCESS | 865 | RSS: Main: 216842240 19:29:01 INFO - PROCESS | 865 | 19:29:04 INFO - PROCESS | 865 | Cycle 1(1): loaded http://localhost:36817/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:36817/tests/kraken/driver.html?testName=audio-dft) 19:29:05 INFO - PROCESS | 865 | RSS: Main: 203669504 19:29:05 INFO - PROCESS | 865 | 19:29:08 INFO - PROCESS | 865 | Cycle 1(1): loaded http://localhost:36817/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:36817/tests/kraken/driver.html?testName=audio-fft) 19:29:09 INFO - PROCESS | 865 | RSS: Main: 271204352 19:29:09 INFO - PROCESS | 865 | 19:29:11 INFO - PROCESS | 865 | Cycle 1(1): loaded http://localhost:36817/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:36817/tests/kraken/driver.html?testName=audio-oscillator) 19:29:11 INFO - PROCESS | 865 | RSS: Main: 181731328 19:29:11 INFO - PROCESS | 865 | 19:29:13 INFO - PROCESS | 865 | Cycle 1(1): loaded http://localhost:36817/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:36817/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:29:14 INFO - PROCESS | 865 | RSS: Main: 184471552 19:29:14 INFO - PROCESS | 865 | 19:29:17 INFO - PROCESS | 865 | Cycle 1(1): loaded http://localhost:36817/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:36817/tests/kraken/driver.html?testName=imaging-darkroom) 19:29:18 INFO - PROCESS | 865 | RSS: Main: 221855744 19:29:18 INFO - PROCESS | 865 | 19:29:21 INFO - PROCESS | 865 | Cycle 1(1): loaded http://localhost:36817/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:36817/tests/kraken/driver.html?testName=imaging-desaturate) 19:29:22 INFO - PROCESS | 865 | RSS: Main: 217329664 19:29:22 INFO - PROCESS | 865 | 19:29:25 INFO - PROCESS | 865 | Cycle 1(1): loaded http://localhost:36817/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:36817/tests/kraken/driver.html?testName=json-parse-financial) 19:29:25 INFO - PROCESS | 865 | RSS: Main: 220581888 19:29:25 INFO - PROCESS | 865 | 19:29:27 INFO - PROCESS | 865 | Cycle 1(1): loaded http://localhost:36817/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:36817/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:29:28 INFO - PROCESS | 865 | RSS: Main: 206196736 19:29:28 INFO - PROCESS | 865 | 19:29:30 INFO - PROCESS | 865 | Cycle 1(1): loaded http://localhost:36817/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:36817/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:29:30 INFO - PROCESS | 865 | RSS: Main: 211685376 19:29:30 INFO - PROCESS | 865 | 19:29:32 INFO - PROCESS | 865 | Cycle 1(1): loaded http://localhost:36817/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:36817/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:29:33 INFO - PROCESS | 865 | RSS: Main: 211963904 19:29:33 INFO - PROCESS | 865 | 19:29:36 INFO - PROCESS | 865 | Cycle 1(1): loaded http://localhost:36817/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:36817/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:29:36 INFO - PROCESS | 865 | RSS: Main: 225533952 19:29:36 INFO - PROCESS | 865 | 19:29:39 INFO - PROCESS | 865 | Cycle 1(1): loaded http://localhost:36817/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:36817/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:29:40 INFO - PROCESS | 865 | RSS: Main: 225292288 19:29:40 INFO - PROCESS | 865 | 19:29:42 INFO - PROCESS | 865 | Cycle 1(1): loaded http://localhost:36817/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:36817/tests/kraken/driver.html?testName=ai-astar) 19:29:42 INFO - PROCESS | 865 | RSS: Main: 268414976 19:29:42 INFO - PROCESS | 865 | 19:29:42 INFO - PROCESS | 865 | __start_tp_report 19:29:42 INFO - PROCESS | 865 | _x_x_mozilla_page_load 19:29:42 INFO - PROCESS | 865 | _x_x_mozilla_page_load_details 19:29:42 INFO - PROCESS | 865 | |i|pagename|runs| 19:29:42 INFO - PROCESS | 865 | |0;ai-astar;104;112;113;114;113;114;112;112;112;114 19:29:42 INFO - PROCESS | 865 | |1;audio-beat-detection;161;127;123;179;127;124;149;121;175;128 19:29:42 INFO - PROCESS | 865 | |2;audio-dft;160;161;162;161;162;161;161;161;161;163 19:29:42 INFO - PROCESS | 865 | |3;audio-fft;76;75;76;112;77;78;75;75;98;76 19:29:42 INFO - PROCESS | 865 | |4;audio-oscillator;84;85;112;86;86;85;117;85;85;128 19:29:42 INFO - PROCESS | 865 | |5;imaging-gaussian-blur;117;117;117;116;116;117;117;117;117;117 19:29:42 INFO - PROCESS | 865 | |6;imaging-darkroom;145;147;146;146;147;145;146;146;146;145 19:29:42 INFO - PROCESS | 865 | |7;imaging-desaturate;94;95;94;94;95;96;95;96;95;95 19:29:42 INFO - PROCESS | 865 | |8;json-parse-financial;62;62;62;62;62;62;61;62;61;62 19:29:42 INFO - PROCESS | 865 | |9;json-stringify-tinderbox;63;65;64;65;66;65;64;64;64;65 19:29:42 INFO - PROCESS | 865 | |10;stanford-crypto-aes;79;78;82;84;78;79;83;80;79;89 19:29:42 INFO - PROCESS | 865 | |11;stanford-crypto-ccm;130;126;127;140;140;128;138;139;214;128 19:29:42 INFO - PROCESS | 865 | |12;stanford-crypto-pbkdf2;163;164;161;162;164;162;161;162;163;161 19:29:42 INFO - PROCESS | 865 | |13;stanford-crypto-sha256-iterative;67;70;68;66;66;65;69;68;70;64 19:29:42 INFO - PROCESS | 865 | __end_tp_report 19:29:42 INFO - PROCESS | 865 | __start_cc_report 19:29:42 INFO - PROCESS | 865 | _x_x_mozilla_cycle_collect,1871 19:29:42 INFO - PROCESS | 865 | __end_cc_report 19:29:42 INFO - PROCESS | 865 | __startTimestamp1467944982399__endTimestamp 19:29:42 INFO - PROCESS | 865 | 19:29:42 INFO - PROCESS | 865 | ------- Summary: start ------- 19:29:42 INFO - PROCESS | 865 | Number of tests: 14 19:29:42 INFO - PROCESS | 865 | 19:29:42 INFO - PROCESS | 865 | [#0] ai-astar Cycles:10 Average:112.00 Median:113.00 stddev:2.94 (2.6%) stddev-sans-first:0.93 19:29:42 INFO - PROCESS | 865 | Values: 104.0 112.0 113.0 114.0 113.0 114.0 112.0 112.0 112.0 114.0 19:29:42 INFO - PROCESS | 865 | 19:29:42 INFO - PROCESS | 865 | [#1] audio-beat-detection Cycles:10 Average:141.40 Median:138.50 stddev:22.70 (16.4%) stddev-sans-first:22.94 19:29:42 INFO - PROCESS | 865 | Values: 161.0 127.0 123.0 179.0 127.0 124.0 149.0 121.0 175.0 128.0 19:29:42 INFO - PROCESS | 865 | 19:29:42 INFO - PROCESS | 865 | [#2] audio-dft Cycles:10 Average:161.30 Median:161.00 stddev:0.82 (0.5%) stddev-sans-first:0.73 19:29:42 INFO - PROCESS | 865 | Values: 160.0 161.0 162.0 161.0 162.0 161.0 161.0 161.0 161.0 163.0 19:29:42 INFO - PROCESS | 865 | 19:29:42 INFO - PROCESS | 865 | [#3] audio-fft Cycles:10 Average:81.80 Median:76.00 stddev:12.70 (16.7%) stddev-sans-first:13.30 19:29:42 INFO - PROCESS | 865 | Values: 76.0 75.0 76.0 112.0 77.0 78.0 75.0 75.0 98.0 76.0 19:29:42 INFO - PROCESS | 865 | 19:29:42 INFO - PROCESS | 865 | [#4] audio-oscillator Cycles:10 Average:95.30 Median:85.00 stddev:16.81 (19.8%) stddev-sans-first:17.33 19:29:42 INFO - PROCESS | 865 | Values: 84.0 85.0 112.0 86.0 86.0 85.0 117.0 85.0 85.0 128.0 19:29:42 INFO - PROCESS | 865 | 19:29:42 INFO - PROCESS | 865 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 19:29:42 INFO - PROCESS | 865 | Values: 117.0 117.0 117.0 116.0 116.0 117.0 117.0 117.0 117.0 117.0 19:29:42 INFO - PROCESS | 865 | 19:29:42 INFO - PROCESS | 865 | [#6] imaging-darkroom Cycles:10 Average:145.90 Median:146.00 stddev:0.74 (0.5%) stddev-sans-first:0.71 19:29:42 INFO - PROCESS | 865 | Values: 145.0 147.0 146.0 146.0 147.0 145.0 146.0 146.0 146.0 145.0 19:29:42 INFO - PROCESS | 865 | 19:29:42 INFO - PROCESS | 865 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.74 (0.8%) stddev-sans-first:0.71 19:29:42 INFO - PROCESS | 865 | Values: 94.0 95.0 94.0 94.0 95.0 96.0 95.0 96.0 95.0 95.0 19:29:42 INFO - PROCESS | 865 | 19:29:42 INFO - PROCESS | 865 | [#8] json-parse-financial Cycles:10 Average:61.80 Median:62.00 stddev:0.42 (0.7%) stddev-sans-first:0.44 19:29:42 INFO - PROCESS | 865 | Values: 62.0 62.0 62.0 62.0 62.0 62.0 61.0 62.0 61.0 62.0 19:29:42 INFO - PROCESS | 865 | 19:29:42 INFO - PROCESS | 865 | [#9] json-stringify-tinderbox Cycles:10 Average:64.50 Median:65.00 stddev:0.85 (1.3%) stddev-sans-first:0.71 19:29:42 INFO - PROCESS | 865 | Values: 63.0 65.0 64.0 65.0 66.0 65.0 64.0 64.0 64.0 65.0 19:29:42 INFO - PROCESS | 865 | 19:29:42 INFO - PROCESS | 865 | [#10] stanford-crypto-aes Cycles:10 Average:81.10 Median:81.00 stddev:3.48 (4.3%) stddev-sans-first:3.61 19:29:42 INFO - PROCESS | 865 | Values: 79.0 78.0 82.0 84.0 78.0 79.0 83.0 80.0 79.0 89.0 19:29:42 INFO - PROCESS | 865 | 19:29:42 INFO - PROCESS | 865 | [#11] stanford-crypto-ccm Cycles:10 Average:141.00 Median:138.50 stddev:26.30 (19.0%) stddev-sans-first:27.59 19:29:42 INFO - PROCESS | 865 | Values: 130.0 126.0 127.0 140.0 140.0 128.0 138.0 139.0 214.0 128.0 19:29:42 INFO - PROCESS | 865 | 19:29:42 INFO - PROCESS | 865 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:162.30 Median:162.50 stddev:1.16 (0.7%) stddev-sans-first:1.20 19:29:42 INFO - PROCESS | 865 | Values: 163.0 164.0 161.0 162.0 164.0 162.0 161.0 162.0 163.0 161.0 19:29:42 INFO - PROCESS | 865 | 19:29:42 INFO - PROCESS | 865 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.30 Median:68.00 stddev:2.06 (3.0%) stddev-sans-first:2.18 19:29:42 INFO - PROCESS | 865 | Values: 67.0 70.0 68.0 66.0 66.0 65.0 69.0 68.0 70.0 64.0 19:29:42 INFO - PROCESS | 865 | -------- Summary: end -------- 19:29:42 INFO - PROCESS | 865 | 19:29:43 INFO - TEST-INFO | 865: exit 0 19:29:43 INFO - TEST-OK | kraken | took 63598ms 19:29:43 INFO - SUITE-END | took 1183s 19:29:43 INFO - Completed test suite (00:19:43) 19:29:43 INFO - javascript benchmark 19:29:43 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2586.909458168964, 2587.0, 2616.8691565421736, 2625.566781481056, 2633.315338012116, 4574.794134263958, 4674.1352849722825, 4711.0, 4731.124741922738, 4749.430068391794, 4654.6276297896175, 4662.626989840813, 4677.555632214942, 4700.929486057703, 4736.573708366246, 4101.651359634435, 4221.451211342521, 4338.327559228324, 4346.78266086696, 4508.549145085498, 4402.647788176938, 4442.644588432918, 4448.221561226788, 4458.598726114649, 4498.0, 4316.82732690692, 4326.069894972565, 4361.214981303364, 4366.912661746747, 4370.912581748379, 4279.593438623326, 4283.764392958386, 4295.699301048928, 4389.736615803062, 4434.5343738907595, 4302.462192225972, 4317.244482215599, 4324.545922678137, 4367.781610919441, 4371.125774845043, 4606.124836281096, 4650.25595904657, 4658.2779669151, 4732.668713190078, 4735.384400028009, 5042.3949126104735, 5055.266986286968, 5056.191009438509, 5057.317262169597, 5117.897642047175, 5124.436312005676, 5129.204973229118, 5136.0, 5143.434222235551, 5146.0, 4627.629789616823, 4671.346011558385, 4721.126591580569, 4726.692764970266, 4750.049990001979, 5164.457731938168, 5183.170692689227, 5238.345206849144, 5238.73806309683, 5240.737963101829, 4968.850934471971, 4970.304157417964, 5016.423111342242, 5022.045811295842, 5028.17035189197, 4941.530554597308, 5002.049610574052, 5040.042391945592, 5062.645614806928, 5083.923741143886, 4989.850304490871, 5006.4743201963265, 5023.52276533736, 5024.84925452237, 5072.061668591323, 4899.534544218293, 4972.0, 4991.950080499221, 4993.450720420751, 5049.772760225798, 4199.0552125771455, 4205.621494065487, 4244.639205667553, 4247.277962746341, 4298.849540266076, 3692.538432695913, 3702.611225821304, 3709.851605935786, 3743.401055831054, 3749.3251214781058, 4376.321670141133, 4377.934330985037, 4384.342348647665, 4387.495438024668, 4421.53573874745, 4894.975525122423, 4946.282788995575, 5007.12375334315, 5039.546440820343, 5056.898862022739, 4327.8485253016, 4335.024619460596, 4339.153864996359, 4340.544242854518, 4391.912161756747, 4401.515833258339, 4494.775261236925, 4498.550144985541, 4498.820047198075, 4536.591706746409, 4749.5487928646035, 4819.855404337875, 4840.322354870392, 4843.055604157155, 4850.102730994778, 5117.948820511747, 5142.408623008402, 5148.665336753099, 5149.819756308511, 5200.895982080261, 4626.467956185082, 4740.288956656529, 4830.7826147822725, 4841.951580484291, 4893.73084480357, 4979.502049794992, 5004.224345226496, 5022.4977502249485, 5029.245613158056, 5067.898642027212, 5077.0, 5089.694618322913, 5089.720065396439, 5096.872578185575, 5163.483651634807, 4803.351547541177, 4880.1215781159735, 4897.681650692693, 4956.454789973028, 4967.379077615298, 4908.361912951294, 4938.111139994836, 4942.233953737176, 4942.406911170682, 4980.153373926369, 4873.51264873524, 4874.390701162355, 4882.487338829372, 4916.139675556692, 4934.309196712535, 4824.517548245148, 4917.1149193145575, 4950.183219768698, 4961.082199792905, 5001.724905130252, 4818.927716084191, 4822.14897298709, 4843.709377437365, 4853.854384368475, 4888.60891128716], "value": 4638.526362612654, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [568.5792513539982, 571.8570357410647, 573.3836126164374, 575.0856138739404, 590.530528230057, 27682.723172768292, 39462.60537394625, 40358.59641403585, 40425.3936190957, 41567.16865662688, 37613.62386376136, 38106.047348816224, 40373.7981310093, 40985.0, 41185.97035074125, 7504.24957504248, 7797.999999999985, 7934.246246606592, 7949.0858551266765, 7952.284294413502, 7487.064116985377, 10300.381977081362, 10561.683149505488, 10567.630132945349, 10568.524416401242, 9994.250431217652, 10041.698749037541, 10045.447500387476, 10052.14556762676, 10061.64784232552, 9978.0, 10021.348612340247, 10042.748931276776, 10044.045815647502, 10053.095221430107, 10158.187345012455, 10207.183425325957, 10220.591176352937, 10229.0, 10241.948790256001, 38197.80901095505, 38444.231115377814, 38496.0, 38547.22905541902, 38560.42159367612, 2611.246457515055, 2672.2116975492277, 2851.7148285171315, 3086.24387025178, 3298.307355455357, 26380.8680956594, 26607.46785064288, 27526.449471010666, 27793.16620501388, 28075.57886631702, 3192.576238581035, 3220.6135263768497, 3259.9022029339158, 3285.8357082146035, 3313.519539666759, 181005.0, 181149.0, 181430.0, 181476.9999999987, 181483.0184339861, 50934.0, 51336.0, 51757.2236416454, 51815.0, 51833.0, 54128.0, 54242.186367203736, 54265.72867135639, 54333.0, 54381.728091360084, 11655.358955257543, 12015.0, 12039.097067719957, 12048.277103373826, 12085.0, 8153.673853045931, 8986.281097512183, 9039.412438191497, 9099.499527525917, 9137.77155571116, 14886.553403397915, 15161.166135862633, 15354.539363819104, 15399.615009624626, 15441.691166176613, 5599.440055994368, 5728.398518155617, 5738.340090889601, 5853.590248682552, 5861.0, 690.6378010409115, 695.5861262548775, 762.8169239382619, 893.1202765276198, 918.609590923868, 26965.460690786076, 27878.3030424241, 28041.0, 28154.718452815618, 28525.14424567266, 897.5601955042113, 901.5672477210976, 920.2316066084894, 946.4983558713893, 967.7387105481621, 13394.397252123677, 13726.725465490834, 13963.0, 13974.510892118727, 14026.649333766738, 421924.0, 445101.0, 455666.0, 457354.0, 661355.0, 49386.50613493891, 49667.25499117516, 49784.25323620148, 49950.25074623884, 50054.49945500498, 1031.8761748590218, 1137.3062431916524, 1179.8407215026034, 1184.088252045937, 1190.6309044196328, 28040.579391309555, 29068.854655726587, 29150.854245728635, 29400.55899161557, 29914.551281730357, 40663.1867362657, 41000.38499422571, 41255.7937210312, 41296.79351603343, 41354.793226033675, 327250.0, 327323.0, 329457.0, 329848.0, 330326.0, 22586.20948266803, 23691.28926132219, 24507.877460612584, 24697.135600253903, 25208.24375268745, 40734.592654074266, 40964.0, 41132.79433602933, 41790.582094178666, 41830.1633967325, 35188.0, 35287.64712352843, 35379.82310088536, 35611.0, 36048.81975590105, 35460.29079418449, 35648.821755892095, 36091.639083608825, 36254.27491450103, 36272.63727362798, 34146.48780268348, 34990.65009349874, 35179.0, 35304.823475882455, 35429.0, 28968.855155724086, 29767.404651906407, 30139.09582712522, 30141.246468838453, 30176.24559385945, 24739.659852087807, 24766.133185339146, 24768.133115340876, 25130.62304065477, 25168.622470662587], "value": 17248.518477939804, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [348.6652813299233, 370.26502392750405, 372.1514945923295, 372.3706935279378, 391.48910671573583, 13214.933925330371, 13951.79072313915, 15375.61560960978, 18246.908765456188, 18256.908715456404, 17185.226664800084, 17227.310907563682, 17230.0, 17238.39665611704, 17426.215820288086, 4435.7116787408795, 4713.080949214905, 4728.385309909707, 4763.833265835697, 4781.90436191276, 3501.247231845153, 5191.974040129813, 5404.513593776559, 5410.8917821643545, 5505.587080968923, 5420.620556561043, 5450.127979523277, 5461.481159289861, 5466.753996070186, 5467.480589344006, 6057.697115144248, 6196.721147548371, 6226.564140510166, 6250.374962503736, 6252.71862766172, 4629.23617603095, 4935.087008903364, 5485.204645326446, 5500.202470641774, 5505.174223866412, 12172.2696638202, 12192.085593580518, 12291.508339666403, 12373.814392784116, 12390.31853248071, 1674.8492635662726, 1819.4814477873742, 1850.3061351993003, 1860.255897640941, 2009.2465325502938, 9232.6306947722, 10870.456477176109, 12570.874291257061, 13263.86736132636, 14242.430302787876, 1677.1614192903548, 1687.333503266203, 1709.9658006839918, 1715.5882588178747, 1733.8699597530235, 68508.0, 73002.0, 74377.0, 74936.25063749401, 75880.62059689667, 10049.29654924163, 10136.847947280796, 10229.335093218917, 10450.790984180274, 10590.205734569938, 13830.65423364424, 13915.860841391659, 13980.44078236859, 14000.089994150349, 14012.369443375073, 6452.935470645233, 6717.093192419059, 6737.0, 6754.257031726506, 6808.795736127924, 6099.0, 6112.908306375408, 6242.563020588515, 6281.560290779693, 6296.307406185316, 4343.457067866517, 4415.094905544356, 4440.35614835847, 4446.399736035593, 4506.211413002711, 1781.1628534588722, 1823.186786882631, 1827.3969590035256, 1841.4935892629635, 1907.3419670214032, 806.5160903457995, 850.6384786465727, 879.4503435352905, 882.969096081634, 901.4906577783531, 16611.91694041522, 16638.08490533008, 17347.392841250497, 17814.821851781577, 17950.371736989404, 838.4256784102909, 844.6705784743832, 851.0765819086361, 856.4261944497201, 895.2435192262528, 9539.952300238594, 9666.420014799134, 9777.120059194707, 9897.716603072504, 10004.749881253027, 396581.0, 404780.0, 406126.0, 406199.0, 406383.0, 59440.70279648574, 76581.61709191419, 78943.8158427624, 86144.56927715322, 87094.56452717696, 902.0393281155548, 904.8054668246359, 921.1525396634978, 934.2992755433424, 935.4995077633433, 12100.697482562655, 15554.533363999099, 15556.444355564805, 16489.505314840575, 16568.420105296256, 38830.0, 39140.804295978334, 39527.60472395239, 40527.0, 41616.95957601084, 50378.748106259234, 53423.0, 53438.19842702441, 53449.0, 53767.731161343945, 7708.344790692854, 7738.729144479917, 7827.373810095292, 8009.599520024092, 8028.59857007159, 11394.316341019565, 11653.88346116528, 11876.812318768054, 12095.455704493143, 12259.632211033684, 10386.221033422373, 10704.946475267574, 10746.570137194425, 10869.565217391531, 11396.0, 10220.540075696761, 10704.839427408438, 10908.290961087503, 10946.343219406861, 10974.451277436256, 10735.248532602644, 10860.511276992396, 10988.560457581927, 11196.83204751913, 11199.160062995144, 7591.367855911006, 7626.6186690664135, 7685.923140768521, 7765.495242809199, 7765.922340776746, 9377.343585948918, 10748.89251107479, 11035.503402347074, 11223.270487418291, 11420.143489238175], "value": 8202.111840662968, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1349.8582648821873, 1391.8190635217422, 1443.2927865345978, 1686.477192070459, 1690.7210310298801, 7593.506422082568, 8079.111297757242, 8194.959025204873, 8197.549134797599, 8204.548749818758, 6875.9312406875915, 7027.859442811154, 7496.250374962501, 7539.208383119776, 7942.285194332509, 2173.739151301842, 2229.1306390507716, 2236.5974124657555, 2238.0, 2244.517428752818, 2165.9891700541475, 2191.7041199438113, 2215.4129155773735, 2216.445888527868, 2218.589560931233, 1896.9051547422644, 2177.586258610867, 2240.8543444676043, 2246.809021233197, 2258.4918393361527, 2193.747719012318, 2196.7803219678062, 2204.3386983904766, 2214.7895949884737, 2235.642297232435, 2225.5215128747236, 2230.3643461613524, 2232.3079845248026, 2233.2630232023557, 2233.7766223377694, 7862.68549258029, 7954.323882469997, 7954.562499062549, 7978.960105199495, 7981.880271795928, 4570.931436028429, 4704.200285951362, 4704.317873908264, 4704.435467743892, 4712.387389639325, 2803.43931213756, 2811.1566530040905, 2934.838583877886, 2944.573036909636, 2947.911562653124, 880.471716969813, 889.0709208876739, 897.7396554999042, 908.7546362482159, 908.9363744537885, 10644.467776611138, 10832.295900766425, 10856.837147442795, 10859.78280434387, 10865.782684346268, 4212.389203565466, 4290.184864875664, 4448.199324121689, 4448.777561121931, 4452.732836029849, 9290.117438843434, 9400.858987115198, 9403.529823508798, 9420.434773913586, 9433.151016408556, 4712.64655150865, 4716.127516409475, 4718.31584420264, 4719.787609557578, 4722.244440889442, 4094.119764250641, 4200.894977625584, 4208.600182982612, 4216.51510076339, 4217.0, 3519.2433626769857, 3568.57177138745, 3600.5499312585926, 3630.4554316852154, 3639.7088232941833, 1034.4775888176423, 1085.9728506787235, 1090.7491277006334, 1097.0948967102172, 1104.2711810205226, 567.8041075828833, 614.7817524778728, 647.058529839083, 654.1202083198091, 685.674304705261, 5077.974610126926, 5115.897682046339, 5253.50091741284, 5315.6013299002725, 5364.624476286701, 435.1427687455707, 436.55471419152286, 600.7807150390164, 825.1748251748252, 942.8585712143097, 3447.500112483676, 3588.6231945645854, 3625.3836847735947, 3649.7627654202392, 3690.372636651776, 9804.215662747105, 10019.649312274036, 10036.799264014824, 10101.747456313651, 10110.39337639744, 8721.389502734875, 9331.486768227676, 9650.227981761442, 9678.128968392737, 10021.649242276631, 7479.626018699152, 7649.0, 7651.579163146081, 7653.4642575020325, 7658.961705191438, 5473.67157970523, 5595.636283641713, 5609.382967873449, 5651.8304450866535, 5720.799772007959, 9350.67272645454, 9403.4828084456, 9453.054694530767, 9465.574049167666, 9491.905080949102, 8833.381663283508, 9081.091890810865, 9189.678361257324, 9417.246620270225, 9418.152366287068, 6802.0, 6969.1985421677155, 6971.372576468142, 6976.127984002, 6981.650917454005, 7255.2019277880445, 7323.890141647773, 7361.926380736339, 7386.224446433047, 7412.592307423143, 5651.265335506522, 5802.65184088956, 6405.967970160119, 6510.18622672166, 6605.702743376656, 6289.685515724287, 6367.108604795425, 6445.677716114269, 6451.0, 6516.674166291761, 6220.564560480723, 6323.936760632335, 6325.304216536086, 6326.2092238470195, 6340.524460665376, 4556.2937744649635, 4652.581267685924, 4682.2508398656055, 4696.8121275148615, 4747.643926705442, 4626.0, 4659.347691323285, 4665.883352916204, 4728.38530990969, 4748.453927798209], "value": 4102.593478165876, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [297.40518962075845, 304.41096478314455, 306.1153267058202, 311.97192252697266, 312.82950791818456, 4849.884526558889, 4871.415430148383, 4889.291052797343, 4890.290907818364, 4900.632452566054, 4786.401699787527, 4795.640326975482, 4864.610831133509, 4867.586255168315, 4871.683340582868, 3633.836477358512, 3729.552453705559, 3740.8503659853573, 3781.9221521866275, 3790.848366065354, 3739.756915800478, 3775.452559378902, 3802.8668996585125, 3812.0850995761093, 3817.5800661927306, 3740.382836831933, 3751.737378383514, 3778.5843557208686, 3786.053486628343, 3795.8802153364686, 3594.3530164570366, 3654.5614526256754, 3773.13217959868, 3778.527684039495, 3787.1857550626746, 3339.632640409553, 3345.8327083645963, 3347.815870127142, 3348.1294863335465, 3365.2259980204444, 4712.693674911154, 4720.197566413718, 4720.362751028601, 4723.039938425549, 4737.644676649265, 4199.997002143482, 4285.528591854893, 4313.309870420749, 4314.80583373749, 4319.632831209351, 4967.55292023716, 4980.452150263468, 4989.276554899519, 4991.226359914219, 4992.276119962621, 3602.963970360315, 3611.9638803611892, 3617.5116359291687, 3624.1845584743487, 3654.524911761454, 5126.974365128188, 5290.285811415448, 5293.841184764425, 5296.232046353257, 5304.681719096867, 3716.1267102230954, 3720.925581488355, 3725.869594564177, 3737.0844143185095, 3745.4943582616543, 3784.5647750509042, 3789.8484060637816, 3792.7345085843726, 3794.5446546414605, 3800.220954704279, 4267.1892340455215, 4270.935935960963, 4285.742855428684, 4286.699931004862, 4318.654507639381, 4833.17259403246, 4962.057209130254, 4982.9252561211615, 5002.424721157041, 5025.899482010339, 1297.8838199148913, 1309.8297221361163, 1310.842698876141, 1317.571789168505, 1318.5055604148445, 843.5866425451485, 859.9613017414231, 870.6430363550913, 880.2561835249204, 902.9954850225838, 4419.867403977886, 4421.115776844644, 4429.645628349725, 4435.0, 4437.7781110944325, 5084.161113416319, 5120.820771272987, 5125.1799712045895, 5140.306058682105, 5143.331366922276, 4213.97893010533, 4385.561443855589, 4388.27593447084, 4413.602775750133, 4423.248047831877, 2717.904873329424, 2739.356251280947, 2767.9169624910883, 2768.695443501213, 2774.890043982383, 4858.0, 4893.632977526629, 4897.534734200317, 4897.926531102036, 4907.435644900811, 5082.517160869711, 5306.6550674206055, 5345.973270133624, 5353.411124776271, 5379.825902717337, 4878.780454879468, 4893.797790994067, 4905.092557876804, 4915.820203151289, 4921.188003979375, 5285.912064734485, 5295.814646487354, 5297.682139071668, 5298.3906850712665, 5300.787968481218, 4878.999999999858, 5108.846734597968, 5157.535821776059, 5200.503758196011, 5207.29701490294, 4879.829205977774, 4898.804047838046, 4900.387451568554, 4904.558589726942, 4940.802367905244, 3691.427828686558, 3694.2980833641523, 3696.4270538066644, 3699.389600715959, 3700.1674623209565, 5109.718965456936, 5226.947730522798, 5238.449962753856, 5250.527452529291, 5262.158244614355, 4994.101061808909, 5006.974965125297, 5020.849374518771, 5027.497250274943, 5029.572486338702, 5020.648554601143, 5051.469595692546, 5059.974700126476, 5068.822591209438, 5080.872978175576, 4686.742229177292, 4990.925136122888, 5008.77460514285, 5011.624128190328, 5017.4731653175895, 4850.345203397496, 4941.65408421407, 5094.2103973884105, 5106.693598384109, 5128.666636668605, 5008.649394542347, 5009.123403404463, 5015.423226329019, 5017.3226614406585, 5029.044481548494], "value": 3819.125946274363, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [537.6102325813785, 574.7787101965743, 604.7913469852899, 614.8616561273711, 621.3693101501976, 10372.585096596136, 10839.458027098643, 10885.401302928356, 10897.945510272437, 10932.508037138328, 11216.383098929555, 11347.092232621391, 11354.716132096702, 11358.60244891429, 11369.488373023212, 606.1362558354349, 722.2236096196583, 728.6538894025349, 741.7478057460462, 751.9849603007937, 755.6599530211399, 758.7723682895137, 760.9277118673717, 761.3300295739741, 761.4289283037722, 807.5235610989515, 813.0730966697969, 813.9430239883211, 814.4136221920209, 814.5153633588005, 634.9428551430393, 652.0284775684244, 652.2140820311513, 656.4846595422619, 807.7980504873782, 568.0882184094552, 581.6684489840792, 582.4292193650205, 585.0814221671976, 585.5783835638333, 11558.53765849365, 11568.074554035657, 11588.942055289754, 11594.246373985749, 11609.361485118316, 12721.363931803373, 13270.734585308337, 13372.799408008888, 13386.19682819034, 13497.460101595925, 11840.763184736343, 11945.522179112824, 12016.279023258545, 12124.636260912186, 12352.505899763999, 4024.9195016099516, 4053.148838743867, 4130.359794231868, 4180.205760905449, 4193.308104162809, 58577.707111464595, 58832.0, 59058.11412828768, 59335.40664593342, 59413.702931485495, 9671.371360861522, 9794.853077203848, 10117.342372745748, 10131.442770647538, 10330.741731456774, 18464.169112389976, 18525.90737046333, 18548.907255463906, 18561.25754969786, 18587.535311617317, 6455.0317452382515, 6482.805515834532, 6498.252700939453, 6522.902156467657, 6550.737970481223, 3394.694477496988, 3471.149568355769, 3560.893173204808, 3590.9640903591153, 3596.1009747563107, 2645.2593273883344, 2719.3065768228976, 2726.2775364528416, 2728.7953403494816, 2735.097417852104, 478.2443738892611, 479.89922116355257, 482.642844295219, 492.4902725678905, 494.1426624805959, 471.7853376713587, 473.51701264709806, 476.7235003697847, 486.4357345479227, 512.6261618861413, 6885.724571017103, 6997.195322537871, 7002.271006813077, 7513.361364284097, 7666.540007599562, 413.8716997730713, 484.12373603777274, 485.33266758207463, 503.58957449678394, 538.4373329870295, 3460.8615655374006, 3482.8780992665133, 3489.2149266414854, 3546.0248431681493, 3627.4558816177782, 89456.3003105159, 95793.52103239439, 98310.0, 99278.0, 99541.0, 52805.47194528082, 77994.61002694951, 78499.21500785033, 78502.6074869622, 83232.0, 43391.56608433875, 43608.56391436108, 45143.0, 56682.716586416806, 65511.67244163844, 6755.189377274758, 6890.345417185359, 7557.659905304385, 7587.924120758943, 8018.238267364591, 33913.32173356568, 39339.60660393359, 40173.19653606853, 42828.14343713171, 45244.54755452403, 60422.69788651029, 62367.0, 63713.36286637074, 64273.357266426734, 67355.0, 9349.252059835333, 9353.298502612468, 9364.157225849705, 9409.85885211736, 9589.712308630753, 10366.792664146826, 11063.834042489209, 11313.264637798517, 13085.280309583064, 13167.0, 12096.092793040732, 12167.39163041862, 12257.142000060267, 12636.30500322491, 12716.74566508646, 12151.696207594527, 12177.269363838199, 12326.938365308475, 12488.25070495773, 12920.28938408359, 11946.581869634521, 11961.581344652895, 12093.455794489446, 12560.434780435085, 12992.285424301375, 10357.844632330673, 10530.526126324487, 10658.147348211969, 10686.519106640375, 10838.783224335311, 11001.339919604849, 11049.17131215146, 11109.166812488935, 11171.776564468828, 11291.830622540832], "value": 6193.746235537764, "unit": "score"}], "name": "dromaeo_css", "value": 6319.466465399565, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 112.0, 113.0, 114.0, 113.0, 114.0, 112.0, 112.0, 112.0, 114.0], "value": 112.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 127.0, 123.0, 179.0, 127.0, 124.0, 149.0, 121.0, 175.0, 128.0], "value": 141.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 161.0, 162.0, 161.0, 162.0, 161.0, 161.0, 161.0, 161.0, 163.0], "value": 161.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 76.0, 112.0, 77.0, 78.0, 75.0, 75.0, 98.0, 76.0], "value": 81.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 112.0, 86.0, 86.0, 85.0, 117.0, 85.0, 85.0, 128.0], "value": 95.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 116.0, 116.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 147.0, 146.0, 146.0, 147.0, 145.0, 146.0, 146.0, 146.0, 145.0], "value": 145.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 94.0, 94.0, 95.0, 96.0, 95.0, 96.0, 95.0, 95.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 62.0, 62.0, 62.0, 62.0, 62.0, 61.0, 62.0, 61.0, 62.0], "value": 61.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 65.0, 64.0, 65.0, 66.0, 65.0, 64.0, 64.0, 64.0, 65.0], "value": 64.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 78.0, 82.0, 84.0, 78.0, 79.0, 83.0, 80.0, 79.0, 89.0], "value": 81.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [130.0, 126.0, 127.0, 140.0, 140.0, 128.0, 138.0, 139.0, 214.0, 128.0], "value": 141.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 164.0, 161.0, 162.0, 164.0, 162.0, 161.0, 162.0, 163.0, 161.0], "value": 162.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 70.0, 68.0, 66.0, 66.0, 65.0, 69.0, 68.0, 70.0, 64.0], "value": 67.3, "unit": "score"}], "name": "kraken", "value": 1527.3999999999996, "alertThreshold": 2.0}]} 19:29:43 INFO - Return code: 0 19:29:43 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:29:43 INFO - # TBPL SUCCESS # 19:29:43 INFO - Running post-action listener: _resource_record_post_action 19:29:43 INFO - ##### 19:29:43 INFO - ##### Finished run-tests step (success) 19:29:43 INFO - ##### 19:29:43 INFO - Running post-run listener: _resource_record_post_run 19:29:43 INFO - Total resource usage - Wall time: 1200s; CPU: 13.0%; Read bytes: 4096; Write bytes: 535953408; Read time: 16; Write time: 403316 19:29:43 INFO - TinderboxPrint: CPU usage
12.9% 19:29:43 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 19:29:43 INFO - TinderboxPrint: I/O write bytes / time
535,953,408 / 403,316 19:29:43 INFO - TinderboxPrint: CPU idle
8,336.6 (87.0%) 19:29:43 INFO - TinderboxPrint: CPU user
1,199.2 (12.5%) 19:29:43 INFO - TinderboxPrint: Swap in / out
0 / 0 19:29:43 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 246345728; Read time: 0; Write time: 224528 19:29:43 INFO - run-tests - Wall time: 1184s; CPU: 13.0%; Read bytes: 0; Write bytes: 289607680; Read time: 0; Write time: 178788 19:29:44 INFO - Running post-run listener: _upload_blobber_files 19:29:44 INFO - Blob upload gear active. 19:29:44 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:29:44 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 19:29:44 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'] 19:29:44 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 19:29:44 INFO - (blobuploader) - INFO - Open directory for files ... 19:29:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 19:29:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:29:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:29:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 19:29:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:29:45 INFO - (blobuploader) - INFO - Done attempting. 19:29:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:29:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:29:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:29:49 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:29:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:29:49 INFO - (blobuploader) - INFO - Done attempting. 19:29:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 19:29:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:29:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:29:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 19:29:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:29:50 INFO - (blobuploader) - INFO - Done attempting. 19:29:50 INFO - (blobuploader) - INFO - Iteration through files over. 19:29:50 INFO - Return code: 0 19:29:50 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:29:50 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:29:50 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/3b680a6f7ac3f9ac70f31c4bccdfb5bccf5e76c5b68634e46423884921c6cadd1a5cb0298798821f1c0bf5828f989ed97377e15bb90355aaa29e4c502f6a03e6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/bd052022dbec7b8687366973555b5438520ceb13a991547289809e58f0ad5ab9cebe6094161a2d6a7e656f518538ebac237201132bb2448b63b846b033d49dcb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/a4b5044cd04f74f9382b295825cf55572c6517790f93e38894c6d3a4189972089178ddd335e8bf2621d555aa11cf93af17f3240d2ec357ed2784dbd92e6574af"} 19:29:50 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:29:50 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:29:50 INFO - Contents: 19:29:50 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/3b680a6f7ac3f9ac70f31c4bccdfb5bccf5e76c5b68634e46423884921c6cadd1a5cb0298798821f1c0bf5828f989ed97377e15bb90355aaa29e4c502f6a03e6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/bd052022dbec7b8687366973555b5438520ceb13a991547289809e58f0ad5ab9cebe6094161a2d6a7e656f518538ebac237201132bb2448b63b846b033d49dcb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/a4b5044cd04f74f9382b295825cf55572c6517790f93e38894c6d3a4189972089178ddd335e8bf2621d555aa11cf93af17f3240d2ec357ed2784dbd92e6574af"} 19:29:50 INFO - Running post-run listener: copy_logs_to_upload_dir 19:29:50 INFO - Copying logs to upload dir... 19:29:50 INFO - mkdir: /builds/slave/test/build/upload/logs 19:29:50 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1244.818464 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 44 secs) (at 2016-07-07 19:29:50.258512) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-07 19:29:50.261700) ========= 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=eca2ab57546de7b41f256b19000001ef-1467943739.668630-1411652204 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/3b680a6f7ac3f9ac70f31c4bccdfb5bccf5e76c5b68634e46423884921c6cadd1a5cb0298798821f1c0bf5828f989ed97377e15bb90355aaa29e4c502f6a03e6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/bd052022dbec7b8687366973555b5438520ceb13a991547289809e58f0ad5ab9cebe6094161a2d6a7e656f518538ebac237201132bb2448b63b846b033d49dcb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/a4b5044cd04f74f9382b295825cf55572c6517790f93e38894c6d3a4189972089178ddd335e8bf2621d555aa11cf93af17f3240d2ec357ed2784dbd92e6574af"} build_url:https://queue.taskcluster.net/v1/task/KkQu1Lb5Slm7Nanvt0EsHQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011324 build_url: 'https://queue.taskcluster.net/v1/task/KkQu1Lb5Slm7Nanvt0EsHQ/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/3b680a6f7ac3f9ac70f31c4bccdfb5bccf5e76c5b68634e46423884921c6cadd1a5cb0298798821f1c0bf5828f989ed97377e15bb90355aaa29e4c502f6a03e6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/bd052022dbec7b8687366973555b5438520ceb13a991547289809e58f0ad5ab9cebe6094161a2d6a7e656f518538ebac237201132bb2448b63b846b033d49dcb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/a4b5044cd04f74f9382b295825cf55572c6517790f93e38894c6d3a4189972089178ddd335e8bf2621d555aa11cf93af17f3240d2ec357ed2784dbd92e6574af"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-07 19:29:50.292952) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-07 19:29:50.293770) ========= 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=eca2ab57546de7b41f256b19000001ef-1467943739.668630-1411652204 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004495 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-07 19:29:50.376731) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-07 19:29:50.377533) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-07 19:29:50.378223) ========= ========= Total master_lag: 0.18 =========