builder: autoland_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-005 starttime: 1474430467.32 results: success (0) buildid: 20160920193707 builduid: c538bb1d60ce4d028327e825b3de54fc revision: 016f24cec86ee68e1323a9d350a9788519d3f175 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-20 21:01:07.322572) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-20 21:01:07.323050) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-20 21:01:07.323322) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-20 21:01:07.338335) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 21:01:07.338761) ========= 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=7fc86f70bae03e018da0b1d8000001c4-1474430467.8790-361103811 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-20 21:01:07-- 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% 38.1M=0s 2016-09-20 21:01:07 (38.1 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.140874 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 21:01:07.493613) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 21:01:07.493947) ========= 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=7fc86f70bae03e018da0b1d8000001c4-1474430467.8790-361103811 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017430 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 21:01:07.543950) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-20 21:01:07.544335) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 016f24cec86ee68e1323a9d350a9788519d3f175 --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 016f24cec86ee68e1323a9d350a9788519d3f175 --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=7fc86f70bae03e018da0b1d8000001c4-1474430467.8790-361103811 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-20 21:01:07,966 truncating revision to first 12 chars 2016-09-20 21:01:07,966 Setting DEBUG logging. 2016-09-20 21:01:07,966 attempt 1/10 2016-09-20 21:01:07,966 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/016f24cec86e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-20 21:01:08,952 unpacking tar archive at: autoland-016f24cec86e/testing/mozharness/ program finished with exit code 0 elapsedTime=1.328321 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-20 21:01:08.883416) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-20 21:01:08.883734) ========= script_repo_revision: 016f24cec86ee68e1323a9d350a9788519d3f175 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-20 21:01:08.884128) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-20 21:01:08.884388) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-20 21:01:08.894413) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 44 secs) (at 2016-09-20 21:01:08.894847) ========= /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=7fc86f70bae03e018da0b1d8000001c4-1474430467.8790-361103811 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 21:01:09 INFO - MultiFileLogger online at 20160920 21:01:09 in /builds/slave/test 21:01:09 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland-Non-PGO 21:01:09 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 21:01:09 INFO - {'append_to_log': False, 21:01:09 INFO - 'base_work_dir': '/builds/slave/test', 21:01:09 INFO - 'blob_upload_branch': 'Autoland-Non-PGO', 21:01:09 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 21:01:09 INFO - 'branch': 'Autoland-Non-PGO', 21:01:09 INFO - 'buildbot_json_path': 'buildprops.json', 21:01:09 INFO - 'config_files': ('talos/linux_config.py',), 21:01:09 INFO - 'default_actions': ('clobber', 21:01:09 INFO - 'read-buildbot-config', 21:01:09 INFO - 'download-and-extract', 21:01:09 INFO - 'populate-webroot', 21:01:09 INFO - 'create-virtualenv', 21:01:09 INFO - 'install', 21:01:09 INFO - 'run-tests'), 21:01:09 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:01:09 INFO - 'download_minidump_stackwalk': True, 21:01:09 INFO - 'download_symbols': 'ondemand', 21:01:09 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:01:09 INFO - 'tooltool.py': '/tools/tooltool.py', 21:01:09 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:01:09 INFO - '/tools/misc-python/virtualenv.py')}, 21:01:09 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:01:09 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:01:09 INFO - 'installer_path': 'installer.exe', 21:01:09 INFO - 'log_level': 'info', 21:01:09 INFO - 'log_name': 'talos', 21:01:09 INFO - 'log_to_console': True, 21:01:09 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 21:01:09 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 21:01:09 INFO - 'opt_config_files': (), 21:01:09 INFO - 'pip_index': False, 21:01:09 INFO - 'sps_profile': False, 21:01:09 INFO - 'sps_profile_interval': 0, 21:01:09 INFO - 'suite': 'dromaeojs', 21:01:09 INFO - 'system_bits': '32', 21:01:09 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:01:09 INFO - 'title': 'talos-linux64-ix-005', 21:01:09 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:01:09 INFO - 'use_talos_json': True, 21:01:09 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 21:01:09 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:01:09 INFO - 'work_dir': 'build'} 21:01:09 INFO - [mozharness: 2016-09-21 04:01:09.410793Z] Running clobber step. 21:01:09 INFO - Running pre-action listener: _resource_record_pre_action 21:01:09 INFO - Running main action method: clobber 21:01:09 INFO - rmtree: /builds/slave/test/build 21:01:09 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 21:01:09 INFO - Running post-action listener: _resource_record_post_action 21:01:09 INFO - [mozharness: 2016-09-21 04:01:09.877456Z] Finished clobber step (success) 21:01:09 INFO - [mozharness: 2016-09-21 04:01:09.877558Z] Running read-buildbot-config step. 21:01:09 INFO - Running pre-action listener: _resource_record_pre_action 21:01:09 INFO - Running main action method: read_buildbot_config 21:01:09 INFO - Using buildbot properties: 21:01:09 INFO - { 21:01:09 INFO - "project": "", 21:01:09 INFO - "product": "firefox", 21:01:09 INFO - "script_repo_revision": "production", 21:01:09 INFO - "builddir": "autoland_ubuntu64_hw_test-dromaeojs", 21:01:09 INFO - "repository": "", 21:01:09 INFO - "buildername": "Ubuntu HW 12.04 x64 autoland talos dromaeojs", 21:01:09 INFO - "buildid": "20160920193707", 21:01:09 INFO - "pgo_build": "False", 21:01:09 INFO - "basedir": "/builds/slave/test", 21:01:09 INFO - "buildnumber": 681, 21:01:09 INFO - "slavename": "talos-linux64-ix-005", 21:01:09 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 21:01:09 INFO - "platform": "ubuntu64_hw", 21:01:09 INFO - "scheduler": "tests-autoland-linux64-talos", 21:01:09 INFO - "branch": "autoland", 21:01:09 INFO - "repo_path": "integration/autoland", 21:01:09 INFO - "revision": "016f24cec86ee68e1323a9d350a9788519d3f175", 21:01:09 INFO - "stage_platform": "linux64", 21:01:09 INFO - "builduid": "c538bb1d60ce4d028327e825b3de54fc", 21:01:09 INFO - "slavebuilddir": "test" 21:01:09 INFO - } 21:01:09 INFO - Found installer url https://queue.taskcluster.net/v1/task/LUJG39qpRAC8cb7cyVanpA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2. 21:01:09 INFO - Running post-action listener: _resource_record_post_action 21:01:09 INFO - [mozharness: 2016-09-21 04:01:09.880000Z] Finished read-buildbot-config step (success) 21:01:09 INFO - [mozharness: 2016-09-21 04:01:09.880090Z] Running download-and-extract step. 21:01:09 INFO - Running pre-action listener: _resource_record_pre_action 21:01:09 INFO - Running main action method: download_and_extract 21:01:09 INFO - mkdir: /builds/slave/test/build/tests 21:01:09 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:01:09 INFO - trying https://queue.taskcluster.net/v1/task/LUJG39qpRAC8cb7cyVanpA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 21:01:09 INFO - Downloading https://queue.taskcluster.net/v1/task/LUJG39qpRAC8cb7cyVanpA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 21:01:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LUJG39qpRAC8cb7cyVanpA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 21:01:10 INFO - Downloaded 1361 bytes. 21:01:10 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 21:01:10 INFO - Using the following test package requirements: 21:01:10 INFO - {u'common': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'], 21:01:10 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 21:01:10 INFO - u'firefox-52.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 21:01:10 INFO - u'gtest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 21:01:10 INFO - u'firefox-52.0a1.en-US.linux-x86_64.gtest.tests.zip'], 21:01:10 INFO - u'jittest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 21:01:10 INFO - u'jsshell-linux-x86_64.zip'], 21:01:10 INFO - u'mochitest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 21:01:10 INFO - u'firefox-52.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 21:01:10 INFO - u'mozbase': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'], 21:01:10 INFO - u'reftest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 21:01:10 INFO - u'firefox-52.0a1.en-US.linux-x86_64.reftest.tests.zip'], 21:01:10 INFO - u'talos': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 21:01:10 INFO - u'firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'], 21:01:10 INFO - u'web-platform': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 21:01:10 INFO - u'firefox-52.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 21:01:10 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 21:01:10 INFO - u'firefox-52.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 21:01:10 INFO - Downloading packages: [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 21:01:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LUJG39qpRAC8cb7cyVanpA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip 21:01:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LUJG39qpRAC8cb7cyVanpA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 21:01:10 INFO - Fetch https://queue.taskcluster.net/v1/task/LUJG39qpRAC8cb7cyVanpA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip into memory 21:01:11 INFO - Expected file size: 20370444 21:01:15 INFO - Downloading packages: [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 21:01:15 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LUJG39qpRAC8cb7cyVanpA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip 21:01:15 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LUJG39qpRAC8cb7cyVanpA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 21:01:15 INFO - Fetch https://queue.taskcluster.net/v1/task/LUJG39qpRAC8cb7cyVanpA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip into memory 21:01:15 INFO - Expected file size: 20370444 21:01:20 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LUJG39qpRAC8cb7cyVanpA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip 21:01:20 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LUJG39qpRAC8cb7cyVanpA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 21:01:20 INFO - Fetch https://queue.taskcluster.net/v1/task/LUJG39qpRAC8cb7cyVanpA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip into memory 21:01:21 INFO - Expected file size: 14414059 21:01:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:01:21 INFO - trying https://queue.taskcluster.net/v1/task/LUJG39qpRAC8cb7cyVanpA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 21:01:21 INFO - Downloading https://queue.taskcluster.net/v1/task/LUJG39qpRAC8cb7cyVanpA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 21:01:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LUJG39qpRAC8cb7cyVanpA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 21:01:22 INFO - Downloaded 55620390 bytes. 21:01:22 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/LUJG39qpRAC8cb7cyVanpA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 21:01:22 INFO - mkdir: /builds/slave/test/properties 21:01:22 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 21:01:22 INFO - Writing to file /builds/slave/test/properties/build_url 21:01:22 INFO - Contents: 21:01:22 INFO - build_url:https://queue.taskcluster.net/v1/task/LUJG39qpRAC8cb7cyVanpA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 21:01:23 INFO - Running post-action listener: _resource_record_post_action 21:01:23 INFO - Running post-action listener: set_extra_try_arguments 21:01:23 INFO - [mozharness: 2016-09-21 04:01:23.316887Z] Finished download-and-extract step (success) 21:01:23 INFO - [mozharness: 2016-09-21 04:01:23.317117Z] Running populate-webroot step. 21:01:23 INFO - Running pre-action listener: _resource_record_pre_action 21:01:23 INFO - Running main action method: populate_webroot 21:01:23 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 21:01:23 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 21:01:23 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 21:01:23 INFO - '--tppagecycles', 21:01:23 INFO - '7'], 21:01:23 INFO - 'tests': ['tsvgm']}}, 21:01:23 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 21:01:23 INFO - 'tests': ['tresize', 'tcanvasmark']}, 21:01:23 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 21:01:23 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 21:01:23 INFO - 'tests': ['dromaeo_css', 'kraken']}, 21:01:23 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 21:01:23 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:01:23 INFO - 'talos_options': ['--disable-e10s'], 21:01:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:01:23 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:01:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:01:23 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:01:23 INFO - 'talos_options': ['--disable-e10s'], 21:01:23 INFO - 'tests': ['damp', 'tps']}, 21:01:23 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:01:23 INFO - 'tests': ['damp', 'tps']}, 21:01:23 INFO - 'g3': {'talos_options': ['--disable-e10s'], 21:01:23 INFO - 'tests': ['dromaeo_dom']}, 21:01:23 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:01:23 INFO - 'g4': {'talos_options': ['--disable-e10s'], 21:01:23 INFO - 'tests': ['basic_compositor_video']}, 21:01:23 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 21:01:23 INFO - 'other': {'talos_options': ['--disable-e10s'], 21:01:23 INFO - 'tests': ['a11yr', 21:01:23 INFO - 'ts_paint', 21:01:23 INFO - 'tpaint', 21:01:23 INFO - 'sessionrestore', 21:01:23 INFO - 'sessionrestore_no_auto_restore', 21:01:23 INFO - 'tabpaint']}, 21:01:23 INFO - 'other-e10s': {'tests': ['a11yr', 21:01:23 INFO - 'ts_paint', 21:01:23 INFO - 'tpaint', 21:01:23 INFO - 'sessionrestore', 21:01:23 INFO - 'sessionrestore_no_auto_restore', 21:01:23 INFO - 'tabpaint']}, 21:01:23 INFO - 'other-e10s_l64': {'tests': ['a11yr', 21:01:23 INFO - 'ts_paint', 21:01:23 INFO - 'tpaint', 21:01:23 INFO - 'sessionrestore', 21:01:23 INFO - 'sessionrestore_no_auto_restore', 21:01:23 INFO - 'tabpaint']}, 21:01:23 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 21:01:23 INFO - 'ts_paint', 21:01:23 INFO - 'tpaint', 21:01:23 INFO - 'sessionrestore', 21:01:23 INFO - 'sessionrestore_no_auto_restore', 21:01:23 INFO - 'tabpaint']}, 21:01:23 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 21:01:23 INFO - 'tests': ['a11yr', 21:01:23 INFO - 'ts_paint', 21:01:23 INFO - 'tpaint', 21:01:23 INFO - 'sessionrestore', 21:01:23 INFO - 'sessionrestore_no_auto_restore', 21:01:23 INFO - 'tabpaint']}, 21:01:23 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 21:01:23 INFO - 'tests': ['a11yr', 21:01:23 INFO - 'ts_paint', 21:01:23 INFO - 'tpaint', 21:01:23 INFO - 'sessionrestore', 21:01:23 INFO - 'sessionrestore_no_auto_restore', 21:01:23 INFO - 'tabpaint']}, 21:01:23 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 21:01:23 INFO - 'tests': ['tsvgx', 21:01:23 INFO - 'tsvgr_opacity', 21:01:23 INFO - 'tart', 21:01:23 INFO - 'tscrollx', 21:01:23 INFO - 'cart']}, 21:01:23 INFO - 'svgr-e10s': {'tests': ['tsvgx', 21:01:23 INFO - 'tsvgr_opacity', 21:01:23 INFO - 'tart', 21:01:23 INFO - 'tscrollx', 21:01:23 INFO - 'cart']}, 21:01:23 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:01:23 INFO - 'talos_options': ['--disable-e10s'], 21:01:23 INFO - 'tests': ['tp5o']}, 21:01:23 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:01:23 INFO - 'tests': ['tp5o']}, 21:01:23 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:01:23 INFO - 'talos_options': ['--disable-e10s', 21:01:23 INFO - '--xperf_path', 21:01:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:01:23 INFO - 'tests': ['tp5n']}, 21:01:23 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:01:23 INFO - 'talos_options': ['--xperf_path', 21:01:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:01:23 INFO - 'tests': ['tp5n']}}, 21:01:23 INFO - 'talos.zip': {'path': '', 21:01:23 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:01:23 INFO - Running post-action listener: _resource_record_post_action 21:01:23 INFO - [mozharness: 2016-09-21 04:01:23.338337Z] Finished populate-webroot step (success) 21:01:23 INFO - [mozharness: 2016-09-21 04:01:23.338426Z] Running create-virtualenv step. 21:01:23 INFO - Running pre-action listener: _resource_record_pre_action 21:01:23 INFO - Running main action method: create_virtualenv 21:01:23 INFO - Creating virtualenv /builds/slave/test/build/venv 21:01:23 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 21:01:23 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 21:01:23 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:01:23 INFO - Using real prefix '/usr' 21:01:23 INFO - New python executable in /builds/slave/test/build/venv/bin/python 21:01:23 INFO - Installing distribute.............................................................................................................................................................................................done. 21:01:25 INFO - Installing pip.................done. 21:01:25 INFO - Return code: 0 21:01:25 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 21:01:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:01:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:01:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:01:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:01:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:01:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:01:25 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 0x7f6f57070f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2af38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bc0ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bbdf60>, '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 0x2bbed40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bbf1d0>, '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': '7fc86f70bae03e018da0b1d8000001c4-1474430467.8790-361103811', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 21:01:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 21:01:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 21:01:25 INFO - Using env: {'DISPLAY': ':0', 21:01:25 INFO - 'HOME': '/home/cltbld', 21:01:25 INFO - 'LANG': 'en_US.UTF-8', 21:01:25 INFO - 'LANGUAGE': 'en_US:en', 21:01:25 INFO - 'LOGNAME': 'cltbld', 21:01:25 INFO - 'MAIL': '/var/mail/cltbld', 21:01:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:01:25 INFO - 'MOZ_NO_REMOTE': '1', 21:01:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:01:25 INFO - 'NO_EM_RESTART': '1', 21:01:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:01:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:01:25 INFO - 'PWD': '/builds/slave/test', 21:01:25 INFO - 'SHELL': '/bin/bash', 21:01:25 INFO - 'SHLVL': '1', 21:01:25 INFO - 'TERM': 'linux', 21:01:25 INFO - 'TMOUT': '86400', 21:01:25 INFO - 'USER': 'cltbld', 21:01:25 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1474430467.8790-361103811', 21:01:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:01:25 INFO - '_': '/tools/buildbot/bin/python'} 21:01:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:01:26 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 21:01:26 INFO - Cleaning up... 21:01:26 INFO - Return code: 0 21:01:26 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 21:01: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:01:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:01:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:01: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:01:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:01:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:01: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', '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 0x7f6f57070f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2af38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bc0ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bbdf60>, '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 0x2bbed40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bbf1d0>, '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': '7fc86f70bae03e018da0b1d8000001c4-1474430467.8790-361103811', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 21:01: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', 'psutil>=3.1.1'] in /builds/slave/test/build 21:01: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 psutil>=3.1.1 21:01:26 INFO - Using env: {'DISPLAY': ':0', 21:01:26 INFO - 'HOME': '/home/cltbld', 21:01:26 INFO - 'LANG': 'en_US.UTF-8', 21:01:26 INFO - 'LANGUAGE': 'en_US:en', 21:01:26 INFO - 'LOGNAME': 'cltbld', 21:01:26 INFO - 'MAIL': '/var/mail/cltbld', 21:01:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:01:26 INFO - 'MOZ_NO_REMOTE': '1', 21:01:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:01:26 INFO - 'NO_EM_RESTART': '1', 21:01:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:01:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:01:26 INFO - 'PWD': '/builds/slave/test', 21:01:26 INFO - 'SHELL': '/bin/bash', 21:01:26 INFO - 'SHLVL': '1', 21:01:26 INFO - 'TERM': 'linux', 21:01:26 INFO - 'TMOUT': '86400', 21:01:26 INFO - 'USER': 'cltbld', 21:01:26 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1474430467.8790-361103811', 21:01:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:01:26 INFO - '_': '/tools/buildbot/bin/python'} 21:01:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:01:26 INFO - Downloading/unpacking psutil>=3.1.1 21:01:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:01:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:01:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:01:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:01:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:01:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:01:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 21:01:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:01:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:01:28 INFO - Installing collected packages: psutil 21:01:28 INFO - Running setup.py install for psutil 21:01:28 INFO - building 'psutil._psutil_linux' extension 21:01:28 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 21:01:28 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 21:01:28 INFO - building 'psutil._psutil_posix' extension 21:01:28 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 21:01:28 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 21:01:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:01:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:01:28 INFO - Successfully installed psutil 21:01:28 INFO - Cleaning up... 21:01:28 INFO - Return code: 0 21:01:28 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 21:01:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:01:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:01:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:01:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:01:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:01:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:01:28 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 0x7f6f57070f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2af38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bc0ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bbdf60>, '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 0x2bbed40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bbf1d0>, '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': '7fc86f70bae03e018da0b1d8000001c4-1474430467.8790-361103811', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 21:01:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 21:01:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 21:01:28 INFO - Using env: {'DISPLAY': ':0', 21:01:28 INFO - 'HOME': '/home/cltbld', 21:01:28 INFO - 'LANG': 'en_US.UTF-8', 21:01:28 INFO - 'LANGUAGE': 'en_US:en', 21:01:28 INFO - 'LOGNAME': 'cltbld', 21:01:28 INFO - 'MAIL': '/var/mail/cltbld', 21:01:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:01:28 INFO - 'MOZ_NO_REMOTE': '1', 21:01:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:01:28 INFO - 'NO_EM_RESTART': '1', 21:01:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:01:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:01:28 INFO - 'PWD': '/builds/slave/test', 21:01:28 INFO - 'SHELL': '/bin/bash', 21:01:28 INFO - 'SHLVL': '1', 21:01:28 INFO - 'TERM': 'linux', 21:01:28 INFO - 'TMOUT': '86400', 21:01:28 INFO - 'USER': 'cltbld', 21:01:28 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1474430467.8790-361103811', 21:01:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:01:28 INFO - '_': '/tools/buildbot/bin/python'} 21:01:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:01:29 INFO - Downloading/unpacking mozsystemmonitor==0.3 21:01: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 21:01: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 21:01: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 21:01: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 21:01: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 21:01: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 21:01:31 INFO - Downloading mozsystemmonitor-0.3.tar.gz 21:01:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:01:31 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 21:01:31 INFO - Installing collected packages: mozsystemmonitor 21:01:31 INFO - Running setup.py install for mozsystemmonitor 21:01:31 INFO - Successfully installed mozsystemmonitor 21:01:31 INFO - Cleaning up... 21:01:31 INFO - Return code: 0 21:01:31 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 21:01: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:01:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:01:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:01: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:01:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:01:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:01: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', '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 0x7f6f57070f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2af38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bc0ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bbdf60>, '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 0x2bbed40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bbf1d0>, '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': '7fc86f70bae03e018da0b1d8000001c4-1474430467.8790-361103811', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 21:01: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 21:01: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 jsonschema==2.5.1 21:01:31 INFO - Using env: {'DISPLAY': ':0', 21:01:31 INFO - 'HOME': '/home/cltbld', 21:01:31 INFO - 'LANG': 'en_US.UTF-8', 21:01:31 INFO - 'LANGUAGE': 'en_US:en', 21:01:31 INFO - 'LOGNAME': 'cltbld', 21:01:31 INFO - 'MAIL': '/var/mail/cltbld', 21:01:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:01:31 INFO - 'MOZ_NO_REMOTE': '1', 21:01:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:01:31 INFO - 'NO_EM_RESTART': '1', 21:01:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:01:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:01:31 INFO - 'PWD': '/builds/slave/test', 21:01:31 INFO - 'SHELL': '/bin/bash', 21:01:31 INFO - 'SHLVL': '1', 21:01:31 INFO - 'TERM': 'linux', 21:01:31 INFO - 'TMOUT': '86400', 21:01:31 INFO - 'USER': 'cltbld', 21:01:31 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1474430467.8790-361103811', 21:01:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:01:31 INFO - '_': '/tools/buildbot/bin/python'} 21:01:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:01:31 INFO - Downloading/unpacking jsonschema==2.5.1 21:01: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 21:01: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 21:01: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 21:01: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 21:01: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 21:01: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 21:01:33 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:01:33 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 21:01: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 21:01: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 21:01: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 21:01: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 21:01: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 21:01: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 21:01:33 INFO - Downloading functools32-3.2.3-2.tar.gz 21:01:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 21:01:33 INFO - warning: no files found matching '*.txt' 21:01:33 INFO - no previously-included directories found matching 'build' 21:01:33 INFO - no previously-included directories found matching 'dist' 21:01:33 INFO - no previously-included directories found matching '.git*' 21:01:33 INFO - Installing collected packages: jsonschema, functools32 21:01:33 INFO - Running setup.py install for functools32 21:01:33 INFO - warning: no files found matching '*.txt' 21:01:33 INFO - no previously-included directories found matching 'build' 21:01:33 INFO - no previously-included directories found matching 'dist' 21:01:33 INFO - no previously-included directories found matching '.git*' 21:01:33 INFO - Successfully installed jsonschema functools32 21:01:33 INFO - Cleaning up... 21:01:33 INFO - Return code: 0 21:01:33 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 21:01:33 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:01:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:01:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:01:33 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:01:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:01:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:01:33 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', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f6f57070f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2af38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bc0ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bbdf60>, '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 0x2bbed40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bbf1d0>, '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': '7fc86f70bae03e018da0b1d8000001c4-1474430467.8790-361103811', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 21:01:33 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', 'functools32==3.2.3-2'] in /builds/slave/test/build 21:01:33 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 functools32==3.2.3-2 21:01:33 INFO - Using env: {'DISPLAY': ':0', 21:01:33 INFO - 'HOME': '/home/cltbld', 21:01:33 INFO - 'LANG': 'en_US.UTF-8', 21:01:33 INFO - 'LANGUAGE': 'en_US:en', 21:01:33 INFO - 'LOGNAME': 'cltbld', 21:01:33 INFO - 'MAIL': '/var/mail/cltbld', 21:01:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:01:33 INFO - 'MOZ_NO_REMOTE': '1', 21:01:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:01:33 INFO - 'NO_EM_RESTART': '1', 21:01:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:01:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:01:33 INFO - 'PWD': '/builds/slave/test', 21:01:33 INFO - 'SHELL': '/bin/bash', 21:01:33 INFO - 'SHLVL': '1', 21:01:33 INFO - 'TERM': 'linux', 21:01:33 INFO - 'TMOUT': '86400', 21:01:33 INFO - 'USER': 'cltbld', 21:01:33 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1474430467.8790-361103811', 21:01:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:01:33 INFO - '_': '/tools/buildbot/bin/python'} 21:01:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:01:34 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 21:01:34 INFO - Cleaning up... 21:01:34 INFO - Return code: 0 21:01:34 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 21:01:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:01:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:01:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:01:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:01:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:01:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:01:34 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 0x7f6f57070f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2af38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bc0ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bbdf60>, '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 0x2bbed40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bbf1d0>, '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': '7fc86f70bae03e018da0b1d8000001c4-1474430467.8790-361103811', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 21:01:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 21:01:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 21:01:34 INFO - Using env: {'DISPLAY': ':0', 21:01:34 INFO - 'HOME': '/home/cltbld', 21:01:34 INFO - 'LANG': 'en_US.UTF-8', 21:01:34 INFO - 'LANGUAGE': 'en_US:en', 21:01:34 INFO - 'LOGNAME': 'cltbld', 21:01:34 INFO - 'MAIL': '/var/mail/cltbld', 21:01:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:01:34 INFO - 'MOZ_NO_REMOTE': '1', 21:01:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:01:34 INFO - 'NO_EM_RESTART': '1', 21:01:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:01:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:01:34 INFO - 'PWD': '/builds/slave/test', 21:01:34 INFO - 'SHELL': '/bin/bash', 21:01:34 INFO - 'SHLVL': '1', 21:01:34 INFO - 'TERM': 'linux', 21:01:34 INFO - 'TMOUT': '86400', 21:01:34 INFO - 'USER': 'cltbld', 21:01:34 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1474430467.8790-361103811', 21:01:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:01:34 INFO - '_': '/tools/buildbot/bin/python'} 21:01:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:01:34 INFO - Downloading/unpacking blobuploader==1.2.4 21:01: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 21:01: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 21:01: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 21:01: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 21:01: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 21:01: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 21:01:36 INFO - Downloading blobuploader-1.2.4.tar.gz 21:01:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:01:36 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:01:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:01:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:01:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:01:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:01:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:01:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:01:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 21:01:36 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:01:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:01:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:01:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:01:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:01:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:01:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:01:36 INFO - Downloading docopt-0.6.1.tar.gz 21:01:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 21:01:36 INFO - Installing collected packages: blobuploader, requests, docopt 21:01:36 INFO - Running setup.py install for blobuploader 21:01:36 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:01:36 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 21:01:37 INFO - Running setup.py install for requests 21:01:37 INFO - Running setup.py install for docopt 21:01:37 INFO - Successfully installed blobuploader requests docopt 21:01:37 INFO - Cleaning up... 21:01:37 INFO - Return code: 0 21:01:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:01:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:01:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:01:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:01:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:01:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:01:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:01:37 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 0x7f6f57070f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2af38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bc0ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bbdf60>, '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 0x2bbed40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bbf1d0>, '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': '7fc86f70bae03e018da0b1d8000001c4-1474430467.8790-361103811', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 21:01:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 21:01:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 21:01:37 INFO - Using env: {'DISPLAY': ':0', 21:01:37 INFO - 'HOME': '/home/cltbld', 21:01:37 INFO - 'LANG': 'en_US.UTF-8', 21:01:37 INFO - 'LANGUAGE': 'en_US:en', 21:01:37 INFO - 'LOGNAME': 'cltbld', 21:01:37 INFO - 'MAIL': '/var/mail/cltbld', 21:01:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:01:37 INFO - 'MOZ_NO_REMOTE': '1', 21:01:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:01:37 INFO - 'NO_EM_RESTART': '1', 21:01:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:01:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:01:37 INFO - 'PWD': '/builds/slave/test', 21:01:37 INFO - 'SHELL': '/bin/bash', 21:01:37 INFO - 'SHLVL': '1', 21:01:37 INFO - 'TERM': 'linux', 21:01:37 INFO - 'TMOUT': '86400', 21:01:37 INFO - 'USER': 'cltbld', 21:01:37 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1474430467.8790-361103811', 21:01:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:01:37 INFO - '_': '/tools/buildbot/bin/python'} 21:01:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:01:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:01:37 INFO - Running setup.py (path:/tmp/pip-CF67z4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:01:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:01:37 INFO - Running setup.py (path:/tmp/pip-v620UE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:01:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:01:37 INFO - Running setup.py (path:/tmp/pip-TDPLrY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:01:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:01:37 INFO - Running setup.py (path:/tmp/pip-H4LM5y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:01:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:01:38 INFO - Running setup.py (path:/tmp/pip-83rmM8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:01:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:01:38 INFO - Running setup.py (path:/tmp/pip-dui3NN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:01:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:01:38 INFO - Running setup.py (path:/tmp/pip-fedfRi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:01:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:01:38 INFO - Running setup.py (path:/tmp/pip-BXHWul-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:01:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:01:38 INFO - Running setup.py (path:/tmp/pip-glIMlh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:01:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:01:38 INFO - Running setup.py (path:/tmp/pip-0CbvR1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:01:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:01:38 INFO - Running setup.py (path:/tmp/pip-zsLgm1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:01:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:01:38 INFO - Running setup.py (path:/tmp/pip-gNbkfJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:01:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:01:38 INFO - Running setup.py (path:/tmp/pip-tb_Ntr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:01:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:01:38 INFO - Running setup.py (path:/tmp/pip-uOyItI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:01:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:01:38 INFO - Running setup.py (path:/tmp/pip-OFPsnN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:01:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:01:38 INFO - Running setup.py (path:/tmp/pip-f4bu5R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:01:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:01:38 INFO - Running setup.py (path:/tmp/pip-eMdNtL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:01:38 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:01:38 INFO - Running setup.py install for manifestparser 21:01:39 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 21:01:39 INFO - Running setup.py install for mozcrash 21:01:39 INFO - Running setup.py install for mozdebug 21:01:39 INFO - Running setup.py install for mozdevice 21:01:39 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 21:01:39 INFO - Installing dm script to /builds/slave/test/build/venv/bin 21:01:39 INFO - Running setup.py install for mozfile 21:01:39 INFO - Running setup.py install for mozhttpd 21:01:39 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 21:01:39 INFO - Running setup.py install for mozinfo 21:01:39 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 21:01:39 INFO - Running setup.py install for mozInstall 21:01:39 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 21:01:39 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 21:01:39 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 21:01:39 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 21:01:39 INFO - Running setup.py install for mozleak 21:01:39 INFO - Running setup.py install for mozlog 21:01:39 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 21:01:40 INFO - Running setup.py install for moznetwork 21:01:40 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 21:01:40 INFO - Running setup.py install for mozprocess 21:01:40 INFO - Running setup.py install for mozprofile 21:01:40 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 21:01:40 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 21:01:40 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 21:01:40 INFO - Running setup.py install for mozrunner 21:01:40 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 21:01:40 INFO - Running setup.py install for mozscreenshot 21:01:40 INFO - Running setup.py install for moztest 21:01:40 INFO - Running setup.py install for mozversion 21:01:40 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 21:01:40 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:01:40 INFO - Cleaning up... 21:01:40 INFO - Return code: 0 21:01:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:01:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:01:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:01:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:01:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:01:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:01:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:01:40 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 0x7f6f57070f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2af38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bc0ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bbdf60>, '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 0x2bbed40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bbf1d0>, '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': '7fc86f70bae03e018da0b1d8000001c4-1474430467.8790-361103811', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 21:01:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 21:01:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 21:01:40 INFO - Using env: {'DISPLAY': ':0', 21:01:40 INFO - 'HOME': '/home/cltbld', 21:01:40 INFO - 'LANG': 'en_US.UTF-8', 21:01:40 INFO - 'LANGUAGE': 'en_US:en', 21:01:40 INFO - 'LOGNAME': 'cltbld', 21:01:40 INFO - 'MAIL': '/var/mail/cltbld', 21:01:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:01:40 INFO - 'MOZ_NO_REMOTE': '1', 21:01:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:01:40 INFO - 'NO_EM_RESTART': '1', 21:01:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:01:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:01:40 INFO - 'PWD': '/builds/slave/test', 21:01:40 INFO - 'SHELL': '/bin/bash', 21:01:40 INFO - 'SHLVL': '1', 21:01:40 INFO - 'TERM': 'linux', 21:01:40 INFO - 'TMOUT': '86400', 21:01:40 INFO - 'USER': 'cltbld', 21:01:40 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1474430467.8790-361103811', 21:01:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:01:40 INFO - '_': '/tools/buildbot/bin/python'} 21:01:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:01:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:01:40 INFO - Running setup.py (path:/tmp/pip-t4u0f7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:01:41 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 21:01:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:01:41 INFO - Running setup.py (path:/tmp/pip-5cnxAP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:01:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 21:01:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:01:41 INFO - Running setup.py (path:/tmp/pip-6Jula_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:01:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 21:01:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:01:41 INFO - Running setup.py (path:/tmp/pip-S44apZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:01:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 21:01:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:01:41 INFO - Running setup.py (path:/tmp/pip-kJSlbt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:01:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 21:01:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:01:41 INFO - Running setup.py (path:/tmp/pip-bsgl4z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:01:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 21:01:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:01:41 INFO - Running setup.py (path:/tmp/pip-QYx7Gn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:01:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 21:01:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:01:41 INFO - Running setup.py (path:/tmp/pip-YyztIX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:01:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 21:01:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:01:41 INFO - Running setup.py (path:/tmp/pip-nwdbcT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:01:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 21:01:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:01:41 INFO - Running setup.py (path:/tmp/pip-g9IDAQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:01:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:01:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:01:41 INFO - Running setup.py (path:/tmp/pip-5RHwmS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:01:41 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 21:01:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:01:41 INFO - Running setup.py (path:/tmp/pip-tycP8g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:01:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 21:01:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:01:41 INFO - Running setup.py (path:/tmp/pip-BDShSA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:01:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 21:01:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:01:41 INFO - Running setup.py (path:/tmp/pip-FyqYXr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:01:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 21:01:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:01:42 INFO - Running setup.py (path:/tmp/pip-7iLeYY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:01:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 21:01:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:01:42 INFO - Running setup.py (path:/tmp/pip-cZr_Pq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:01:42 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 21:01:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:01:42 INFO - Running setup.py (path:/tmp/pip-yXF3r1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:01:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 21:01:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 21:01:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 21:01:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 21:01:42 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 21:01:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 21:01:42 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:01:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:01:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:01:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:01:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:01:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:01:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:01:44 INFO - Downloading blessings-1.6.tar.gz 21:01:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 21:01:44 INFO - Installing collected packages: blessings 21:01:44 INFO - Running setup.py install for blessings 21:01:44 INFO - Successfully installed blessings 21:01:44 INFO - Cleaning up... 21:01:44 INFO - Return code: 0 21:01:44 INFO - Done creating virtualenv /builds/slave/test/build/venv. 21:01:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:01:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:01:44 INFO - Reading from file tmpfile_stdout 21:01:44 INFO - Current package versions: 21:01:44 INFO - argparse == 1.2.1 21:01:44 INFO - blessings == 1.6 21:01:44 INFO - blobuploader == 1.2.4 21:01:44 INFO - docopt == 0.6.1 21:01:44 INFO - functools32 == 3.2.3-2 21:01:44 INFO - jsonschema == 2.5.1 21:01:44 INFO - manifestparser == 1.1 21:01:44 INFO - mozInstall == 1.12 21:01:44 INFO - mozcrash == 0.17 21:01:44 INFO - mozdebug == 0.1 21:01:44 INFO - mozdevice == 0.48 21:01:44 INFO - mozfile == 1.2 21:01:44 INFO - mozhttpd == 0.7 21:01:44 INFO - mozinfo == 0.9 21:01:44 INFO - mozleak == 0.1 21:01:44 INFO - mozlog == 3.4 21:01:44 INFO - moznetwork == 0.27 21:01:44 INFO - mozprocess == 0.23 21:01:44 INFO - mozprofile == 0.28 21:01:44 INFO - mozrunner == 6.12 21:01:44 INFO - mozscreenshot == 0.1 21:01:44 INFO - mozsystemmonitor == 0.3 21:01:44 INFO - moztest == 0.7 21:01:44 INFO - mozversion == 1.4 21:01:44 INFO - psutil == 3.1.1 21:01:44 INFO - requests == 1.2.3 21:01:44 INFO - wsgiref == 0.1.2 21:01:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:01:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:01:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:01:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:01:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:01:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:01:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:01:44 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 0x7f6f57070f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2af38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bc0ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bbdf60>, '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 0x2bbed40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bbf1d0>, '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': '7fc86f70bae03e018da0b1d8000001c4-1474430467.8790-361103811', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 21:01:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 21:01:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 21:01:44 INFO - Using env: {'DISPLAY': ':0', 21:01:44 INFO - 'HOME': '/home/cltbld', 21:01:44 INFO - 'LANG': 'en_US.UTF-8', 21:01:44 INFO - 'LANGUAGE': 'en_US:en', 21:01:44 INFO - 'LOGNAME': 'cltbld', 21:01:44 INFO - 'MAIL': '/var/mail/cltbld', 21:01:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:01:44 INFO - 'MOZ_NO_REMOTE': '1', 21:01:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:01:44 INFO - 'NO_EM_RESTART': '1', 21:01:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:01:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:01:44 INFO - 'PWD': '/builds/slave/test', 21:01:44 INFO - 'SHELL': '/bin/bash', 21:01:44 INFO - 'SHLVL': '1', 21:01:44 INFO - 'TERM': 'linux', 21:01:44 INFO - 'TMOUT': '86400', 21:01:44 INFO - 'USER': 'cltbld', 21:01:44 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1474430467.8790-361103811', 21:01:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:01:44 INFO - '_': '/tools/buildbot/bin/python'} 21:01:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:01:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 21:01:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 21:01:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 21:01:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 21:01:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 21:01:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 21:01:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 21:01:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 21:01:44 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 21:01:44 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 21:01:44 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 21:01:44 INFO - Cleaning up... 21:01:44 INFO - Return code: 0 21:01:44 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 21:01:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:01:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:01:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:01:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:01:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:01:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:01:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f6f57070f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2af38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bc0ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bbdf60>, '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 0x2bbed40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bbf1d0>, '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': '7fc86f70bae03e018da0b1d8000001c4-1474430467.8790-361103811', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 21:01:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 21:01:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 21:01:44 INFO - Using env: {'DISPLAY': ':0', 21:01:44 INFO - 'HOME': '/home/cltbld', 21:01:44 INFO - 'LANG': 'en_US.UTF-8', 21:01:44 INFO - 'LANGUAGE': 'en_US:en', 21:01:44 INFO - 'LOGNAME': 'cltbld', 21:01:44 INFO - 'MAIL': '/var/mail/cltbld', 21:01:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:01:44 INFO - 'MOZ_NO_REMOTE': '1', 21:01:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:01:44 INFO - 'NO_EM_RESTART': '1', 21:01:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:01:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:01:44 INFO - 'PWD': '/builds/slave/test', 21:01:44 INFO - 'SHELL': '/bin/bash', 21:01:44 INFO - 'SHLVL': '1', 21:01:44 INFO - 'TERM': 'linux', 21:01:44 INFO - 'TMOUT': '86400', 21:01:44 INFO - 'USER': 'cltbld', 21:01:44 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1474430467.8790-361103811', 21:01:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:01:44 INFO - '_': '/tools/buildbot/bin/python'} 21:01:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:01:45 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 21:01:45 INFO - Cleaning up... 21:01:45 INFO - Return code: 0 21:01:45 INFO - Running post-action listener: _resource_record_post_action 21:01:45 INFO - Running post-action listener: _start_resource_monitoring 21:01:45 INFO - Starting resource monitoring. 21:01:45 INFO - [mozharness: 2016-09-21 04:01:45.087786Z] Finished create-virtualenv step (success) 21:01:45 INFO - [mozharness: 2016-09-21 04:01:45.088287Z] Running install step. 21:01:45 INFO - Running pre-action listener: _resource_record_pre_action 21:01:45 INFO - Running main action method: install 21:01:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:01:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:01:45 INFO - Reading from file tmpfile_stdout 21:01:45 INFO - Detecting whether we're running mozinstall >=1.0... 21:01:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 21:01:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 21:01:45 INFO - Reading from file tmpfile_stdout 21:01:45 INFO - Output received: 21:01:45 INFO - Usage: mozinstall [options] installer 21:01:45 INFO - Options: 21:01:45 INFO - -h, --help show this help message and exit 21:01:45 INFO - -d DEST, --destination=DEST 21:01:45 INFO - Directory to install application into. [default: 21:01:45 INFO - "/builds/slave/test"] 21:01:45 INFO - --app=APP Application being installed. [default: firefox] 21:01:45 INFO - mkdir: /builds/slave/test/build/application 21:01:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 21:01:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 21:02:01 INFO - Reading from file tmpfile_stdout 21:02:01 INFO - Output received: 21:02:01 INFO - /builds/slave/test/build/application/firefox/firefox 21:02:01 INFO - Running post-action listener: _resource_record_post_action 21:02:01 INFO - [mozharness: 2016-09-21 04:02:01.439951Z] Finished install step (success) 21:02:01 INFO - [mozharness: 2016-09-21 04:02:01.440196Z] Running run-tests step. 21:02:01 INFO - Running pre-action listener: _resource_record_pre_action 21:02:01 INFO - Running main action method: run_tests 21:02:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 21:02:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 21:02:01 INFO - Python 2.7.3 21:02:01 INFO - Return code: 0 21:02:01 INFO - grabbing minidump binary from tooltool 21:02:01 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:02:01 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 0x2bbdf60>, '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 0x2bbed40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bbf1d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 21:02:01 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 21:02:01 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 21:02:01 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 21:02:01 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:02:01 INFO - Return code: 0 21:02:01 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 21:02:01 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 21:02:01 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 21:02:01 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 21:02:01 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 21:02:01 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/LUJG39qpRAC8cb7cyVanpA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-005', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 21:02:01 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/LUJG39qpRAC8cb7cyVanpA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-005 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 21:02:01 INFO - Using env: {'DISPLAY': ':0', 21:02:01 INFO - 'HOME': '/home/cltbld', 21:02:01 INFO - 'LANG': 'en_US.UTF-8', 21:02:01 INFO - 'LANGUAGE': 'en_US:en', 21:02:01 INFO - 'LOGNAME': 'cltbld', 21:02:01 INFO - 'MAIL': '/var/mail/cltbld', 21:02:01 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:02:01 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 21:02:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:02:01 INFO - 'MOZ_NO_REMOTE': '1', 21:02:01 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:02:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:02:01 INFO - 'NO_EM_RESTART': '1', 21:02:01 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:02:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:02:01 INFO - 'PWD': '/builds/slave/test', 21:02:01 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:02:01 INFO - 'SHELL': '/bin/bash', 21:02:01 INFO - 'SHLVL': '1', 21:02:01 INFO - 'TERM': 'linux', 21:02:01 INFO - 'TMOUT': '86400', 21:02:01 INFO - 'USER': 'cltbld', 21:02:01 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1474430467.8790-361103811', 21:02:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:02:01 INFO - '_': '/tools/buildbot/bin/python'} 21:02:01 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/LUJG39qpRAC8cb7cyVanpA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-005', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 21:02:01 INFO - mozversion application_buildid: 20160920193707 21:02:01 INFO - mozversion application_changeset: 016f24cec86ee68e1323a9d350a9788519d3f175 21:02:01 INFO - mozversion application_display_name: Nightly 21:02:01 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:02:01 INFO - mozversion application_name: Firefox 21:02:01 INFO - mozversion application_remotingname: firefox 21:02:01 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 21:02:01 INFO - mozversion application_vendor: Mozilla 21:02:01 INFO - mozversion application_version: 52.0a1 21:02:01 INFO - mozversion platform_buildid: 20160920193707 21:02:01 INFO - mozversion platform_changeset: 016f24cec86ee68e1323a9d350a9788519d3f175 21:02:01 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 21:02:01 INFO - mozversion platform_version: 52.0a1 21:02:01 INFO - using testdate: 1474430521 21:02:01 INFO - actual date: 1474430521 21:02:01 INFO - starting webserver on 'localhost:35709' 21:02:01 INFO - SUITE-START | Running 2 tests 21:02:01 INFO - TEST-START | dromaeo_css 21:02:01 INFO - operating with platform_type : linux_ 21:02:01 INFO - Initialising browser for dromaeo_css test... 21:02:01 INFO - TEST-INFO | started process 3640 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp2_sSzH/profile http://localhost:35709/getInfo.html) 21:02:06 INFO - PROCESS | 3640 | __metrics Screen width/height:1600/1200 21:02:06 INFO - PROCESS | 3640 | colorDepth:24 21:02:06 INFO - PROCESS | 3640 | Browser inner width/height: 1024/697 21:02:06 INFO - PROCESS | 3640 | __metrics 21:02:06 INFO - PROCESS | 3640 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 21:02:06 INFO - PROCESS | 3640 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 21:02:07 INFO - PROCESS | 3640 | console.error: PushService: 21:02:07 INFO - PROCESS | 3640 | stateChangeProcessEnqueue: Error transitioning state 21:02:07 INFO - PROCESS | 3640 | UnknownError 21:02:07 INFO - TEST-INFO | 3640: exit 0 21:02:07 INFO - Browser initialized. 21:02:07 INFO - Running cycle 1/1 for dromaeo_css test... 21:02:07 INFO - TEST-INFO | started process 3720 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp2_sSzH/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:02:20 INFO - PROCESS | 3720 | RSS: Main: 174698496 21:02:20 INFO - PROCESS | 3720 | 21:02:45 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 21:02:49 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 21:03:03 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 21:03:09 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.13337 seconds. 21:03:11 INFO - PROCESS | 3720 | 1474430591126 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 21:03:20 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 21:03:28 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 21:03:30 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 21:03:42 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.06672 seconds. 21:03:44 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 21:03:46 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 21:03:48 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 21:03:50 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.16672 seconds. 21:03:54 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.05005 seconds. 21:04:07 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.03338 seconds. 21:04:12 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 21:04:18 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.03338 seconds. 21:04:23 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 21:04:29 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 21:04:31 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.01675 seconds. 21:04:33 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.16672 seconds. 21:04:39 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.0501 seconds. 21:04:43 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.05005 seconds. 21:04:45 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 21:04:47 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 21:04:49 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 21:04:52 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.16672 seconds. 21:05:08 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.15005 seconds. 21:05:12 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.01664 seconds. 21:05:20 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.01668 seconds. 21:05:24 INFO - PROCESS | 3720 | Cycle 1(1): loaded http://localhost:35709/tests/dromaeo/cssquery-dojo.html (next: http://localhost:35709/tests/dromaeo/cssquery-ext.html) 21:05:25 INFO - PROCESS | 3720 | RSS: Main: 323108864 21:05:25 INFO - PROCESS | 3720 | 21:05:35 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 21:05:38 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 21:06:12 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 21:06:20 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.03338 seconds. 21:06:25 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 21:07:01 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 21:07:13 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 21:07:26 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 21:07:27 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 21:07:28 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 2.01673 seconds. 21:07:38 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 21:07:44 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 21:07:45 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 21:07:47 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.00007 seconds. 21:07:54 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 21:07:56 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 21:08:02 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 21:08:04 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 21:08:06 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 21:08:14 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 21:08:16 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.01668 seconds. 21:08:28 INFO - PROCESS | 3720 | Cycle 1(1): loaded http://localhost:35709/tests/dromaeo/cssquery-ext.html (next: http://localhost:35709/tests/dromaeo/cssquery-jquery.html) 21:08:29 INFO - PROCESS | 3720 | RSS: Main: 273481728 21:08:29 INFO - PROCESS | 3720 | 21:08:32 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 21:08:36 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 21:08:39 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 21:08:47 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 21:08:49 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 21:08:53 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.00419 seconds. 21:09:00 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 21:09:10 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 21:09:12 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 21:09:14 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 21:09:16 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 21:09:18 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 21:09:32 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 21:09:53 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 21:09:54 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 21:09:56 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 21:09:58 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 21:10:00 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.01674 seconds. 21:10:30 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.00007 seconds. 21:10:34 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 21:10:40 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 21:10:42 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 21:10:49 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 21:10:51 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 21:10:53 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 21:11:01 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 21:11:04 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 21:11:27 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 21:11:31 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 21:11:31 INFO - PROCESS | 3720 | Cycle 1(1): loaded http://localhost:35709/tests/dromaeo/cssquery-jquery.html (next: http://localhost:35709/tests/dromaeo/cssquery-mootools.html) 21:11:31 INFO - PROCESS | 3720 | RSS: Main: 241336320 21:11:31 INFO - PROCESS | 3720 | 21:11:35 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.03354 seconds. 21:11:48 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 21:12:02 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 21:12:04 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 21:12:12 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 21:12:15 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 21:12:16 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 21:12:27 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.03339 seconds. 21:12:51 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 21:13:01 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 21:13:19 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 21:13:29 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 21:13:37 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 21:13:39 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 21:13:53 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 21:13:55 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 21:14:04 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 21:14:18 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 21:14:22 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 21:14:34 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.01675 seconds. 21:14:34 INFO - PROCESS | 3720 | Cycle 1(1): loaded http://localhost:35709/tests/dromaeo/cssquery-mootools.html (next: http://localhost:35709/tests/dromaeo/cssquery-prototype.html) 21:14:34 INFO - PROCESS | 3720 | RSS: Main: 218050560 21:14:34 INFO - PROCESS | 3720 | 21:14:51 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.01675 seconds. 21:15:02 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 21:15:03 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 21:15:09 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 21:15:15 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 21:15:42 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 21:15:50 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 21:15:55 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 21:15:56 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 21:15:58 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 21:16:00 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 21:16:02 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 21:16:04 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 21:16:09 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.05003 seconds. 21:16:13 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 21:16:15 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 21:16:20 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 21:16:22 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.01282 seconds. 21:16:24 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 21:16:27 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.06671 seconds. 21:16:31 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 21:16:33 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 21:16:37 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 21:16:39 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 21:16:43 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 21:16:45 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 21:17:01 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 21:17:03 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 21:17:05 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 21:17:07 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 21:17:10 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 21:17:11 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.0334 seconds. 21:17:15 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 21:17:17 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 21:17:27 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.03338 seconds. 21:17:31 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 21:17:35 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 21:17:37 INFO - PROCESS | 3720 | Cycle 1(1): loaded http://localhost:35709/tests/dromaeo/cssquery-prototype.html (next: http://localhost:35709/tests/dromaeo/cssquery-yui.html) 21:17:38 INFO - PROCESS | 3720 | RSS: Main: 220266496 21:17:38 INFO - PROCESS | 3720 | 21:17:41 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.03339 seconds. 21:17:50 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 21:17:51 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.01667 seconds. 21:18:03 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 21:18:05 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 21:18:09 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 21:18:19 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 21:18:23 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 21:18:41 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 21:18:47 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.01667 seconds. 21:18:49 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 21:18:52 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 21:18:53 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 21:18:58 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 21:19:01 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 21:19:08 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 21:19:10 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 21:19:13 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 21:19:24 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.00008 seconds. 21:19:28 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 21:19:36 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.00011 seconds. 21:19:50 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 21:19:52 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 21:19:54 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 21:19:56 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 21:19:58 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 21:20:00 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 21:20:12 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 21:20:21 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 21:20:28 INFO - PROCESS | 3720 | [GFX1-]: Refresh driver waiting for the compositor for 1.01667 seconds. 21:20:41 INFO - PROCESS | 3720 | Cycle 1(1): loaded http://localhost:35709/tests/dromaeo/cssquery-yui.html (next: http://localhost:35709/tests/dromaeo/cssquery-dojo.html) 21:20:41 INFO - PROCESS | 3720 | RSS: Main: 309374976 21:20:41 INFO - PROCESS | 3720 | 21:20:41 INFO - PROCESS | 3720 | __start_tp_report 21:20:41 INFO - PROCESS | 3720 | _x_x_mozilla_page_load 21:20:41 INFO - PROCESS | 3720 | _x_x_mozilla_page_load_details 21:20:41 INFO - PROCESS | 3720 | |i|pagename|runs| 21:20:41 INFO - PROCESS | 3720 | |0;dojo.html;2586.34048317679;2623.147477069953;2642.9824517560746;2647.272000199946;2668.799840011998;4595.517470665583;4661.603763680083;4720.433547974244;4729.550692684194;4744.667873248874;4689.7655117244085;4719.150552900476;4739.73931433771;4744.762761861911;4801.567858892699;4314.741115533062;4324.97837510812;4363.08375241199;4371.978140109295;4395.099004704028;4316.07204451043;4371.475422949251;4395.714278571899;4462.352958820969;4480.350349199364;4422.601965823075;4441.844535441261;4475.865724028285;4499.280115181556;4500.324951257305;4232.470941132358;4264.7227930184745;4271.145770845843;4367.890802729958;4368.781560921973;4251.171021650779;4258.6380157686635;4263.616274535307;4269.978650106761;4305.590968857953;4633.235516139833;4635.582797548237;4669.906601867943;4700.976495117537;4703.318018887277;5023.2214006829;5037.0429618372755;5044.924326135111;5052.671576347526;5104.51507106828;4913.992631510568;4931.052234367405;4979.7261150636705;5045.495450454961;5057.418396884369;4680.7191568506;4715.127701375257;4721.362616046858;4724.645651576146;4787.521247875185;5167;5167.638265321467;5174.197999310113;5179.015986962465;5241.0566098101945;4847.442544107403;4888.462269150391;4927.162382395001;4963.8014479421145;4978.452370239271;5065.240213967934;5068.847934561969;5068.949310506921;5070.771815268248;5117.155669314521;5045.924311135336;5047.697138171721;5048.924266136011;5052.418971818214;5094.159463688524;4885.975570122127;4940.184869496493;4941.234108713155;5026.648134630541;5093.5161159690515;4175.394567787654;4222.218889505451;4263.317869140985;4267.338562522814;4267.914641707211;3687.465317528943;3693.8707145249787;3723.925521489555;3738.4953031340965;3791.0711875590655;4297.05464797744;4304.117655880537;4304.870853874389;4312.827486900552;4370.519242883281;4921.360223170965;4968.850934471971;4994.275830004629;5017.247412888096;5083.8983220335385;4315.697901146952;4323.113761678848;4341.978290108593;4353.912921741611;4391.780410979438;4550.522195169526;4554.407926969473;4555.453345598549;4601.907961840811;4611.884702882455;4754.120487709784;4766.165920963748;4766.594839438687;4771.427428708577;4780.856574302777;4887.706737595756;4932.827351042696;4978.975105124451;4994.4006719192475;5064.645474816727;4737.218358970869;4740.928886066643;4741.952580474152;4791.305260737243;4804.039192161512;4953.975230123826;4962.255661650637;4962.280469331855;4965.577925876341;5016.2224855147515;4916.557509824133;4958.206686930074;5025.396952365593;5034.874128146679;5089.770960306869;4894.19245824435;4905.950940490549;4935.703857768545;4937.136001199704;4968.602511798975;4892.192788190051;4899.877503062452;4905.190643543918;4906.334955903274;4961.957988822388;4707.693999889996;4764;4831.879203019953;4834.105690447279;4900.240462728283;4878.585320247818;4893.339399181065;4927;4945.357103576512;4945.975270123627;4739.0521895621705;4792.568668819684;4794.232922732346;4847.563719265283;4849.830255941025 21:20:41 INFO - PROCESS | 3720 | |1;ext.html;556.89419010388;569.2940753465703;573.3062993777529;582.4263100845666;583.7256489449957;29153.70846291539;36945.630543694555;37588.43617345742;37795.24409511815;41801.372979405336;39206.4119038214;39588;39616;39659.801700991455;39692;7598.468107232482;7736.961315193416;7744.264294892005;7748.1864404237585;7753.767386978399;7161;7340.632968351561;9664.130228279453;9949.253805964545;9949.651762188325;10039.799204015952;10112.191024718042;10123.08892199702;10123.1901447884;10123.898761012406;9876.407415555053;9937.701868943943;9959.107769523767;9962.352447090916;9998.05018523239;6951.200611929642;7136.250693677193;9490.715278541655;9832.065953734455;9834.655787047419;36745;37116.07209819749;37122;37130.25739485222;37144.628553714385;2810.789190810697;2823.449427361665;2834.4189441164513;2892.103447931148;3225.225945773021;26845;27761.86119069412;27887;28358.858205709046;28376.716232837614;3193.7764356495054;3198.2643991881996;3253.7397008239286;3268.9019329420157;3320.25294308781;251873;252310;252761;252924.14765483487;253450;50627;50958.235626465634;50965.745171273906;50985.23522147171;51219.48780512222;52627.47372526302;52744.472555274726;52879.735601322514;53072.46927530752;53169.734151328994;14413.49552765669;14858;14869.182194979163;14881.107133571804;14887.10677359362;9465.90534094664;11529.596464123715;11560.421978901022;11569.826452603218;11585.420728963518;12087.697807554881;12480.063995200215;12646.683832904251;12876.356182190853;12948.28784416866;5436.728163591804;5617.494425501724;5672.149177623307;5763.2507773989955;5791.131330300489;761.588742079271;881.8104107616894;885.3094586222757;951.8952915179325;962.8603852441357;25473;26974.730252697613;27368.178954631392;27770.861145694143;27816.582751258746;882.5543100734088;894.5706061090583;937.3485427627734;969.0793745941384;1004.7940172264668;14227.28863556818;14298.928505357406;14418.567442976728;14799.926000370144;14821.92589037048;364026;366871;368127;368661.07406037045;370220;47709.76145119252;47884.0568117621;47901.40739444556;47977.28034079492;48009.5199048005;986.9555869986011;1007.4912169354576;1098.917566197295;1109.1847492093143;1150.8388825564427;25002.37494062663;28137.091210845134;28408.289792755346;28468.715312847435;28961;40561.59438405578;40907.795461022506;41042.58957410388;41083.1783364337;41102.766916992536;296719;297052;299362;299607;299804;21814.672779907996;22669.659955101015;22823.771762282828;23534.882325588263;35313.93721255708;38240.61759382371;38871.805640971616;40822;41350.79324603358;41998;35058.298834023684;35306.470402943465;35423.468647969785;35575.82212088923;35592;35091.64908350986;35633.28733425369;35857.46213806743;35872.64127358797;36254.637453625124;33778.66221337755;34322.31355372829;34389.65610343865;34579.48130778091;34885.65114348824;30208;31370.21574460657;31651.683483165794;31867.681323186473;32236.516452253705;24630.630540541548;24739.25782226536;24814;25345.112921048414;25527.48945021052 21:20:41 INFO - PROCESS | 3720 | |2;jquery.html;337.1066673315715;355.6283683550689;366.9651383118603;373.20879734961886;379.63744623884196;12340.753184936297;13632.318384080781;15173.696526069485;17196.656066878655;18035.63928721425;13654.931725341385;13694.109882857632;13730.107543009723;13731;14228.502002429917;4837.879053023667;4949.5545400913825;4953.900921981558;4987.376577927759;5061.266116413118;3581.3374525712698;3777.3011992781285;4977.0792403405485;5060.873478163038;5062.367204099488;3783.205526839367;3787.7727336359767;3792.563855156665;3795.905102372435;5063.139266324715;5814.069748840187;5901.085331773582;5905.084711869667;5906;5910.615809972381;5015.598752099823;5181.53366197044;5182.689038657655;5218.139007063868;5257.185136303879;20736.481587960272;20788.58422831535;20950.05724742375;21003.579928401497;21126.15495380183;1908.895010774407;1937.951551211231;2103.8001389867936;2160.7191065161587;2214.833887458431;13259.86740132596;13283.136596121221;13554.254516001616;13583;13757.31213439334;1690.4421540891599;1729.705949988505;1738.0875040603657;1783.8483728883061;1851.6111616560538;64905;65109.674451627914;65747.67126164386;73554.63222683853;73905.63047184783;15733.763993540106;15750.68498630021;15906.681866362607;16327.34690612386;16344;28388.85805570959;28430.882441160375;28432.857835711104;28482.430351393272;28581.857090714413;8582.871256931026;8643.870341944876;8782.648694052166;8969.416987895765;9309;7441.737027826685;7527.9247207528315;7567.583782891884;7678.8080297993;7757.418193635387;8029.879551806727;8250.298724608356;8307.252347288651;8324.292435143081;8349;3221.7583681224;3244.464663330524;3277.6886195811358;3281.950770738441;3339.632640409553;358.7560458887981;362.74607774557916;763.9618019099023;788.341734651561;872.6116877989234;10254.692359229235;10297;10396.792064158675;10503;10798.298110622783;367.9852805887788;377.8016541315776;378.5778856574899;382.521847690387;387.4788409589098;5495.120780675073;5577.330720313507;5579.581531385163;5604.719764011783;5777.653340799566;397090.0581988344;397497;400526;402292;403235;82150;82674.17325826664;83135.58432207922;84065.15934840574;85160;403.6810919373662;412.52147508889544;413.0479245339461;413.3923132994493;414.0311670690538;7528.322450979438;9888.901110988798;9983.550740216853;10070.597176112873;10196.592136314464;37496.81251593724;38838.834834955;38926;38932.41601376038;39211;47560.2865957004;49411.258831118284;49546.50453495419;49570.25644615261;49642.560764670816;7154.570725756263;7263.636818159177;7362.190159082604;7460.097675093034;7975.401844861774;8032.87950680752;11077.446127693744;11102.777944441228;11705.941470292593;11849.229800062969;9687.273454491082;9911.306208565331;10166.339187952759;10188.898111018794;10364.896351036394;10020.198384129399;10184.338018028804;10449.738756530844;10473.633422830164;10524.263301568817;9957.651482198089;10008.299419040597;10020.949895250476;10021.39871607706;10292.330998485075;7470.738524151629;7717.1896950819355;7886;7912.4856884302335;7943.285104340637;9224.538773061455;9982.401055936667;10090.34412763168;10092.444915529422;10152.319454898376 21:20:41 INFO - PROCESS | 3720 | |3;mootools.html;1309.3976770685488;1337.545234620229;1354.5191457032754;1426.386653738892;1451.7024010077935;6876.553024053433;7107.786766397004;7318.231585683507;7885.526868387894;7888.290053895155;7514.098308203023;7745.728899488519;7755.185705500926;7995.800104997378;8055.234752698499;2184.377452426055;2212.104097840377;2212.822974162063;2216.2713083794665;2219.411855858199;1843.1245158549648;2130.9893450532804;2141.4325203821;2146.796054374832;2215.4129155773735;1850.1489314915154;2086.436662101232;2146.2702681088504;2159.1039718516795;2169.652855543113;2111.366590022987;2122.352682431858;2124.097258665061;2132.69075983983;2142.678598210265;2071.8964051797348;2114.0233881321014;2116.3016204652426;2121.543868068373;2124.0866427436194;7942.444028917978;8027.95986020072;8029.638666260033;8033.879491807627;8034.678612855479;4379.978100109511;4499.460064792213;4500.662450316239;4502.909941801178;4503.729776213438;2912.359280958186;2998.9353779408434;3011.713887180714;3048.2379405148713;3052.7099925507264;936.166811537732;995.6863587969839;1013.9290249682525;1017.6641708236265;1026.0816569170624;11016.834747478793;11042.33745975244;11042.613508527164;11046.944765276203;11055.22613417053;3901.3367727486398;4064.7154699170774;4244.830206791694;4250.978745106255;4258.42511260982;9317.90682093184;9337.066293370744;9340.486273255035;9352.625894964125;9357.157855793012;4583.770811459413;4586.72479651222;4609.078184363141;4610.216263235258;4615.561521655437;4113.9098138993195;4143.482064741907;4156.501219853637;4156.625903668624;4158.688098392633;3566.589842168132;3682.4660424238336;3722.580322790208;3725.348064088828;3741.1021354874633;1154.3016475032484;1170.6605084525563;1173.9589114380956;1178.7171078941165;1189.2567145534042;564.4636353246912;622.539320902538;678.7522554267756;708.129001328369;728.5009768308726;5068.873278168076;5085.7711402986215;5095.15929871567;5154.278401023859;5321.840344789663;863.1196179896596;867.6876324523292;872.2455076358904;872.6901949807853;877.381231627646;3237.14215732831;3372.696457318853;3376.4766461198556;3423.4693622488057;3493.7903725776537;8965;9418.10527999839;9424.811503769886;9429.952850235704;9435.764105897408;9531.332806703464;9718.319717619699;9774.511274436392;9827.705168844946;9831.655892043744;7279.961610748999;7359.190489046201;7362.558246505119;7364.521306115193;7366.226546212571;4784.258439941815;4789.976050119727;4830.512705306831;4845.830395936126;5039.521245481674;8931.151540603632;9043.050479699537;9058.818823623622;9066.637334506546;9099.1810737034;9002.909970900208;9083.818323633623;9167;9181.586828592595;9205.76985575366;6086.908696369646;6244.344343844013;6284.4344009039405;6297.370262973666;6314.842128946813;7354.595497247703;7527.774166775005;7596.658150383357;7602.657880395506;7612.543247405173;6584.50616203777;6674.6662666865495;6734.02339343316;6838.14523184602;7022.08712867324;6574.737010519526;6687.565308254948;6697.899531506934;6708.228553716385;6732.831679208059;6232.532560057924;6329.335419780857;6331.366863313632;6331.525135614939;6334.524910631629;4440.111977604427;4565.543445655408;4636.489986101594;4637.165310244189;4648.140094082605;4530;4549.727016378895;4657.510961349145;4698.318743782064;4837.3227748116 21:20:41 INFO - PROCESS | 3720 | |4;prototype.html;273.4408135363182;277.0966648725156;285.32378263515466;288.6334355368682;289.8463814178486;4584.174848527264;4765.973606759707;4775.426948766149;4867.318575399442;4887.511248875111;4562.224421848286;4672.229082201433;4702.836695120241;4733.644976626758;4781.617470602352;3761.8307176177;3785.1483416231335;3785.9621403785886;3787.147891724354;3788.3938569828833;3541.8052007139595;3794.582595914447;3794.8236046825436;3799.278137153946;3799.715021373408;3466.3933811582997;3737.4767532545466;3753.099256178511;3755.4179102239195;3760.172761992357;3735.1595890924596;3739.8130093495215;3744.588095309514;3744.7565908216015;3747.737658363902;3301.7028467437804;3304.2400247943106;3307.30546585215;3312.801231926092;3313.93372132556;4596.172688915994;4651.837185698484;4653.813847446098;4665.836695715668;4739.73931433771;4236.703430759848;4247.511536173349;4263.5736426357425;4267.935980960257;4275.482203817631;4989.750512474362;4990.800367985276;4994.175960966436;5002.649814512985;5004.5495905368325;3451.8964431067106;3626.4197728363333;3629.8548058077645;3634.4548317752283;3636.5636123665063;5177.948220517785;5208.739563021872;5214.556762675215;5223.582113430916;5242.55438287746;3606.585242697071;3635.818209089588;3637.618050104754;3639.252364464812;3641.5630124385243;3682.2451397463456;3705.1292946157632;3717.4052151655605;3718.0704823794053;3724.5902950675404;3972.3445631470486;4131.338985762263;4223.260929337353;4275.187714334266;4279.40088387626;5014.774335154927;5019.372578427697;5023.572996295283;5023.773930173151;5038.017586570657;1189.9881001190051;1219.603628820637;1241.875812418751;1270.987290127087;1272.5355245335386;823.6375994562493;850.2900078434453;856.3235044314921;861.6596444404425;862.8317478091838;4405.493368262627;4424.911501769947;4426.84506042287;4449.710768800082;4456.353828694886;5129.768855474659;5140.922886156711;5154.768035438415;5179.352580927384;5191.688498690015;4270.231358355526;4447.421835161473;4489.2592722201125;4494.213512635276;4527.9850466542075;2745.8627068646488;2745.890164393442;2751.2984189032068;2756.476269508787;2773.5285001549787;4862.12808068896;4867.537583929521;4882.584980276716;4915.557599816034;4915.803367865316;5179.8446046618665;5262.789698284044;5289.471052894757;5292.788288468418;5312.715532321062;4876.5854902332985;4881.585065269421;4890.877728056827;4897.2654101885;4906.484819093944;4995.486603644513;5166.199239117943;5172.431032586488;5193.974030129976;5234.2933703949475;5087.847364579068;5099.796008159632;5101.260317254051;5103.209002604603;5127.41034781005;4827.503473923095;4869.732164730974;4872.3909511311085;4877.195262781602;4888.364512613338;3558.2705545363656;3559.2169722660974;3589.712822974208;3592.5688917329044;3598.0465176727666;4972.527609877201;5185.977468787251;5198.6880787152895;5221.190715439113;5242.135047717236;5008.449070602158;5034.370703662043;5048.293238946624;5056.721880296619;5058.190689489811;4957.0001078673695;5066.214736715815;5078.136716758234;5086.669366491166;5116.641835071509;4775.594074503706;4919.819243381491;4939.827106051271;4982.202847544376;5029.547340739351;4906.803727850846;5068.417132029716;5073.974630126826;5077.263796749525;5082.170418649357;4942.308076869169;4944.826931057396;4953.603711703127;4956.752162391794;4964.156093464047 21:20:41 INFO - PROCESS | 3720 | |5;yui.html;555.2393221286837;578.2136294639291;586.961847479914;603.0803025386285;608.6074481959137;12174.147809653317;12789.744205115892;12795.936020319908;12818.935905320484;13751;13524.391402386891;14253.78619320711;14258.500952466666;14304.35630396632;14308.642283942907;582.9650220986755;724.6231959381132;744.151667099507;747.3572727454385;747.8279995601015;599.5533327670892;712.0565251042364;730.817295676081;739.2275072549172;743.9256074392572;780.1457404142454;782.636074225485;782.7534326687104;782.8003859015971;782.8669126248551;598.5570677698472;618.7927044440073;772.0735117858592;772.1583474013324;772.2663469703748;544.0370544136856;559.0496156533909;564.0495764636601;565.0761005755578;566.0744682444192;12608.873911260862;12662.366881655973;12677.556285529961;12696.111272210948;12778.169418987829;11650.30098194111;11736.53053877844;11832.408379581073;11926.463309151022;12525.812112818316;10867.239293249475;11129.666110016713;11255.012167982532;11407.885921140765;11605.535778568847;3918.4122381642983;3984.860529881469;4073.633372996415;4078.12320351123;4087.2847251730827;66007;66632.33367666358;66789.33210667879;68012.31987680159;68573.65713171402;8628.309735221168;8701.608427620773;8906.242969347548;9443.819522559543;9995.850062249072;15870.9206453967;16620.66758664844;16649.33402663904;17147.142642867806;17178.484645460656;6375.203099612549;6744.898826517606;6849.452043836481;6872.209695885037;6927.515073944775;3543.982280088583;3623.5108260385036;3695.2978934002454;3696.075981004749;3704.0369503929173;2885.3075261936983;3019.1093627379973;3021.6676165621766;3169.176014236315;3323.4848598467274;552.9253550770675;562.2943206276097;565.564515323207;574.12159396124;577.9971100144472;489.207483876123;489.93385892904;500.12228538913973;505.91399462091533;505.93169922060054;7390.815229619195;7599.392048636096;7762.456628035983;7821.882671759814;7930.405219608552;459.46701825881814;476.89508308172145;481.9614430845524;497.72873783787924;497.8033676697684;3554.822258887097;3565;3653.725970552219;3696.77819330841;3851.595582463801;63389;63592.36407635957;65156.02265966014;96096.51951740196;106366.46816765866;72660.27339726535;90007;91050.54474727716;91510;92548;75812;77195;80918.26392598754;81750;82178.58910705408;7072;7116.679749411185;7164.534305270141;7238.298999735163;7489.21363256872;44349.77825110854;45462;46070;46347.30479042749;46714;60831.69584152051;62469.68765156145;63200.683996579726;63738.362616375096;63957.68021159864;9626.47054412014;9655.372400793925;9678.785915422986;9705.708828735149;9970.55132519024;9898.604055837686;9956.751081223027;10012.599496020077;10037.49812509357;10137.64518241858;9692.41845489273;10009.649662261785;10032.147267482345;10124.544395502367;10127.24045696561;8263.586820658822;8755.693550725695;9270.119338662817;9346.672866449642;9492.905070949202;9385.85921211196;9468.763280917756;9607.51962401891;9698.27262955267;9789.608415663293;7703.229677032252;8254.174582541698;8310.459820111673;8432.704855330034;8560.14398560139;7848.447900217974;8033.35733141359;8099.635516401894;8193.754187374388;8452.577371131296 21:20:41 INFO - PROCESS | 3720 | __end_tp_report 21:20:41 INFO - PROCESS | 3720 | __start_cc_report 21:20:41 INFO - PROCESS | 3720 | _x_x_mozilla_cycle_collect,1315 21:20:41 INFO - PROCESS | 3720 | __end_cc_report 21:20:41 INFO - PROCESS | 3720 | __startTimestamp1474431641734__endTimestamp 21:20:41 INFO - PROCESS | 3720 | 21:20:41 INFO - PROCESS | 3720 | ------- Summary: start ------- 21:20:41 INFO - PROCESS | 3720 | Number of tests: 6 21:20:41 INFO - PROCESS | 3720 | 21:20:41 INFO - PROCESS | 3720 | [#0] dojo.html Cycles:165 Average:4653.53 Median:4771.43 stddev:482.98 (10.1%) stddev-sans-first:456.42 21:20:41 INFO - PROCESS | 3720 | Values: 2586.3 2623.1 2643.0 2647.3 2668.8 4595.5 4661.6 4720.4 4729.6 4744.7 4689.8 4719.2 4739.7 4744.8 4801.6 4314.7 4325.0 4363.1 4372.0 4395.1 4316.1 4371.5 4395.7 4462.4 4480.4 4422.6 4441.8 4475.9 4499.3 4500.3 4232.5 4264.7 4271.1 4367.9 4368.8 4251.2 4258.6 4263.6 4270.0 4305.6 4633.2 4635.6 4669.9 4701.0 4703.3 5023.2 5037.0 5044.9 5052.7 5104.5 4914.0 4931.1 4979.7 5045.5 5057.4 4680.7 4715.1 4721.4 4724.6 4787.5 5167.0 5167.6 5174.2 5179.0 5241.1 4847.4 4888.5 4927.2 4963.8 4978.5 5065.2 5068.8 5068.9 5070.8 5117.2 5045.9 5047.7 5048.9 5052.4 5094.2 4886.0 4940.2 4941.2 5026.6 5093.5 4175.4 4222.2 4263.3 4267.3 4267.9 3687.5 3693.9 3723.9 3738.5 3791.1 4297.1 4304.1 4304.9 4312.8 4370.5 4921.4 4968.9 4994.3 5017.2 5083.9 4315.7 4323.1 4342.0 4353.9 4391.8 4550.5 4554.4 4555.5 4601.9 4611.9 4754.1 4766.2 4766.6 4771.4 4780.9 4887.7 4932.8 4979.0 4994.4 5064.6 4737.2 4740.9 4742.0 4791.3 4804.0 4954.0 4962.3 4962.3 4965.6 5016.2 4916.6 4958.2 5025.4 5034.9 5089.8 4894.2 4906.0 4935.7 4937.1 4968.6 4892.2 4899.9 4905.2 4906.3 4962.0 4707.7 4764.0 4831.9 4834.1 4900.2 4878.6 4893.3 4927.0 4945.4 4946.0 4739.1 4792.6 4794.2 4847.6 4849.8 21:20:41 INFO - PROCESS | 3720 | 21:20:41 INFO - PROCESS | 3720 | [#1] ext.html Cycles:180 Average:45791.05 Median:35612.64 stddev:81420.41 (228.6%) stddev-sans-first:81577.98 21:20:41 INFO - PROCESS | 3720 | Values: 556.9 569.3 573.3 582.4 583.7 29153.7 36945.6 37588.4 37795.2 41801.4 39206.4 39588.0 39616.0 39659.8 39692.0 7598.5 7737.0 7744.3 7748.2 7753.8 7161.0 7340.6 9664.1 9949.3 9949.7 10039.8 10112.2 10123.1 10123.2 10123.9 9876.4 9937.7 9959.1 9962.4 9998.1 6951.2 7136.3 9490.7 9832.1 9834.7 36745.0 37116.1 37122.0 37130.3 37144.6 2810.8 2823.4 2834.4 2892.1 3225.2 26845.0 27761.9 27887.0 28358.9 28376.7 3193.8 3198.3 3253.7 3268.9 3320.3 251873.0 252310.0 252761.0 252924.1 253450.0 50627.0 50958.2 50965.7 50985.2 51219.5 52627.5 52744.5 52879.7 53072.5 53169.7 14413.5 14858.0 14869.2 14881.1 14887.1 9465.9 11529.6 11560.4 11569.8 11585.4 12087.7 12480.1 12646.7 12876.4 12948.3 5436.7 5617.5 5672.1 5763.3 5791.1 761.6 881.8 885.3 951.9 962.9 25473.0 26974.7 27368.2 27770.9 27816.6 882.6 894.6 937.3 969.1 1004.8 14227.3 14298.9 14418.6 14799.9 14821.9 364026.0 366871.0 368127.0 368661.1 370220.0 47709.8 47884.1 47901.4 47977.3 48009.5 987.0 1007.5 1098.9 1109.2 1150.8 25002.4 28137.1 28408.3 28468.7 28961.0 40561.6 40907.8 41042.6 41083.2 41102.8 296719.0 297052.0 299362.0 299607.0 299804.0 21814.7 22669.7 22823.8 23534.9 35313.9 38240.6 38871.8 40822.0 41350.8 41998.0 35058.3 35306.5 35423.5 35575.8 35592.0 35091.6 35633.3 35857.5 35872.6 36254.6 33778.7 34322.3 34389.7 34579.5 34885.7 30208.0 31370.2 31651.7 31867.7 32236.5 24630.6 24739.3 24814.0 25345.1 25527.5 21:20:41 INFO - PROCESS | 3720 | 21:20:41 INFO - PROCESS | 3720 | [#2] jquery.html Cycles:180 Average:24946.58 Median:38882.42 stddev:66147.96 (170.1%) stddev-sans-first:66307.72 21:20:41 INFO - PROCESS | 3720 | Values: 337.1 355.6 367.0 373.2 379.6 12340.8 13632.3 15173.7 17196.7 18035.6 13654.9 13694.1 13730.1 13731.0 14228.5 4837.9 4949.6 4953.9 4987.4 5061.3 3581.3 3777.3 4977.1 5060.9 5062.4 3783.2 3787.8 3792.6 3795.9 5063.1 5814.1 5901.1 5905.1 5906.0 5910.6 5015.6 5181.5 5182.7 5218.1 5257.2 20736.5 20788.6 20950.1 21003.6 21126.2 1908.9 1938.0 2103.8 2160.7 2214.8 13259.9 13283.1 13554.3 13583.0 13757.3 1690.4 1729.7 1738.1 1783.8 1851.6 64905.0 65109.7 65747.7 73554.6 73905.6 15733.8 15750.7 15906.7 16327.3 16344.0 28388.9 28430.9 28432.9 28482.4 28581.9 8582.9 8643.9 8782.6 8969.4 9309.0 7441.7 7527.9 7567.6 7678.8 7757.4 8029.9 8250.3 8307.3 8324.3 8349.0 3221.8 3244.5 3277.7 3282.0 3339.6 358.8 362.7 764.0 788.3 872.6 10254.7 10297.0 10396.8 10503.0 10798.3 368.0 377.8 378.6 382.5 387.5 5495.1 5577.3 5579.6 5604.7 5777.7 397090.1 397497.0 400526.0 402292.0 403235.0 82150.0 82674.2 83135.6 84065.2 85160.0 403.7 412.5 413.0 413.4 414.0 7528.3 9888.9 9983.6 10070.6 10196.6 37496.8 38838.8 38926.0 38932.4 39211.0 47560.3 49411.3 49546.5 49570.3 49642.6 7154.6 7263.6 7362.2 7460.1 7975.4 8032.9 11077.4 11102.8 11705.9 11849.2 9687.3 9911.3 10166.3 10188.9 10364.9 10020.2 10184.3 10449.7 10473.6 10524.3 9957.7 10008.3 10020.9 10021.4 10292.3 7470.7 7717.2 7886.0 7912.5 7943.3 9224.5 9982.4 10090.3 10092.4 10152.3 21:20:41 INFO - PROCESS | 3720 | 21:20:41 INFO - PROCESS | 3720 | [#3] mootools.html Cycles:180 Average:5115.09 Median:4841.58 stddev:2921.02 (60.3%) stddev-sans-first:2915.21 21:20:41 INFO - PROCESS | 3720 | Values: 1309.4 1337.5 1354.5 1426.4 1451.7 6876.6 7107.8 7318.2 7885.5 7888.3 7514.1 7745.7 7755.2 7995.8 8055.2 2184.4 2212.1 2212.8 2216.3 2219.4 1843.1 2131.0 2141.4 2146.8 2215.4 1850.1 2086.4 2146.3 2159.1 2169.7 2111.4 2122.4 2124.1 2132.7 2142.7 2071.9 2114.0 2116.3 2121.5 2124.1 7942.4 8028.0 8029.6 8033.9 8034.7 4380.0 4499.5 4500.7 4502.9 4503.7 2912.4 2998.9 3011.7 3048.2 3052.7 936.2 995.7 1013.9 1017.7 1026.1 11016.8 11042.3 11042.6 11046.9 11055.2 3901.3 4064.7 4244.8 4251.0 4258.4 9317.9 9337.1 9340.5 9352.6 9357.2 4583.8 4586.7 4609.1 4610.2 4615.6 4113.9 4143.5 4156.5 4156.6 4158.7 3566.6 3682.5 3722.6 3725.3 3741.1 1154.3 1170.7 1174.0 1178.7 1189.3 564.5 622.5 678.8 708.1 728.5 5068.9 5085.8 5095.2 5154.3 5321.8 863.1 867.7 872.2 872.7 877.4 3237.1 3372.7 3376.5 3423.5 3493.8 8965.0 9418.1 9424.8 9430.0 9435.8 9531.3 9718.3 9774.5 9827.7 9831.7 7280.0 7359.2 7362.6 7364.5 7366.2 4784.3 4790.0 4830.5 4845.8 5039.5 8931.2 9043.1 9058.8 9066.6 9099.2 9002.9 9083.8 9167.0 9181.6 9205.8 6086.9 6244.3 6284.4 6297.4 6314.8 7354.6 7527.8 7596.7 7602.7 7612.5 6584.5 6674.7 6734.0 6838.1 7022.1 6574.7 6687.6 6697.9 6708.2 6732.8 6232.5 6329.3 6331.4 6331.5 6334.5 4440.1 4565.5 4636.5 4637.2 4648.1 4530.0 4549.7 4657.5 4698.3 4837.3 21:20:41 INFO - PROCESS | 3720 | 21:20:41 INFO - PROCESS | 3720 | [#4] prototype.html Cycles:180 Average:4169.76 Median:4770.70 stddev:1217.38 (25.5%) stddev-sans-first:1185.14 21:20:41 INFO - PROCESS | 3720 | Values: 273.4 277.1 285.3 288.6 289.8 4584.2 4766.0 4775.4 4867.3 4887.5 4562.2 4672.2 4702.8 4733.6 4781.6 3761.8 3785.1 3786.0 3787.1 3788.4 3541.8 3794.6 3794.8 3799.3 3799.7 3466.4 3737.5 3753.1 3755.4 3760.2 3735.2 3739.8 3744.6 3744.8 3747.7 3301.7 3304.2 3307.3 3312.8 3313.9 4596.2 4651.8 4653.8 4665.8 4739.7 4236.7 4247.5 4263.6 4267.9 4275.5 4989.8 4990.8 4994.2 5002.6 5004.5 3451.9 3626.4 3629.9 3634.5 3636.6 5177.9 5208.7 5214.6 5223.6 5242.6 3606.6 3635.8 3637.6 3639.3 3641.6 3682.2 3705.1 3717.4 3718.1 3724.6 3972.3 4131.3 4223.3 4275.2 4279.4 5014.8 5019.4 5023.6 5023.8 5038.0 1190.0 1219.6 1241.9 1271.0 1272.5 823.6 850.3 856.3 861.7 862.8 4405.5 4424.9 4426.8 4449.7 4456.4 5129.8 5140.9 5154.8 5179.4 5191.7 4270.2 4447.4 4489.3 4494.2 4528.0 2745.9 2745.9 2751.3 2756.5 2773.5 4862.1 4867.5 4882.6 4915.6 4915.8 5179.8 5262.8 5289.5 5292.8 5312.7 4876.6 4881.6 4890.9 4897.3 4906.5 4995.5 5166.2 5172.4 5194.0 5234.3 5087.8 5099.8 5101.3 5103.2 5127.4 4827.5 4869.7 4872.4 4877.2 4888.4 3558.3 3559.2 3589.7 3592.6 3598.0 4972.5 5186.0 5198.7 5221.2 5242.1 5008.4 5034.4 5048.3 5056.7 5058.2 4957.0 5066.2 5078.1 5086.7 5116.6 4775.6 4919.8 4939.8 4982.2 5029.5 4906.8 5068.4 5074.0 5077.3 5082.2 4942.3 4944.8 4953.6 4956.8 4964.2 21:20:41 INFO - PROCESS | 3720 | 21:20:41 INFO - PROCESS | 3720 | [#5] yui.html Cycles:180 Average:17138.82 Median:30720.15 stddev:25122.83 (81.8%) stddev-sans-first:25162.45 21:20:41 INFO - PROCESS | 3720 | Values: 555.2 578.2 587.0 603.1 608.6 12174.1 12789.7 12795.9 12818.9 13751.0 13524.4 14253.8 14258.5 14304.4 14308.6 583.0 724.6 744.2 747.4 747.8 599.6 712.1 730.8 739.2 743.9 780.1 782.6 782.8 782.8 782.9 598.6 618.8 772.1 772.2 772.3 544.0 559.0 564.0 565.1 566.1 12608.9 12662.4 12677.6 12696.1 12778.2 11650.3 11736.5 11832.4 11926.5 12525.8 10867.2 11129.7 11255.0 11407.9 11605.5 3918.4 3984.9 4073.6 4078.1 4087.3 66007.0 66632.3 66789.3 68012.3 68573.7 8628.3 8701.6 8906.2 9443.8 9995.9 15870.9 16620.7 16649.3 17147.1 17178.5 6375.2 6744.9 6849.5 6872.2 6927.5 3544.0 3623.5 3695.3 3696.1 3704.0 2885.3 3019.1 3021.7 3169.2 3323.5 552.9 562.3 565.6 574.1 578.0 489.2 489.9 500.1 505.9 505.9 7390.8 7599.4 7762.5 7821.9 7930.4 459.5 476.9 482.0 497.7 497.8 3554.8 3565.0 3653.7 3696.8 3851.6 63389.0 63592.4 65156.0 96096.5 106366.5 72660.3 90007.0 91050.5 91510.0 92548.0 75812.0 77195.0 80918.3 81750.0 82178.6 7072.0 7116.7 7164.5 7238.3 7489.2 44349.8 45462.0 46070.0 46347.3 46714.0 60831.7 62469.7 63200.7 63738.4 63957.7 9626.5 9655.4 9678.8 9705.7 9970.6 9898.6 9956.8 10012.6 10037.5 10137.6 9692.4 10009.6 10032.1 10124.5 10127.2 8263.6 8755.7 9270.1 9346.7 9492.9 9385.9 9468.8 9607.5 9698.3 9789.6 7703.2 8254.2 8310.5 8432.7 8560.1 7848.4 8033.4 8099.6 8193.8 8452.6 21:20:41 INFO - PROCESS | 3720 | -------- Summary: end -------- 21:20:41 INFO - PROCESS | 3720 | 21:20:42 INFO - TEST-INFO | 3720: exit 0 21:20:42 INFO - TEST-OK | dromaeo_css | took 1121009ms 21:20:42 INFO - TEST-START | kraken 21:20:42 INFO - operating with platform_type : linux_ 21:20:42 INFO - Initialising browser for kraken test... 21:20:42 INFO - TEST-INFO | started process 4026 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpz9Py3C/profile http://localhost:35709/getInfo.html) 21:20:47 INFO - PROCESS | 4026 | __metrics Screen width/height:1600/1200 21:20:47 INFO - PROCESS | 4026 | colorDepth:24 21:20:47 INFO - PROCESS | 4026 | Browser inner width/height: 1024/697 21:20:47 INFO - PROCESS | 4026 | __metrics 21:20:47 INFO - PROCESS | 4026 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 21:20:47 INFO - PROCESS | 4026 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 21:20:48 INFO - PROCESS | 4026 | console.error: PushService: 21:20:48 INFO - PROCESS | 4026 | stateChangeProcessEnqueue: Error transitioning state 21:20:48 INFO - PROCESS | 4026 | UnknownError 21:20:48 INFO - TEST-INFO | 4026: exit 0 21:20:48 INFO - Browser initialized. 21:20:48 INFO - Running cycle 1/1 for kraken test... 21:20:48 INFO - TEST-INFO | started process 4106 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpz9Py3C/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:21:01 INFO - PROCESS | 4106 | RSS: Main: 174563328 21:21:01 INFO - PROCESS | 4106 | 21:21:03 INFO - PROCESS | 4106 | Cycle 1(1): loaded http://localhost:35709/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:35709/tests/kraken/driver.html?testName=audio-beat-detection) 21:21:04 INFO - PROCESS | 4106 | RSS: Main: 233521152 21:21:04 INFO - PROCESS | 4106 | 21:21:07 INFO - PROCESS | 4106 | Cycle 1(1): loaded http://localhost:35709/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:35709/tests/kraken/driver.html?testName=audio-dft) 21:21:08 INFO - PROCESS | 4106 | RSS: Main: 218689536 21:21:08 INFO - PROCESS | 4106 | 21:21:11 INFO - PROCESS | 4106 | Cycle 1(1): loaded http://localhost:35709/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:35709/tests/kraken/driver.html?testName=audio-fft) 21:21:12 INFO - PROCESS | 4106 | RSS: Main: 300281856 21:21:12 INFO - PROCESS | 4106 | 21:21:14 INFO - PROCESS | 4106 | Cycle 1(1): loaded http://localhost:35709/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:35709/tests/kraken/driver.html?testName=audio-oscillator) 21:21:14 INFO - PROCESS | 4106 | RSS: Main: 209928192 21:21:14 INFO - PROCESS | 4106 | 21:21:17 INFO - PROCESS | 4106 | Cycle 1(1): loaded http://localhost:35709/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:35709/tests/kraken/driver.html?testName=imaging-gaussian-blur) 21:21:17 INFO - PROCESS | 4106 | RSS: Main: 213188608 21:21:17 INFO - PROCESS | 4106 | 21:21:20 INFO - PROCESS | 4106 | Cycle 1(1): loaded http://localhost:35709/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:35709/tests/kraken/driver.html?testName=imaging-darkroom) 21:21:21 INFO - PROCESS | 4106 | RSS: Main: 250105856 21:21:21 INFO - PROCESS | 4106 | 21:21:24 INFO - PROCESS | 4106 | Cycle 1(1): loaded http://localhost:35709/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:35709/tests/kraken/driver.html?testName=imaging-desaturate) 21:21:25 INFO - PROCESS | 4106 | RSS: Main: 249802752 21:21:25 INFO - PROCESS | 4106 | 21:21:28 INFO - PROCESS | 4106 | Cycle 1(1): loaded http://localhost:35709/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:35709/tests/kraken/driver.html?testName=json-parse-financial) 21:21:29 INFO - PROCESS | 4106 | RSS: Main: 249262080 21:21:29 INFO - PROCESS | 4106 | 21:21:30 INFO - PROCESS | 4106 | Cycle 1(1): loaded http://localhost:35709/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:35709/tests/kraken/driver.html?testName=json-stringify-tinderbox) 21:21:31 INFO - PROCESS | 4106 | RSS: Main: 212033536 21:21:31 INFO - PROCESS | 4106 | 21:21:33 INFO - PROCESS | 4106 | Cycle 1(1): loaded http://localhost:35709/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:35709/tests/kraken/driver.html?testName=stanford-crypto-aes) 21:21:33 INFO - PROCESS | 4106 | RSS: Main: 219308032 21:21:33 INFO - PROCESS | 4106 | 21:21:36 INFO - PROCESS | 4106 | Cycle 1(1): loaded http://localhost:35709/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:35709/tests/kraken/driver.html?testName=stanford-crypto-ccm) 21:21:36 INFO - PROCESS | 4106 | RSS: Main: 223887360 21:21:36 INFO - PROCESS | 4106 | 21:21:39 INFO - PROCESS | 4106 | Cycle 1(1): loaded http://localhost:35709/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:35709/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 21:21:40 INFO - PROCESS | 4106 | RSS: Main: 224002048 21:21:40 INFO - PROCESS | 4106 | 21:21:43 INFO - PROCESS | 4106 | Cycle 1(1): loaded http://localhost:35709/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:35709/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 21:21:43 INFO - PROCESS | 4106 | RSS: Main: 222396416 21:21:43 INFO - PROCESS | 4106 | 21:21:45 INFO - PROCESS | 4106 | Cycle 1(1): loaded http://localhost:35709/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:35709/tests/kraken/driver.html?testName=ai-astar) 21:21:45 INFO - PROCESS | 4106 | RSS: Main: 272203776 21:21:45 INFO - PROCESS | 4106 | 21:21:45 INFO - PROCESS | 4106 | __start_tp_report 21:21:45 INFO - PROCESS | 4106 | _x_x_mozilla_page_load 21:21:45 INFO - PROCESS | 4106 | _x_x_mozilla_page_load_details 21:21:45 INFO - PROCESS | 4106 | |i|pagename|runs| 21:21:45 INFO - PROCESS | 4106 | |0;ai-astar;112;117;115;115;113;115;115;114;114;113 21:21:45 INFO - PROCESS | 4106 | |1;audio-beat-detection;152;126;130;174;124;125;128;123;186;126 21:21:45 INFO - PROCESS | 4106 | |2;audio-dft;163;162;163;163;171;161;163;162;162;162 21:21:45 INFO - PROCESS | 4106 | |3;audio-fft;76;76;76;104;77;77;77;76;107;75 21:21:45 INFO - PROCESS | 4106 | |4;audio-oscillator;83;83;121;84;84;83;108;83;83;113 21:21:45 INFO - PROCESS | 4106 | |5;imaging-gaussian-blur;117;118;117;118;117;117;117;117;117;119 21:21:45 INFO - PROCESS | 4106 | |6;imaging-darkroom;147;147;147;146;147;147;146;147;147;146 21:21:45 INFO - PROCESS | 4106 | |7;imaging-desaturate;96;94;95;95;95;95;94;95;95;95 21:21:45 INFO - PROCESS | 4106 | |8;json-parse-financial;64;62;62;62;63;63;63;64;64;62 21:21:45 INFO - PROCESS | 4106 | |9;json-stringify-tinderbox;66;66;66;66;66;66;66;67;66;66 21:21:45 INFO - PROCESS | 4106 | |10;stanford-crypto-aes;83;85;83;84;85;82;83;90;82;81 21:21:45 INFO - PROCESS | 4106 | |11;stanford-crypto-ccm;136;147;131;133;132;134;135;135;178;134 21:21:45 INFO - PROCESS | 4106 | |12;stanford-crypto-pbkdf2;177;181;176;183;177;172;176;171;172;173 21:21:45 INFO - PROCESS | 4106 | |13;stanford-crypto-sha256-iterative;71;71;69;70;72;70;71;71;69;72 21:21:45 INFO - PROCESS | 4106 | __end_tp_report 21:21:45 INFO - PROCESS | 4106 | __start_cc_report 21:21:45 INFO - PROCESS | 4106 | _x_x_mozilla_cycle_collect,1957 21:21:45 INFO - PROCESS | 4106 | __end_cc_report 21:21:45 INFO - PROCESS | 4106 | __startTimestamp1474431705827__endTimestamp 21:21:45 INFO - PROCESS | 4106 | 21:21:45 INFO - PROCESS | 4106 | ------- Summary: start ------- 21:21:45 INFO - PROCESS | 4106 | Number of tests: 14 21:21:45 INFO - PROCESS | 4106 | 21:21:45 INFO - PROCESS | 4106 | [#0] ai-astar Cycles:10 Average:114.30 Median:115.00 stddev:1.42 (1.2%) stddev-sans-first:1.24 21:21:45 INFO - PROCESS | 4106 | Values: 112.0 117.0 115.0 115.0 113.0 115.0 115.0 114.0 114.0 113.0 21:21:45 INFO - PROCESS | 4106 | 21:21:45 INFO - PROCESS | 4106 | [#1] audio-beat-detection Cycles:10 Average:139.40 Median:129.00 stddev:23.14 (17.9%) stddev-sans-first:24.09 21:21:45 INFO - PROCESS | 4106 | Values: 152.0 126.0 130.0 174.0 124.0 125.0 128.0 123.0 186.0 126.0 21:21:45 INFO - PROCESS | 4106 | 21:21:45 INFO - PROCESS | 4106 | [#2] audio-dft Cycles:10 Average:163.20 Median:163.00 stddev:2.82 (1.7%) stddev-sans-first:2.99 21:21:45 INFO - PROCESS | 4106 | Values: 163.0 162.0 163.0 163.0 171.0 161.0 163.0 162.0 162.0 162.0 21:21:45 INFO - PROCESS | 4106 | 21:21:45 INFO - PROCESS | 4106 | [#3] audio-fft Cycles:10 Average:82.10 Median:76.00 stddev:12.37 (16.3%) stddev-sans-first:12.92 21:21:45 INFO - PROCESS | 4106 | Values: 76.0 76.0 76.0 104.0 77.0 77.0 77.0 76.0 107.0 75.0 21:21:45 INFO - PROCESS | 4106 | 21:21:45 INFO - PROCESS | 4106 | [#4] audio-oscillator Cycles:10 Average:92.50 Median:83.00 stddev:15.16 (18.3%) stddev-sans-first:15.69 21:21:45 INFO - PROCESS | 4106 | Values: 83.0 83.0 121.0 84.0 84.0 83.0 108.0 83.0 83.0 113.0 21:21:45 INFO - PROCESS | 4106 | 21:21:45 INFO - PROCESS | 4106 | [#5] imaging-gaussian-blur Cycles:10 Average:117.40 Median:117.00 stddev:0.70 (0.6%) stddev-sans-first:0.73 21:21:45 INFO - PROCESS | 4106 | Values: 117.0 118.0 117.0 118.0 117.0 117.0 117.0 117.0 117.0 119.0 21:21:45 INFO - PROCESS | 4106 | 21:21:45 INFO - PROCESS | 4106 | [#6] imaging-darkroom Cycles:10 Average:146.70 Median:147.00 stddev:0.48 (0.3%) stddev-sans-first:0.50 21:21:45 INFO - PROCESS | 4106 | Values: 147.0 147.0 147.0 146.0 147.0 147.0 146.0 147.0 147.0 146.0 21:21:45 INFO - PROCESS | 4106 | 21:21:45 INFO - PROCESS | 4106 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.57 (0.6%) stddev-sans-first:0.44 21:21:45 INFO - PROCESS | 4106 | Values: 96.0 94.0 95.0 95.0 95.0 95.0 94.0 95.0 95.0 95.0 21:21:45 INFO - PROCESS | 4106 | 21:21:45 INFO - PROCESS | 4106 | [#8] json-parse-financial Cycles:10 Average:62.90 Median:63.00 stddev:0.88 (1.4%) stddev-sans-first:0.83 21:21:45 INFO - PROCESS | 4106 | Values: 64.0 62.0 62.0 62.0 63.0 63.0 63.0 64.0 64.0 62.0 21:21:45 INFO - PROCESS | 4106 | 21:21:45 INFO - PROCESS | 4106 | [#9] json-stringify-tinderbox Cycles:10 Average:66.10 Median:66.00 stddev:0.32 (0.5%) stddev-sans-first:0.33 21:21:45 INFO - PROCESS | 4106 | Values: 66.0 66.0 66.0 66.0 66.0 66.0 66.0 67.0 66.0 66.0 21:21:45 INFO - PROCESS | 4106 | 21:21:45 INFO - PROCESS | 4106 | [#10] stanford-crypto-aes Cycles:10 Average:83.80 Median:83.50 stddev:2.53 (3.0%) stddev-sans-first:2.67 21:21:45 INFO - PROCESS | 4106 | Values: 83.0 85.0 83.0 84.0 85.0 82.0 83.0 90.0 82.0 81.0 21:21:45 INFO - PROCESS | 4106 | 21:21:45 INFO - PROCESS | 4106 | [#11] stanford-crypto-ccm Cycles:10 Average:139.50 Median:135.00 stddev:14.23 (10.5%) stddev-sans-first:15.04 21:21:45 INFO - PROCESS | 4106 | Values: 136.0 147.0 131.0 133.0 132.0 134.0 135.0 135.0 178.0 134.0 21:21:45 INFO - PROCESS | 4106 | 21:21:45 INFO - PROCESS | 4106 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.80 Median:176.50 stddev:3.97 (2.2%) stddev-sans-first:4.18 21:21:45 INFO - PROCESS | 4106 | Values: 177.0 181.0 176.0 183.0 177.0 172.0 176.0 171.0 172.0 173.0 21:21:45 INFO - PROCESS | 4106 | 21:21:45 INFO - PROCESS | 4106 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.60 Median:71.00 stddev:1.07 (1.5%) stddev-sans-first:1.13 21:21:45 INFO - PROCESS | 4106 | Values: 71.0 71.0 69.0 70.0 72.0 70.0 71.0 71.0 69.0 72.0 21:21:45 INFO - PROCESS | 4106 | -------- Summary: end -------- 21:21:45 INFO - PROCESS | 4106 | 21:21:47 INFO - TEST-INFO | 4106: exit 0 21:21:47 INFO - TEST-OK | kraken | took 64343ms 21:21:47 INFO - SUITE-END | took 1185s 21:21:47 INFO - Completed test suite (00:19:45) 21:21:47 INFO - javascript benchmark 21:21:47 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2586.34048317679, 2623.147477069953, 2642.9824517560746, 2647.272000199946, 2668.799840011998, 4595.517470665583, 4661.603763680083, 4720.433547974244, 4729.550692684194, 4744.667873248874, 4689.7655117244085, 4719.150552900476, 4739.73931433771, 4744.762761861911, 4801.567858892699, 4314.741115533062, 4324.97837510812, 4363.08375241199, 4371.978140109295, 4395.099004704028, 4316.07204451043, 4371.475422949251, 4395.714278571899, 4462.352958820969, 4480.350349199364, 4422.601965823075, 4441.844535441261, 4475.865724028285, 4499.280115181556, 4500.324951257305, 4232.470941132358, 4264.7227930184745, 4271.145770845843, 4367.890802729958, 4368.781560921973, 4251.171021650779, 4258.6380157686635, 4263.616274535307, 4269.978650106761, 4305.590968857953, 4633.235516139833, 4635.582797548237, 4669.906601867943, 4700.976495117537, 4703.318018887277, 5023.2214006829, 5037.0429618372755, 5044.924326135111, 5052.671576347526, 5104.51507106828, 4913.992631510568, 4931.052234367405, 4979.7261150636705, 5045.495450454961, 5057.418396884369, 4680.7191568506, 4715.127701375257, 4721.362616046858, 4724.645651576146, 4787.521247875185, 5167.0, 5167.638265321467, 5174.197999310113, 5179.015986962465, 5241.0566098101945, 4847.442544107403, 4888.462269150391, 4927.162382395001, 4963.8014479421145, 4978.452370239271, 5065.240213967934, 5068.847934561969, 5068.949310506921, 5070.771815268248, 5117.155669314521, 5045.924311135336, 5047.697138171721, 5048.924266136011, 5052.418971818214, 5094.159463688524, 4885.975570122127, 4940.184869496493, 4941.234108713155, 5026.648134630541, 5093.5161159690515, 4175.394567787654, 4222.218889505451, 4263.317869140985, 4267.338562522814, 4267.914641707211, 3687.465317528943, 3693.8707145249787, 3723.925521489555, 3738.4953031340965, 3791.0711875590655, 4297.05464797744, 4304.117655880537, 4304.870853874389, 4312.827486900552, 4370.519242883281, 4921.360223170965, 4968.850934471971, 4994.275830004629, 5017.247412888096, 5083.8983220335385, 4315.697901146952, 4323.113761678848, 4341.978290108593, 4353.912921741611, 4391.780410979438, 4550.522195169526, 4554.407926969473, 4555.453345598549, 4601.907961840811, 4611.884702882455, 4754.120487709784, 4766.165920963748, 4766.594839438687, 4771.427428708577, 4780.856574302777, 4887.706737595756, 4932.827351042696, 4978.975105124451, 4994.4006719192475, 5064.645474816727, 4737.218358970869, 4740.928886066643, 4741.952580474152, 4791.305260737243, 4804.039192161512, 4953.975230123826, 4962.255661650637, 4962.280469331855, 4965.577925876341, 5016.2224855147515, 4916.557509824133, 4958.206686930074, 5025.396952365593, 5034.874128146679, 5089.770960306869, 4894.19245824435, 4905.950940490549, 4935.703857768545, 4937.136001199704, 4968.602511798975, 4892.192788190051, 4899.877503062452, 4905.190643543918, 4906.334955903274, 4961.957988822388, 4707.693999889996, 4764.0, 4831.879203019953, 4834.105690447279, 4900.240462728283, 4878.585320247818, 4893.339399181065, 4927.0, 4945.357103576512, 4945.975270123627, 4739.0521895621705, 4792.568668819684, 4794.232922732346, 4847.563719265283, 4849.830255941025], "value": 4622.838643338866, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [556.89419010388, 569.2940753465703, 573.3062993777529, 582.4263100845666, 583.7256489449957, 29153.70846291539, 36945.630543694555, 37588.43617345742, 37795.24409511815, 41801.372979405336, 39206.4119038214, 39588.0, 39616.0, 39659.801700991455, 39692.0, 7598.468107232482, 7736.961315193416, 7744.264294892005, 7748.1864404237585, 7753.767386978399, 7161.0, 7340.632968351561, 9664.130228279453, 9949.253805964545, 9949.651762188325, 10039.799204015952, 10112.191024718042, 10123.08892199702, 10123.1901447884, 10123.898761012406, 9876.407415555053, 9937.701868943943, 9959.107769523767, 9962.352447090916, 9998.05018523239, 6951.200611929642, 7136.250693677193, 9490.715278541655, 9832.065953734455, 9834.655787047419, 36745.0, 37116.07209819749, 37122.0, 37130.25739485222, 37144.628553714385, 2810.789190810697, 2823.449427361665, 2834.4189441164513, 2892.103447931148, 3225.225945773021, 26845.0, 27761.86119069412, 27887.0, 28358.858205709046, 28376.716232837614, 3193.7764356495054, 3198.2643991881996, 3253.7397008239286, 3268.9019329420157, 3320.25294308781, 251873.0, 252310.0, 252761.0, 252924.14765483487, 253450.0, 50627.0, 50958.235626465634, 50965.745171273906, 50985.23522147171, 51219.48780512222, 52627.47372526302, 52744.472555274726, 52879.735601322514, 53072.46927530752, 53169.734151328994, 14413.49552765669, 14858.0, 14869.182194979163, 14881.107133571804, 14887.10677359362, 9465.90534094664, 11529.596464123715, 11560.421978901022, 11569.826452603218, 11585.420728963518, 12087.697807554881, 12480.063995200215, 12646.683832904251, 12876.356182190853, 12948.28784416866, 5436.728163591804, 5617.494425501724, 5672.149177623307, 5763.2507773989955, 5791.131330300489, 761.588742079271, 881.8104107616894, 885.3094586222757, 951.8952915179325, 962.8603852441357, 25473.0, 26974.730252697613, 27368.178954631392, 27770.861145694143, 27816.582751258746, 882.5543100734088, 894.5706061090583, 937.3485427627734, 969.0793745941384, 1004.7940172264668, 14227.28863556818, 14298.928505357406, 14418.567442976728, 14799.926000370144, 14821.92589037048, 364026.0, 366871.0, 368127.0, 368661.07406037045, 370220.0, 47709.76145119252, 47884.0568117621, 47901.40739444556, 47977.28034079492, 48009.5199048005, 986.9555869986011, 1007.4912169354576, 1098.917566197295, 1109.1847492093143, 1150.8388825564427, 25002.37494062663, 28137.091210845134, 28408.289792755346, 28468.715312847435, 28961.0, 40561.59438405578, 40907.795461022506, 41042.58957410388, 41083.1783364337, 41102.766916992536, 296719.0, 297052.0, 299362.0, 299607.0, 299804.0, 21814.672779907996, 22669.659955101015, 22823.771762282828, 23534.882325588263, 35313.93721255708, 38240.61759382371, 38871.805640971616, 40822.0, 41350.79324603358, 41998.0, 35058.298834023684, 35306.470402943465, 35423.468647969785, 35575.82212088923, 35592.0, 35091.64908350986, 35633.28733425369, 35857.46213806743, 35872.64127358797, 36254.637453625124, 33778.66221337755, 34322.31355372829, 34389.65610343865, 34579.48130778091, 34885.65114348824, 30208.0, 31370.21574460657, 31651.683483165794, 31867.681323186473, 32236.516452253705, 24630.630540541548, 24739.25782226536, 24814.0, 25345.112921048414, 25527.48945021052], "value": 17167.38343454773, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [337.1066673315715, 355.6283683550689, 366.9651383118603, 373.20879734961886, 379.63744623884196, 12340.753184936297, 13632.318384080781, 15173.696526069485, 17196.656066878655, 18035.63928721425, 13654.931725341385, 13694.109882857632, 13730.107543009723, 13731.0, 14228.502002429917, 4837.879053023667, 4949.5545400913825, 4953.900921981558, 4987.376577927759, 5061.266116413118, 3581.3374525712698, 3777.3011992781285, 4977.0792403405485, 5060.873478163038, 5062.367204099488, 3783.205526839367, 3787.7727336359767, 3792.563855156665, 3795.905102372435, 5063.139266324715, 5814.069748840187, 5901.085331773582, 5905.084711869667, 5906.0, 5910.615809972381, 5015.598752099823, 5181.53366197044, 5182.689038657655, 5218.139007063868, 5257.185136303879, 20736.481587960272, 20788.58422831535, 20950.05724742375, 21003.579928401497, 21126.15495380183, 1908.895010774407, 1937.951551211231, 2103.8001389867936, 2160.7191065161587, 2214.833887458431, 13259.86740132596, 13283.136596121221, 13554.254516001616, 13583.0, 13757.31213439334, 1690.4421540891599, 1729.705949988505, 1738.0875040603657, 1783.8483728883061, 1851.6111616560538, 64905.0, 65109.674451627914, 65747.67126164386, 73554.63222683853, 73905.63047184783, 15733.763993540106, 15750.68498630021, 15906.681866362607, 16327.34690612386, 16344.0, 28388.85805570959, 28430.882441160375, 28432.857835711104, 28482.430351393272, 28581.857090714413, 8582.871256931026, 8643.870341944876, 8782.648694052166, 8969.416987895765, 9309.0, 7441.737027826685, 7527.9247207528315, 7567.583782891884, 7678.8080297993, 7757.418193635387, 8029.879551806727, 8250.298724608356, 8307.252347288651, 8324.292435143081, 8349.0, 3221.7583681224, 3244.464663330524, 3277.6886195811358, 3281.950770738441, 3339.632640409553, 358.7560458887981, 362.74607774557916, 763.9618019099023, 788.341734651561, 872.6116877989234, 10254.692359229235, 10297.0, 10396.792064158675, 10503.0, 10798.298110622783, 367.9852805887788, 377.8016541315776, 378.5778856574899, 382.521847690387, 387.4788409589098, 5495.120780675073, 5577.330720313507, 5579.581531385163, 5604.719764011783, 5777.653340799566, 397090.0581988344, 397497.0, 400526.0, 402292.0, 403235.0, 82150.0, 82674.17325826664, 83135.58432207922, 84065.15934840574, 85160.0, 403.6810919373662, 412.52147508889544, 413.0479245339461, 413.3923132994493, 414.0311670690538, 7528.322450979438, 9888.901110988798, 9983.550740216853, 10070.597176112873, 10196.592136314464, 37496.81251593724, 38838.834834955, 38926.0, 38932.41601376038, 39211.0, 47560.2865957004, 49411.258831118284, 49546.50453495419, 49570.25644615261, 49642.560764670816, 7154.570725756263, 7263.636818159177, 7362.190159082604, 7460.097675093034, 7975.401844861774, 8032.87950680752, 11077.446127693744, 11102.777944441228, 11705.941470292593, 11849.229800062969, 9687.273454491082, 9911.306208565331, 10166.339187952759, 10188.898111018794, 10364.896351036394, 10020.198384129399, 10184.338018028804, 10449.738756530844, 10473.633422830164, 10524.263301568817, 9957.651482198089, 10008.299419040597, 10020.949895250476, 10021.39871607706, 10292.330998485075, 7470.738524151629, 7717.1896950819355, 7886.0, 7912.4856884302335, 7943.285104340637, 9224.538773061455, 9982.401055936667, 10090.34412763168, 10092.444915529422, 10152.319454898376], "value": 7964.7553044182505, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1309.3976770685488, 1337.545234620229, 1354.5191457032754, 1426.386653738892, 1451.7024010077935, 6876.553024053433, 7107.786766397004, 7318.231585683507, 7885.526868387894, 7888.290053895155, 7514.098308203023, 7745.728899488519, 7755.185705500926, 7995.800104997378, 8055.234752698499, 2184.377452426055, 2212.104097840377, 2212.822974162063, 2216.2713083794665, 2219.411855858199, 1843.1245158549648, 2130.9893450532804, 2141.4325203821, 2146.796054374832, 2215.4129155773735, 1850.1489314915154, 2086.436662101232, 2146.2702681088504, 2159.1039718516795, 2169.652855543113, 2111.366590022987, 2122.352682431858, 2124.097258665061, 2132.69075983983, 2142.678598210265, 2071.8964051797348, 2114.0233881321014, 2116.3016204652426, 2121.543868068373, 2124.0866427436194, 7942.444028917978, 8027.95986020072, 8029.638666260033, 8033.879491807627, 8034.678612855479, 4379.978100109511, 4499.460064792213, 4500.662450316239, 4502.909941801178, 4503.729776213438, 2912.359280958186, 2998.9353779408434, 3011.713887180714, 3048.2379405148713, 3052.7099925507264, 936.166811537732, 995.6863587969839, 1013.9290249682525, 1017.6641708236265, 1026.0816569170624, 11016.834747478793, 11042.33745975244, 11042.613508527164, 11046.944765276203, 11055.22613417053, 3901.3367727486398, 4064.7154699170774, 4244.830206791694, 4250.978745106255, 4258.42511260982, 9317.90682093184, 9337.066293370744, 9340.486273255035, 9352.625894964125, 9357.157855793012, 4583.770811459413, 4586.72479651222, 4609.078184363141, 4610.216263235258, 4615.561521655437, 4113.9098138993195, 4143.482064741907, 4156.501219853637, 4156.625903668624, 4158.688098392633, 3566.589842168132, 3682.4660424238336, 3722.580322790208, 3725.348064088828, 3741.1021354874633, 1154.3016475032484, 1170.6605084525563, 1173.9589114380956, 1178.7171078941165, 1189.2567145534042, 564.4636353246912, 622.539320902538, 678.7522554267756, 708.129001328369, 728.5009768308726, 5068.873278168076, 5085.7711402986215, 5095.15929871567, 5154.278401023859, 5321.840344789663, 863.1196179896596, 867.6876324523292, 872.2455076358904, 872.6901949807853, 877.381231627646, 3237.14215732831, 3372.696457318853, 3376.4766461198556, 3423.4693622488057, 3493.7903725776537, 8965.0, 9418.10527999839, 9424.811503769886, 9429.952850235704, 9435.764105897408, 9531.332806703464, 9718.319717619699, 9774.511274436392, 9827.705168844946, 9831.655892043744, 7279.961610748999, 7359.190489046201, 7362.558246505119, 7364.521306115193, 7366.226546212571, 4784.258439941815, 4789.976050119727, 4830.512705306831, 4845.830395936126, 5039.521245481674, 8931.151540603632, 9043.050479699537, 9058.818823623622, 9066.637334506546, 9099.1810737034, 9002.909970900208, 9083.818323633623, 9167.0, 9181.586828592595, 9205.76985575366, 6086.908696369646, 6244.344343844013, 6284.4344009039405, 6297.370262973666, 6314.842128946813, 7354.595497247703, 7527.774166775005, 7596.658150383357, 7602.657880395506, 7612.543247405173, 6584.50616203777, 6674.6662666865495, 6734.02339343316, 6838.14523184602, 7022.08712867324, 6574.737010519526, 6687.565308254948, 6697.899531506934, 6708.228553716385, 6732.831679208059, 6232.532560057924, 6329.335419780857, 6331.366863313632, 6331.525135614939, 6334.524910631629, 4440.111977604427, 4565.543445655408, 4636.489986101594, 4637.165310244189, 4648.140094082605, 4530.0, 4549.727016378895, 4657.510961349145, 4698.318743782064, 4837.3227748116], "value": 4085.174669100563, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [273.4408135363182, 277.0966648725156, 285.32378263515466, 288.6334355368682, 289.8463814178486, 4584.174848527264, 4765.973606759707, 4775.426948766149, 4867.318575399442, 4887.511248875111, 4562.224421848286, 4672.229082201433, 4702.836695120241, 4733.644976626758, 4781.617470602352, 3761.8307176177, 3785.1483416231335, 3785.9621403785886, 3787.147891724354, 3788.3938569828833, 3541.8052007139595, 3794.582595914447, 3794.8236046825436, 3799.278137153946, 3799.715021373408, 3466.3933811582997, 3737.4767532545466, 3753.099256178511, 3755.4179102239195, 3760.172761992357, 3735.1595890924596, 3739.8130093495215, 3744.588095309514, 3744.7565908216015, 3747.737658363902, 3301.7028467437804, 3304.2400247943106, 3307.30546585215, 3312.801231926092, 3313.93372132556, 4596.172688915994, 4651.837185698484, 4653.813847446098, 4665.836695715668, 4739.73931433771, 4236.703430759848, 4247.511536173349, 4263.5736426357425, 4267.935980960257, 4275.482203817631, 4989.750512474362, 4990.800367985276, 4994.175960966436, 5002.649814512985, 5004.5495905368325, 3451.8964431067106, 3626.4197728363333, 3629.8548058077645, 3634.4548317752283, 3636.5636123665063, 5177.948220517785, 5208.739563021872, 5214.556762675215, 5223.582113430916, 5242.55438287746, 3606.585242697071, 3635.818209089588, 3637.618050104754, 3639.252364464812, 3641.5630124385243, 3682.2451397463456, 3705.1292946157632, 3717.4052151655605, 3718.0704823794053, 3724.5902950675404, 3972.3445631470486, 4131.338985762263, 4223.260929337353, 4275.187714334266, 4279.40088387626, 5014.774335154927, 5019.372578427697, 5023.572996295283, 5023.773930173151, 5038.017586570657, 1189.9881001190051, 1219.603628820637, 1241.875812418751, 1270.987290127087, 1272.5355245335386, 823.6375994562493, 850.2900078434453, 856.3235044314921, 861.6596444404425, 862.8317478091838, 4405.493368262627, 4424.911501769947, 4426.84506042287, 4449.710768800082, 4456.353828694886, 5129.768855474659, 5140.922886156711, 5154.768035438415, 5179.352580927384, 5191.688498690015, 4270.231358355526, 4447.421835161473, 4489.2592722201125, 4494.213512635276, 4527.9850466542075, 2745.8627068646488, 2745.890164393442, 2751.2984189032068, 2756.476269508787, 2773.5285001549787, 4862.12808068896, 4867.537583929521, 4882.584980276716, 4915.557599816034, 4915.803367865316, 5179.8446046618665, 5262.789698284044, 5289.471052894757, 5292.788288468418, 5312.715532321062, 4876.5854902332985, 4881.585065269421, 4890.877728056827, 4897.2654101885, 4906.484819093944, 4995.486603644513, 5166.199239117943, 5172.431032586488, 5193.974030129976, 5234.2933703949475, 5087.847364579068, 5099.796008159632, 5101.260317254051, 5103.209002604603, 5127.41034781005, 4827.503473923095, 4869.732164730974, 4872.3909511311085, 4877.195262781602, 4888.364512613338, 3558.2705545363656, 3559.2169722660974, 3589.712822974208, 3592.5688917329044, 3598.0465176727666, 4972.527609877201, 5185.977468787251, 5198.6880787152895, 5221.190715439113, 5242.135047717236, 5008.449070602158, 5034.370703662043, 5048.293238946624, 5056.721880296619, 5058.190689489811, 4957.0001078673695, 5066.214736715815, 5078.136716758234, 5086.669366491166, 5116.641835071509, 4775.594074503706, 4919.819243381491, 4939.827106051271, 4982.202847544376, 5029.547340739351, 4906.803727850846, 5068.417132029716, 5073.974630126826, 5077.263796749525, 5082.170418649357, 4942.308076869169, 4944.826931057396, 4953.603711703127, 4956.752162391794, 4964.156093464047], "value": 3776.919748633436, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [555.2393221286837, 578.2136294639291, 586.961847479914, 603.0803025386285, 608.6074481959137, 12174.147809653317, 12789.744205115892, 12795.936020319908, 12818.935905320484, 13751.0, 13524.391402386891, 14253.78619320711, 14258.500952466666, 14304.35630396632, 14308.642283942907, 582.9650220986755, 724.6231959381132, 744.151667099507, 747.3572727454385, 747.8279995601015, 599.5533327670892, 712.0565251042364, 730.817295676081, 739.2275072549172, 743.9256074392572, 780.1457404142454, 782.636074225485, 782.7534326687104, 782.8003859015971, 782.8669126248551, 598.5570677698472, 618.7927044440073, 772.0735117858592, 772.1583474013324, 772.2663469703748, 544.0370544136856, 559.0496156533909, 564.0495764636601, 565.0761005755578, 566.0744682444192, 12608.873911260862, 12662.366881655973, 12677.556285529961, 12696.111272210948, 12778.169418987829, 11650.30098194111, 11736.53053877844, 11832.408379581073, 11926.463309151022, 12525.812112818316, 10867.239293249475, 11129.666110016713, 11255.012167982532, 11407.885921140765, 11605.535778568847, 3918.4122381642983, 3984.860529881469, 4073.633372996415, 4078.12320351123, 4087.2847251730827, 66007.0, 66632.33367666358, 66789.33210667879, 68012.31987680159, 68573.65713171402, 8628.309735221168, 8701.608427620773, 8906.242969347548, 9443.819522559543, 9995.850062249072, 15870.9206453967, 16620.66758664844, 16649.33402663904, 17147.142642867806, 17178.484645460656, 6375.203099612549, 6744.898826517606, 6849.452043836481, 6872.209695885037, 6927.515073944775, 3543.982280088583, 3623.5108260385036, 3695.2978934002454, 3696.075981004749, 3704.0369503929173, 2885.3075261936983, 3019.1093627379973, 3021.6676165621766, 3169.176014236315, 3323.4848598467274, 552.9253550770675, 562.2943206276097, 565.564515323207, 574.12159396124, 577.9971100144472, 489.207483876123, 489.93385892904, 500.12228538913973, 505.91399462091533, 505.93169922060054, 7390.815229619195, 7599.392048636096, 7762.456628035983, 7821.882671759814, 7930.405219608552, 459.46701825881814, 476.89508308172145, 481.9614430845524, 497.72873783787924, 497.8033676697684, 3554.822258887097, 3565.0, 3653.725970552219, 3696.77819330841, 3851.595582463801, 63389.0, 63592.36407635957, 65156.02265966014, 96096.51951740196, 106366.46816765866, 72660.27339726535, 90007.0, 91050.54474727716, 91510.0, 92548.0, 75812.0, 77195.0, 80918.26392598754, 81750.0, 82178.58910705408, 7072.0, 7116.679749411185, 7164.534305270141, 7238.298999735163, 7489.21363256872, 44349.77825110854, 45462.0, 46070.0, 46347.30479042749, 46714.0, 60831.69584152051, 62469.68765156145, 63200.683996579726, 63738.362616375096, 63957.68021159864, 9626.47054412014, 9655.372400793925, 9678.785915422986, 9705.708828735149, 9970.55132519024, 9898.604055837686, 9956.751081223027, 10012.599496020077, 10037.49812509357, 10137.64518241858, 9692.41845489273, 10009.649662261785, 10032.147267482345, 10124.544395502367, 10127.24045696561, 8263.586820658822, 8755.693550725695, 9270.119338662817, 9346.672866449642, 9492.905070949202, 9385.85921211196, 9468.763280917756, 9607.51962401891, 9698.27262955267, 9789.608415663293, 7703.229677032252, 8254.174582541698, 8310.459820111673, 8432.704855330034, 8560.14398560139, 7848.447900217974, 8033.35733141359, 8099.635516401894, 8193.754187374388, 8452.577371131296], "value": 6128.786363099201, "unit": "score"}], "name": "dromaeo_css", "value": 6253.064246447663, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 117.0, 115.0, 115.0, 113.0, 115.0, 115.0, 114.0, 114.0, 113.0], "value": 114.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 126.0, 130.0, 174.0, 124.0, 125.0, 128.0, 123.0, 186.0, 126.0], "value": 139.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 162.0, 163.0, 163.0, 171.0, 161.0, 163.0, 162.0, 162.0, 162.0], "value": 163.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 76.0, 104.0, 77.0, 77.0, 77.0, 76.0, 107.0, 75.0], "value": 82.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 121.0, 84.0, 84.0, 83.0, 108.0, 83.0, 83.0, 113.0], "value": 92.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 118.0, 117.0, 118.0, 117.0, 117.0, 117.0, 117.0, 117.0, 119.0], "value": 117.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 147.0, 146.0, 147.0, 147.0, 146.0, 147.0, 147.0, 146.0], "value": 146.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 94.0, 95.0, 95.0, 95.0, 95.0, 94.0, 95.0, 95.0, 95.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 62.0, 62.0, 62.0, 63.0, 63.0, 63.0, 64.0, 64.0, 62.0], "value": 62.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 66.0, 66.0, 66.0, 66.0, 66.0, 67.0, 66.0, 66.0], "value": 66.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 85.0, 83.0, 84.0, 85.0, 82.0, 83.0, 90.0, 82.0, 81.0], "value": 83.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 147.0, 131.0, 133.0, 132.0, 134.0, 135.0, 135.0, 178.0, 134.0], "value": 139.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [177.0, 181.0, 176.0, 183.0, 177.0, 172.0, 176.0, 171.0, 172.0, 173.0], "value": 175.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 71.0, 69.0, 70.0, 72.0, 70.0, 71.0, 71.0, 69.0, 72.0], "value": 70.6, "unit": "score"}], "name": "kraken", "value": 1549.1999999999996, "alertThreshold": 2.0}]} 21:21:47 INFO - Return code: 0 21:21:47 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 21:21:47 INFO - # TBPL SUCCESS # 21:21:47 INFO - Running post-action listener: _resource_record_post_action 21:21:47 INFO - [mozharness: 2016-09-21 04:21:47.620902Z] Finished run-tests step (success) 21:21:47 INFO - Running post-run listener: _resource_record_post_run 21:21:47 INFO - Total resource usage - Wall time: 1202s; CPU: 13.0%; Read bytes: 4096; Write bytes: 396976128; Read time: 20; Write time: 227328 21:21:47 INFO - TinderboxPrint: CPU usage
13.0% 21:21:47 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 20 21:21:47 INFO - TinderboxPrint: I/O write bytes / time
396,976,128 / 227,328 21:21:47 INFO - TinderboxPrint: CPU idle
8,337.7 (86.9%) 21:21:47 INFO - TinderboxPrint: CPU user
1,201.2 (12.5%) 21:21:47 INFO - TinderboxPrint: Swap in / out
0 / 0 21:21:47 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 110702592; Read time: 0; Write time: 50520 21:21:47 INFO - run-tests - Wall time: 1186s; CPU: 13.0%; Read bytes: 0; Write bytes: 286273536; Read time: 0; Write time: 176808 21:21:48 INFO - Running post-run listener: _upload_blobber_files 21:21:48 INFO - Blob upload gear active. 21:21:48 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 21:21:48 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 21:21:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Autoland-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 21:21:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Autoland-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 21:21:48 INFO - (blobuploader) - INFO - Open directory for files ... 21:21:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 21:21:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:21:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:21:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 21:21:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:21:49 INFO - (blobuploader) - INFO - Done attempting. 21:21:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 21:21:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:21:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:21:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 21:21:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:21:50 INFO - (blobuploader) - INFO - Done attempting. 21:21:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 21:21:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:21:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:21:54 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 21:21:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:21:54 INFO - (blobuploader) - INFO - Done attempting. 21:21:54 INFO - (blobuploader) - INFO - Iteration through files over. 21:21:54 INFO - Return code: 0 21:21:54 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 21:21:54 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 21:21:54 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/3b6dd0f76dc898cdac7fc264185d1adf0449ed66fdfde741e963a998e78d1e484b077c789f159a843eef566f8d8a6123f94d7cc9ea98cc9be6c8b75735adbe3a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/fa3ff976a6b55efe81a90ac0e0c3e997cbcea0c6dfca5229bb08001053c5c95fcf24e83a66095d83278c0a28b03f7f42b629ecb513a765262905d9d13a17d149", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/3614fc0b0493a9cd7f6be325c34791c7effbf2c34b44ae1343a50adeb7bdeaac23b00b7dc28483fe6b2f787097bd1da2a03a72c98e2a9d7b84755998a9e3dfcc"} 21:21:54 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 21:21:54 INFO - Writing to file /builds/slave/test/properties/blobber_files 21:21:54 INFO - Contents: 21:21:54 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/3b6dd0f76dc898cdac7fc264185d1adf0449ed66fdfde741e963a998e78d1e484b077c789f159a843eef566f8d8a6123f94d7cc9ea98cc9be6c8b75735adbe3a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/fa3ff976a6b55efe81a90ac0e0c3e997cbcea0c6dfca5229bb08001053c5c95fcf24e83a66095d83278c0a28b03f7f42b629ecb513a765262905d9d13a17d149", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/3614fc0b0493a9cd7f6be325c34791c7effbf2c34b44ae1343a50adeb7bdeaac23b00b7dc28483fe6b2f787097bd1da2a03a72c98e2a9d7b84755998a9e3dfcc"} 21:21:54 INFO - Running post-run listener: copy_logs_to_upload_dir 21:21:54 INFO - Copying logs to upload dir... 21:21:54 INFO - mkdir: /builds/slave/test/build/upload/logs 21:21:54 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1244.911097 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 44 secs) (at 2016-09-20 21:21:53.821382) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-20 21:21:53.825203) ========= 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=7fc86f70bae03e018da0b1d8000001c4-1474430467.8790-361103811 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/3b6dd0f76dc898cdac7fc264185d1adf0449ed66fdfde741e963a998e78d1e484b077c789f159a843eef566f8d8a6123f94d7cc9ea98cc9be6c8b75735adbe3a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/fa3ff976a6b55efe81a90ac0e0c3e997cbcea0c6dfca5229bb08001053c5c95fcf24e83a66095d83278c0a28b03f7f42b629ecb513a765262905d9d13a17d149", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/3614fc0b0493a9cd7f6be325c34791c7effbf2c34b44ae1343a50adeb7bdeaac23b00b7dc28483fe6b2f787097bd1da2a03a72c98e2a9d7b84755998a9e3dfcc"} build_url:https://queue.taskcluster.net/v1/task/LUJG39qpRAC8cb7cyVanpA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011290 build_url: 'https://queue.taskcluster.net/v1/task/LUJG39qpRAC8cb7cyVanpA/artifacts/public/build/firefox-52.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/3b6dd0f76dc898cdac7fc264185d1adf0449ed66fdfde741e963a998e78d1e484b077c789f159a843eef566f8d8a6123f94d7cc9ea98cc9be6c8b75735adbe3a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/fa3ff976a6b55efe81a90ac0e0c3e997cbcea0c6dfca5229bb08001053c5c95fcf24e83a66095d83278c0a28b03f7f42b629ecb513a765262905d9d13a17d149", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/3614fc0b0493a9cd7f6be325c34791c7effbf2c34b44ae1343a50adeb7bdeaac23b00b7dc28483fe6b2f787097bd1da2a03a72c98e2a9d7b84755998a9e3dfcc"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-20 21:21:53.850546) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 21:21:53.850898) ========= 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=7fc86f70bae03e018da0b1d8000001c4-1474430467.8790-361103811 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004464 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 21:21:53.935660) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-20 21:21:53.935990) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-20 21:21:53.936288) ========= ========= Total master_lag: 0.17 =========