builder: autoland_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-011 starttime: 1472226351.21 results: success (0) buildid: 20160826075817 builduid: 746cf2f2435f455a83d4a285997c7562 revision: 4eda5a74d949ad4c8bc587898fb420a8ef8bd28d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-26 08:45:51.214722) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-26 08:45:51.215245) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-26 08:45:51.215859) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-26 08:45:51.247521) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 08:45:51.247852) ========= 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=5cf47e208febbcd81da23b2e0000026f-1472226266.231314-1163103039 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-26 08:45:51-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 43.3M=0s 2016-08-26 08:45:51 (43.3 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.118004 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 08:45:51.383446) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 08:45:51.383769) ========= 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=5cf47e208febbcd81da23b2e0000026f-1472226266.231314-1163103039 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014748 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 08:45:51.435862) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 08:45:51.436236) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 4eda5a74d949ad4c8bc587898fb420a8ef8bd28d --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 4eda5a74d949ad4c8bc587898fb420a8ef8bd28d --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=5cf47e208febbcd81da23b2e0000026f-1472226266.231314-1163103039 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-26 08:45:51,683 truncating revision to first 12 chars 2016-08-26 08:45:51,683 Setting DEBUG logging. 2016-08-26 08:45:51,683 attempt 1/10 2016-08-26 08:45:51,683 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/4eda5a74d949?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-26 08:45:52,046 unpacking tar archive at: autoland-4eda5a74d949/testing/mozharness/ program finished with exit code 0 elapsedTime=0.780060 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 08:45:52.229466) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-26 08:45:52.229739) ========= script_repo_revision: 4eda5a74d949ad4c8bc587898fb420a8ef8bd28d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-26 08:45:52.230145) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-26 08:45:52.230434) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-26 08:45:52.241306) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2016-08-26 08:45:52.241613) ========= /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=5cf47e208febbcd81da23b2e0000026f-1472226266.231314-1163103039 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 08:45:52 INFO - MultiFileLogger online at 20160826 08:45:52 in /builds/slave/test 08:45:52 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 08:45:52 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:45:52 INFO - {'append_to_log': False, 08:45:52 INFO - 'base_work_dir': '/builds/slave/test', 08:45:52 INFO - 'blob_upload_branch': 'Autoland-Non-PGO', 08:45:52 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:45:52 INFO - 'branch': 'Autoland-Non-PGO', 08:45:52 INFO - 'buildbot_json_path': 'buildprops.json', 08:45:52 INFO - 'config_files': ('talos/linux_config.py',), 08:45:52 INFO - 'default_actions': ('clobber', 08:45:52 INFO - 'read-buildbot-config', 08:45:52 INFO - 'download-and-extract', 08:45:52 INFO - 'populate-webroot', 08:45:52 INFO - 'create-virtualenv', 08:45:52 INFO - 'install', 08:45:52 INFO - 'run-tests'), 08:45:52 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:45:52 INFO - 'download_minidump_stackwalk': True, 08:45:52 INFO - 'download_symbols': 'ondemand', 08:45:52 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:45:52 INFO - 'tooltool.py': '/tools/tooltool.py', 08:45:52 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:45:52 INFO - '/tools/misc-python/virtualenv.py')}, 08:45:52 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:45:52 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:45:52 INFO - 'installer_path': 'installer.exe', 08:45:52 INFO - 'log_level': 'info', 08:45:52 INFO - 'log_name': 'talos', 08:45:52 INFO - 'log_to_console': True, 08:45:52 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 08:45:52 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 08:45:52 INFO - 'opt_config_files': (), 08:45:52 INFO - 'pip_index': False, 08:45:52 INFO - 'sps_profile': False, 08:45:52 INFO - 'sps_profile_interval': 0, 08:45:52 INFO - 'suite': 'dromaeojs', 08:45:52 INFO - 'system_bits': '32', 08:45:52 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:45:52 INFO - 'title': 'talos-linux64-ix-011', 08:45:52 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:45:52 INFO - 'use_talos_json': True, 08:45:52 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:45:52 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:45:52 INFO - 'work_dir': 'build'} 08:45:52 INFO - [mozharness: 2016-08-26 15:45:52.586193Z] Running clobber step. 08:45:52 INFO - Running pre-action listener: _resource_record_pre_action 08:45:52 INFO - Running main action method: clobber 08:45:52 INFO - rmtree: /builds/slave/test/build 08:45:52 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:45:53 INFO - Running post-action listener: _resource_record_post_action 08:45:53 INFO - [mozharness: 2016-08-26 15:45:53.561675Z] Finished clobber step (success) 08:45:53 INFO - [mozharness: 2016-08-26 15:45:53.561777Z] Running read-buildbot-config step. 08:45:53 INFO - Running pre-action listener: _resource_record_pre_action 08:45:53 INFO - Running main action method: read_buildbot_config 08:45:53 INFO - Using buildbot properties: 08:45:53 INFO - { 08:45:53 INFO - "project": "", 08:45:53 INFO - "product": "firefox", 08:45:53 INFO - "script_repo_revision": "production", 08:45:53 INFO - "builddir": "autoland_ubuntu64_hw_test-dromaeojs", 08:45:53 INFO - "repository": "", 08:45:53 INFO - "buildername": "Ubuntu HW 12.04 x64 autoland talos dromaeojs", 08:45:53 INFO - "buildid": "20160826075817", 08:45:53 INFO - "pgo_build": "False", 08:45:53 INFO - "basedir": "/builds/slave/test", 08:45:53 INFO - "buildnumber": 521, 08:45:53 INFO - "slavename": "talos-linux64-ix-011", 08:45:53 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 08:45:53 INFO - "platform": "ubuntu64_hw", 08:45:53 INFO - "scheduler": "tests-autoland-linux64-talos", 08:45:53 INFO - "branch": "autoland", 08:45:53 INFO - "repo_path": "integration/autoland", 08:45:53 INFO - "revision": "4eda5a74d949ad4c8bc587898fb420a8ef8bd28d", 08:45:53 INFO - "stage_platform": "linux64", 08:45:53 INFO - "builduid": "746cf2f2435f455a83d4a285997c7562", 08:45:53 INFO - "slavebuilddir": "test" 08:45:53 INFO - } 08:45:53 INFO - Found installer url https://queue.taskcluster.net/v1/task/LIR-0PQARW-s9sj9Lmf4gg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 08:45:53 INFO - Running post-action listener: _resource_record_post_action 08:45:53 INFO - [mozharness: 2016-08-26 15:45:53.564239Z] Finished read-buildbot-config step (success) 08:45:53 INFO - [mozharness: 2016-08-26 15:45:53.564330Z] Running download-and-extract step. 08:45:53 INFO - Running pre-action listener: _resource_record_pre_action 08:45:53 INFO - Running main action method: download_and_extract 08:45:53 INFO - mkdir: /builds/slave/test/build/tests 08:45:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:45:53 INFO - trying https://queue.taskcluster.net/v1/task/LIR-0PQARW-s9sj9Lmf4gg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 08:45:53 INFO - Downloading https://queue.taskcluster.net/v1/task/LIR-0PQARW-s9sj9Lmf4gg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 08:45:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LIR-0PQARW-s9sj9Lmf4gg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 08:45:54 INFO - Downloaded 1361 bytes. 08:45:54 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 08:45:54 INFO - Using the following test package requirements: 08:45:54 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 08:45:54 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 08:45:54 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 08:45:54 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 08:45:54 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 08:45:54 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 08:45:54 INFO - u'jsshell-linux-x86_64.zip'], 08:45:54 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 08:45:54 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 08:45:54 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 08:45:54 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 08:45:54 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 08:45:54 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 08:45:54 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 08:45:54 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 08:45:54 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 08:45:54 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 08:45:54 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 08:45:54 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 08:45:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:45:54 INFO - trying https://queue.taskcluster.net/v1/task/LIR-0PQARW-s9sj9Lmf4gg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 08:45:54 INFO - Downloading https://queue.taskcluster.net/v1/task/LIR-0PQARW-s9sj9Lmf4gg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 08:45:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LIR-0PQARW-s9sj9Lmf4gg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 08:45:55 INFO - Downloaded 20304491 bytes. 08:45:55 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/tests 08:45:59 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 08:45:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:45:59 INFO - trying https://queue.taskcluster.net/v1/task/LIR-0PQARW-s9sj9Lmf4gg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 08:45:59 INFO - Downloading https://queue.taskcluster.net/v1/task/LIR-0PQARW-s9sj9Lmf4gg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 08:45:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LIR-0PQARW-s9sj9Lmf4gg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 08:46:00 INFO - Downloaded 20304491 bytes. 08:46:00 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/tests 08:46:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:05 INFO - trying https://queue.taskcluster.net/v1/task/LIR-0PQARW-s9sj9Lmf4gg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 08:46:05 INFO - Downloading https://queue.taskcluster.net/v1/task/LIR-0PQARW-s9sj9Lmf4gg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 08:46:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LIR-0PQARW-s9sj9Lmf4gg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 08:46:06 INFO - Downloaded 14413998 bytes. 08:46:06 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/tests 08:46:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:06 INFO - trying https://queue.taskcluster.net/v1/task/LIR-0PQARW-s9sj9Lmf4gg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 08:46:06 INFO - Downloading https://queue.taskcluster.net/v1/task/LIR-0PQARW-s9sj9Lmf4gg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 08:46:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LIR-0PQARW-s9sj9Lmf4gg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 08:46:09 INFO - Downloaded 55858226 bytes. 08:46:09 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/LIR-0PQARW-s9sj9Lmf4gg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 08:46:09 INFO - mkdir: /builds/slave/test/properties 08:46:09 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:46:09 INFO - Writing to file /builds/slave/test/properties/build_url 08:46:09 INFO - Contents: 08:46:09 INFO - build_url:https://queue.taskcluster.net/v1/task/LIR-0PQARW-s9sj9Lmf4gg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 08:46:09 INFO - Running post-action listener: _resource_record_post_action 08:46:09 INFO - Running post-action listener: set_extra_try_arguments 08:46:09 INFO - [mozharness: 2016-08-26 15:46:09.707105Z] Finished download-and-extract step (success) 08:46:09 INFO - [mozharness: 2016-08-26 15:46:09.707334Z] Running populate-webroot step. 08:46:09 INFO - Running pre-action listener: _resource_record_pre_action 08:46:09 INFO - Running main action method: populate_webroot 08:46:09 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:46:09 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:46:09 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:46:09 INFO - '--tppagecycles', 08:46:09 INFO - '7'], 08:46:09 INFO - 'tests': ['tsvgm']}}, 08:46:09 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:46:09 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:46:09 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:46:09 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:46:09 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:46:09 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:46:09 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:46:09 INFO - 'talos_options': ['--disable-e10s'], 08:46:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:46:09 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:46:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:46:09 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:46:09 INFO - 'talos_options': ['--disable-e10s'], 08:46:09 INFO - 'tests': ['damp', 'tps']}, 08:46:09 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:46:09 INFO - 'tests': ['damp', 'tps']}, 08:46:09 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:46:09 INFO - 'tests': ['dromaeo_dom']}, 08:46:09 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:46:09 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:46:09 INFO - 'tests': ['basic_compositor_video']}, 08:46:09 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:46:09 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:46:09 INFO - 'tests': ['a11yr', 08:46:09 INFO - 'ts_paint', 08:46:09 INFO - 'tpaint', 08:46:09 INFO - 'sessionrestore', 08:46:09 INFO - 'sessionrestore_no_auto_restore', 08:46:09 INFO - 'tabpaint']}, 08:46:09 INFO - 'other-e10s': {'tests': ['a11yr', 08:46:09 INFO - 'ts_paint', 08:46:09 INFO - 'tpaint', 08:46:09 INFO - 'sessionrestore', 08:46:09 INFO - 'sessionrestore_no_auto_restore', 08:46:09 INFO - 'tabpaint']}, 08:46:09 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:46:09 INFO - 'ts_paint', 08:46:09 INFO - 'tpaint', 08:46:09 INFO - 'sessionrestore', 08:46:09 INFO - 'sessionrestore_no_auto_restore', 08:46:09 INFO - 'tabpaint']}, 08:46:09 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:46:09 INFO - 'ts_paint', 08:46:09 INFO - 'tpaint', 08:46:09 INFO - 'sessionrestore', 08:46:09 INFO - 'sessionrestore_no_auto_restore', 08:46:09 INFO - 'tabpaint']}, 08:46:09 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:46:09 INFO - 'tests': ['a11yr', 08:46:09 INFO - 'ts_paint', 08:46:09 INFO - 'tpaint', 08:46:09 INFO - 'sessionrestore', 08:46:09 INFO - 'sessionrestore_no_auto_restore', 08:46:09 INFO - 'tabpaint']}, 08:46:09 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:46:09 INFO - 'tests': ['a11yr', 08:46:09 INFO - 'ts_paint', 08:46:09 INFO - 'tpaint', 08:46:09 INFO - 'sessionrestore', 08:46:09 INFO - 'sessionrestore_no_auto_restore', 08:46:09 INFO - 'tabpaint']}, 08:46:09 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:46:09 INFO - 'tests': ['tsvgx', 08:46:09 INFO - 'tsvgr_opacity', 08:46:09 INFO - 'tart', 08:46:09 INFO - 'tscrollx', 08:46:09 INFO - 'cart']}, 08:46:09 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:46:09 INFO - 'tsvgr_opacity', 08:46:09 INFO - 'tart', 08:46:09 INFO - 'tscrollx', 08:46:09 INFO - 'cart']}, 08:46:09 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:46:09 INFO - 'talos_options': ['--disable-e10s'], 08:46:09 INFO - 'tests': ['tp5o']}, 08:46:09 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:46:09 INFO - 'tests': ['tp5o']}, 08:46:09 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:46:09 INFO - 'talos_options': ['--disable-e10s', 08:46:09 INFO - '--xperf_path', 08:46:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:46:09 INFO - 'tests': ['tp5n']}, 08:46:09 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:46:09 INFO - 'talos_options': ['--xperf_path', 08:46:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:46:09 INFO - 'tests': ['tp5n']}}, 08:46:09 INFO - 'talos.zip': {'path': '', 08:46:09 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:46:09 INFO - Running post-action listener: _resource_record_post_action 08:46:09 INFO - [mozharness: 2016-08-26 15:46:09.729538Z] Finished populate-webroot step (success) 08:46:09 INFO - [mozharness: 2016-08-26 15:46:09.729629Z] Running create-virtualenv step. 08:46:09 INFO - Running pre-action listener: _resource_record_pre_action 08:46:09 INFO - Running main action method: create_virtualenv 08:46:09 INFO - Creating virtualenv /builds/slave/test/build/venv 08:46:09 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 08:46:09 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:46:09 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:46:09 INFO - Using real prefix '/usr' 08:46:09 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:46:10 INFO - Installing distribute.............................................................................................................................................................................................done. 08:46:12 INFO - Installing pip.................done. 08:46:12 INFO - Return code: 0 08:46:12 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:46:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:46:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:46:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:12 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 0x7f18f6650f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x205e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x212d230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2127090>, '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 0x212a9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x212ae40>, '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': '5cf47e208febbcd81da23b2e0000026f-1472226266.231314-1163103039', '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 08:46:12 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 08:46:12 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 08:46:12 INFO - Using env: {'DISPLAY': ':0', 08:46:12 INFO - 'HOME': '/home/cltbld', 08:46:12 INFO - 'LANG': 'en_US.UTF-8', 08:46:12 INFO - 'LANGUAGE': 'en_US:en', 08:46:12 INFO - 'LOGNAME': 'cltbld', 08:46:12 INFO - 'MAIL': '/var/mail/cltbld', 08:46:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:46:12 INFO - 'MOZ_NO_REMOTE': '1', 08:46:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:46:12 INFO - 'NO_EM_RESTART': '1', 08:46:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:46:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:46:12 INFO - 'PWD': '/builds/slave/test', 08:46:12 INFO - 'SHELL': '/bin/bash', 08:46:12 INFO - 'SHLVL': '1', 08:46:12 INFO - 'TERM': 'linux', 08:46:12 INFO - 'TMOUT': '86400', 08:46:12 INFO - 'USER': 'cltbld', 08:46:12 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1472226266.231314-1163103039', 08:46:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:46:12 INFO - '_': '/tools/buildbot/bin/python'} 08:46:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:46:12 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 08:46:12 INFO - Cleaning up... 08:46:12 INFO - Return code: 0 08:46:12 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:46:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:46:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:46:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:12 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 0x7f18f6650f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x205e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x212d230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2127090>, '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 0x212a9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x212ae40>, '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': '5cf47e208febbcd81da23b2e0000026f-1472226266.231314-1163103039', '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 08:46:12 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 08:46:12 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 08:46:12 INFO - Using env: {'DISPLAY': ':0', 08:46:12 INFO - 'HOME': '/home/cltbld', 08:46:12 INFO - 'LANG': 'en_US.UTF-8', 08:46:12 INFO - 'LANGUAGE': 'en_US:en', 08:46:12 INFO - 'LOGNAME': 'cltbld', 08:46:12 INFO - 'MAIL': '/var/mail/cltbld', 08:46:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:46:12 INFO - 'MOZ_NO_REMOTE': '1', 08:46:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:46:12 INFO - 'NO_EM_RESTART': '1', 08:46:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:46:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:46:12 INFO - 'PWD': '/builds/slave/test', 08:46:12 INFO - 'SHELL': '/bin/bash', 08:46:12 INFO - 'SHLVL': '1', 08:46:12 INFO - 'TERM': 'linux', 08:46:12 INFO - 'TMOUT': '86400', 08:46:12 INFO - 'USER': 'cltbld', 08:46:12 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1472226266.231314-1163103039', 08:46:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:46:12 INFO - '_': '/tools/buildbot/bin/python'} 08:46:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:46:12 INFO - Downloading/unpacking psutil>=3.1.1 08:46:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:46:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:46:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:46:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:46:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:46:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:46:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:46:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:46:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:46:14 INFO - Installing collected packages: psutil 08:46:14 INFO - Running setup.py install for psutil 08:46:14 INFO - building 'psutil._psutil_linux' extension 08:46:14 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 08:46:14 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 08:46:15 INFO - building 'psutil._psutil_posix' extension 08:46:15 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 08:46:15 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 08:46:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:46:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:46:15 INFO - Successfully installed psutil 08:46:15 INFO - Cleaning up... 08:46:15 INFO - Return code: 0 08:46:15 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:46:15 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')]} 08:46:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:46:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:15 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')]} 08:46:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:46:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:15 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 0x7f18f6650f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x205e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x212d230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2127090>, '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 0x212a9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x212ae40>, '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': '5cf47e208febbcd81da23b2e0000026f-1472226266.231314-1163103039', '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 08:46:15 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 08:46:15 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 08:46:15 INFO - Using env: {'DISPLAY': ':0', 08:46:15 INFO - 'HOME': '/home/cltbld', 08:46:15 INFO - 'LANG': 'en_US.UTF-8', 08:46:15 INFO - 'LANGUAGE': 'en_US:en', 08:46:15 INFO - 'LOGNAME': 'cltbld', 08:46:15 INFO - 'MAIL': '/var/mail/cltbld', 08:46:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:46:15 INFO - 'MOZ_NO_REMOTE': '1', 08:46:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:46:15 INFO - 'NO_EM_RESTART': '1', 08:46:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:46:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:46:15 INFO - 'PWD': '/builds/slave/test', 08:46:15 INFO - 'SHELL': '/bin/bash', 08:46:15 INFO - 'SHLVL': '1', 08:46:15 INFO - 'TERM': 'linux', 08:46:15 INFO - 'TMOUT': '86400', 08:46:15 INFO - 'USER': 'cltbld', 08:46:15 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1472226266.231314-1163103039', 08:46:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:46:15 INFO - '_': '/tools/buildbot/bin/python'} 08:46:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:46:15 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:46:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:46:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:46:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:46:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:46:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:46:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:46:17 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:46:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:46:17 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:46:17 INFO - Installing collected packages: mozsystemmonitor 08:46:17 INFO - Running setup.py install for mozsystemmonitor 08:46:17 INFO - Successfully installed mozsystemmonitor 08:46:17 INFO - Cleaning up... 08:46:17 INFO - Return code: 0 08:46:17 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:46:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:46:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:46:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f18f6650f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x205e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x212d230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2127090>, '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 0x212a9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x212ae40>, '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': '5cf47e208febbcd81da23b2e0000026f-1472226266.231314-1163103039', '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 08:46:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 08:46:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 08:46:17 INFO - Using env: {'DISPLAY': ':0', 08:46:17 INFO - 'HOME': '/home/cltbld', 08:46:17 INFO - 'LANG': 'en_US.UTF-8', 08:46:17 INFO - 'LANGUAGE': 'en_US:en', 08:46:17 INFO - 'LOGNAME': 'cltbld', 08:46:17 INFO - 'MAIL': '/var/mail/cltbld', 08:46:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:46:17 INFO - 'MOZ_NO_REMOTE': '1', 08:46:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:46:17 INFO - 'NO_EM_RESTART': '1', 08:46:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:46:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:46:17 INFO - 'PWD': '/builds/slave/test', 08:46:17 INFO - 'SHELL': '/bin/bash', 08:46:17 INFO - 'SHLVL': '1', 08:46:17 INFO - 'TERM': 'linux', 08:46:17 INFO - 'TMOUT': '86400', 08:46:17 INFO - 'USER': 'cltbld', 08:46:17 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1472226266.231314-1163103039', 08:46:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:46:17 INFO - '_': '/tools/buildbot/bin/python'} 08:46:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:46:17 INFO - Downloading/unpacking jsonschema==2.5.1 08:46:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:46:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:46:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:46:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:46:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:46:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:46:19 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:46:19 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:46:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:46:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:46:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:46:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:46:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:46:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:46:19 INFO - Downloading functools32-3.2.3-2.tar.gz 08:46:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:46:19 INFO - warning: no files found matching '*.txt' 08:46:19 INFO - no previously-included directories found matching 'build' 08:46:19 INFO - no previously-included directories found matching 'dist' 08:46:19 INFO - no previously-included directories found matching '.git*' 08:46:19 INFO - Installing collected packages: jsonschema, functools32 08:46:20 INFO - Running setup.py install for functools32 08:46:20 INFO - warning: no files found matching '*.txt' 08:46:20 INFO - no previously-included directories found matching 'build' 08:46:20 INFO - no previously-included directories found matching 'dist' 08:46:20 INFO - no previously-included directories found matching '.git*' 08:46:20 INFO - Successfully installed jsonschema functools32 08:46:20 INFO - Cleaning up... 08:46:20 INFO - Return code: 0 08:46:20 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:46:20 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')]} 08:46:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:46:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:20 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')]} 08:46:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:46:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:20 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 0x7f18f6650f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x205e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x212d230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2127090>, '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 0x212a9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x212ae40>, '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': '5cf47e208febbcd81da23b2e0000026f-1472226266.231314-1163103039', '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 08:46:20 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 08:46:20 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 08:46:20 INFO - Using env: {'DISPLAY': ':0', 08:46:20 INFO - 'HOME': '/home/cltbld', 08:46:20 INFO - 'LANG': 'en_US.UTF-8', 08:46:20 INFO - 'LANGUAGE': 'en_US:en', 08:46:20 INFO - 'LOGNAME': 'cltbld', 08:46:20 INFO - 'MAIL': '/var/mail/cltbld', 08:46:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:46:20 INFO - 'MOZ_NO_REMOTE': '1', 08:46:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:46:20 INFO - 'NO_EM_RESTART': '1', 08:46:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:46:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:46:20 INFO - 'PWD': '/builds/slave/test', 08:46:20 INFO - 'SHELL': '/bin/bash', 08:46:20 INFO - 'SHLVL': '1', 08:46:20 INFO - 'TERM': 'linux', 08:46:20 INFO - 'TMOUT': '86400', 08:46:20 INFO - 'USER': 'cltbld', 08:46:20 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1472226266.231314-1163103039', 08:46:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:46:20 INFO - '_': '/tools/buildbot/bin/python'} 08:46:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:46:20 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:46:20 INFO - Cleaning up... 08:46:20 INFO - Return code: 0 08:46:20 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:46:20 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')]} 08:46:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:46:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:20 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')]} 08:46:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:46:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:20 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 0x7f18f6650f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x205e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x212d230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2127090>, '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 0x212a9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x212ae40>, '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': '5cf47e208febbcd81da23b2e0000026f-1472226266.231314-1163103039', '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 08:46:20 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 08:46:20 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 08:46:20 INFO - Using env: {'DISPLAY': ':0', 08:46:20 INFO - 'HOME': '/home/cltbld', 08:46:20 INFO - 'LANG': 'en_US.UTF-8', 08:46:20 INFO - 'LANGUAGE': 'en_US:en', 08:46:20 INFO - 'LOGNAME': 'cltbld', 08:46:20 INFO - 'MAIL': '/var/mail/cltbld', 08:46:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:46:20 INFO - 'MOZ_NO_REMOTE': '1', 08:46:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:46:20 INFO - 'NO_EM_RESTART': '1', 08:46:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:46:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:46:20 INFO - 'PWD': '/builds/slave/test', 08:46:20 INFO - 'SHELL': '/bin/bash', 08:46:20 INFO - 'SHLVL': '1', 08:46:20 INFO - 'TERM': 'linux', 08:46:20 INFO - 'TMOUT': '86400', 08:46:20 INFO - 'USER': 'cltbld', 08:46:20 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1472226266.231314-1163103039', 08:46:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:46:20 INFO - '_': '/tools/buildbot/bin/python'} 08:46:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:46:20 INFO - Downloading/unpacking blobuploader==1.2.4 08:46:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:46:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:46:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:46:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:46:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:46:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:46:22 INFO - Downloading blobuploader-1.2.4.tar.gz 08:46:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:46:22 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:46:22 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 08:46:22 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 08:46:22 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 08:46:22 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 08:46:22 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 08:46:22 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 08:46:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:46:22 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:46:22 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 08:46:22 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 08:46:22 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 08:46:22 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 08:46:22 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 08:46:22 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 08:46:23 INFO - Downloading docopt-0.6.1.tar.gz 08:46:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:46:23 INFO - Installing collected packages: blobuploader, requests, docopt 08:46:23 INFO - Running setup.py install for blobuploader 08:46:23 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:46:23 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:46:23 INFO - Running setup.py install for requests 08:46:23 INFO - Running setup.py install for docopt 08:46:23 INFO - Successfully installed blobuploader requests docopt 08:46:23 INFO - Cleaning up... 08:46:23 INFO - Return code: 0 08:46:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:46:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:46:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:46:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:23 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 0x7f18f6650f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x205e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x212d230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2127090>, '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 0x212a9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x212ae40>, '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': '5cf47e208febbcd81da23b2e0000026f-1472226266.231314-1163103039', '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 08:46:23 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 08:46:23 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 08:46:23 INFO - Using env: {'DISPLAY': ':0', 08:46:23 INFO - 'HOME': '/home/cltbld', 08:46:23 INFO - 'LANG': 'en_US.UTF-8', 08:46:23 INFO - 'LANGUAGE': 'en_US:en', 08:46:23 INFO - 'LOGNAME': 'cltbld', 08:46:23 INFO - 'MAIL': '/var/mail/cltbld', 08:46:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:46:23 INFO - 'MOZ_NO_REMOTE': '1', 08:46:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:46:23 INFO - 'NO_EM_RESTART': '1', 08:46:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:46:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:46:23 INFO - 'PWD': '/builds/slave/test', 08:46:23 INFO - 'SHELL': '/bin/bash', 08:46:23 INFO - 'SHLVL': '1', 08:46:23 INFO - 'TERM': 'linux', 08:46:23 INFO - 'TMOUT': '86400', 08:46:23 INFO - 'USER': 'cltbld', 08:46:23 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1472226266.231314-1163103039', 08:46:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:46:23 INFO - '_': '/tools/buildbot/bin/python'} 08:46:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:46:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:46:24 INFO - Running setup.py (path:/tmp/pip-_o3rJL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:46:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:46:24 INFO - Running setup.py (path:/tmp/pip-0uRVEL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:46:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:46:24 INFO - Running setup.py (path:/tmp/pip-vJiakk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:46:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:46:24 INFO - Running setup.py (path:/tmp/pip-R1Bqv2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:46:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:46:24 INFO - Running setup.py (path:/tmp/pip-TibBIO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:46:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:46:24 INFO - Running setup.py (path:/tmp/pip-PJFkxk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:46:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:46:24 INFO - Running setup.py (path:/tmp/pip-3MhzxQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:46:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:46:24 INFO - Running setup.py (path:/tmp/pip-JyLO8C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:46:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:46:24 INFO - Running setup.py (path:/tmp/pip-cgauJZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:46:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:46:24 INFO - Running setup.py (path:/tmp/pip-wexjqt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:46:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:46:24 INFO - Running setup.py (path:/tmp/pip-A1pPDw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:46:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:46:24 INFO - Running setup.py (path:/tmp/pip-RV7L2t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:46:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:46:24 INFO - Running setup.py (path:/tmp/pip-H2mABT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:46:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:46:25 INFO - Running setup.py (path:/tmp/pip-hzq8Jb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:46:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:46:25 INFO - Running setup.py (path:/tmp/pip-m9QMKn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:46:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:46:25 INFO - Running setup.py (path:/tmp/pip-aArmUD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:46:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:46:25 INFO - Running setup.py (path:/tmp/pip-UvxbgM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:46:25 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:46:25 INFO - Running setup.py install for manifestparser 08:46:25 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:46:25 INFO - Running setup.py install for mozcrash 08:46:25 INFO - Running setup.py install for mozdebug 08:46:25 INFO - Running setup.py install for mozdevice 08:46:25 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:46:25 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:46:25 INFO - Running setup.py install for mozfile 08:46:25 INFO - Running setup.py install for mozhttpd 08:46:25 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:46:25 INFO - Running setup.py install for mozinfo 08:46:26 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:46:26 INFO - Running setup.py install for mozInstall 08:46:26 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:46:26 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:46:26 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:46:26 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:46:26 INFO - Running setup.py install for mozleak 08:46:26 INFO - Running setup.py install for mozlog 08:46:26 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:46:26 INFO - Running setup.py install for moznetwork 08:46:26 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:46:26 INFO - Running setup.py install for mozprocess 08:46:26 INFO - Running setup.py install for mozprofile 08:46:26 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:46:26 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:46:26 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:46:26 INFO - Running setup.py install for mozrunner 08:46:26 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:46:26 INFO - Running setup.py install for mozscreenshot 08:46:26 INFO - Running setup.py install for moztest 08:46:27 INFO - Running setup.py install for mozversion 08:46:27 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:46:27 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:46:27 INFO - Cleaning up... 08:46:27 INFO - Return code: 0 08:46:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:46:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:46:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:46:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:27 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 0x7f18f6650f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x205e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x212d230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2127090>, '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 0x212a9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x212ae40>, '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': '5cf47e208febbcd81da23b2e0000026f-1472226266.231314-1163103039', '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 08:46:27 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 08:46:27 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 08:46:27 INFO - Using env: {'DISPLAY': ':0', 08:46:27 INFO - 'HOME': '/home/cltbld', 08:46:27 INFO - 'LANG': 'en_US.UTF-8', 08:46:27 INFO - 'LANGUAGE': 'en_US:en', 08:46:27 INFO - 'LOGNAME': 'cltbld', 08:46:27 INFO - 'MAIL': '/var/mail/cltbld', 08:46:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:46:27 INFO - 'MOZ_NO_REMOTE': '1', 08:46:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:46:27 INFO - 'NO_EM_RESTART': '1', 08:46:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:46:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:46:27 INFO - 'PWD': '/builds/slave/test', 08:46:27 INFO - 'SHELL': '/bin/bash', 08:46:27 INFO - 'SHLVL': '1', 08:46:27 INFO - 'TERM': 'linux', 08:46:27 INFO - 'TMOUT': '86400', 08:46:27 INFO - 'USER': 'cltbld', 08:46:27 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1472226266.231314-1163103039', 08:46:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:46:27 INFO - '_': '/tools/buildbot/bin/python'} 08:46:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:46:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:46:27 INFO - Running setup.py (path:/tmp/pip-7eXTZn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:46:27 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)) 08:46:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:46:27 INFO - Running setup.py (path:/tmp/pip-TGhbfz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:46:27 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)) 08:46:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:46:27 INFO - Running setup.py (path:/tmp/pip-f7QJkY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:46:27 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)) 08:46:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:46:27 INFO - Running setup.py (path:/tmp/pip-aUkTgB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:46:27 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)) 08:46:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:46:27 INFO - Running setup.py (path:/tmp/pip-cPw8bZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:46:27 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)) 08:46:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:46:27 INFO - Running setup.py (path:/tmp/pip-xkWcAT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:46:27 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)) 08:46:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:46:27 INFO - Running setup.py (path:/tmp/pip-Gx4nmm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:46:27 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)) 08:46:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:46:27 INFO - Running setup.py (path:/tmp/pip-yOLdAa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:46:27 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)) 08:46:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:46:27 INFO - Running setup.py (path:/tmp/pip-Szcz55-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:46:27 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)) 08:46:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:46:28 INFO - Running setup.py (path:/tmp/pip-8Jx5zg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:46:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 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)) 08:46:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:46:28 INFO - Running setup.py (path:/tmp/pip-kRhEIp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:46:28 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)) 08:46:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:46:28 INFO - Running setup.py (path:/tmp/pip-3ycNsB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:46:28 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)) 08:46:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:46:28 INFO - Running setup.py (path:/tmp/pip-TlUr9Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:46:28 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)) 08:46:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:46:28 INFO - Running setup.py (path:/tmp/pip-n9csvX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:46:28 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)) 08:46:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:46:28 INFO - Running setup.py (path:/tmp/pip-keNSSB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:46:28 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)) 08:46:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:46:28 INFO - Running setup.py (path:/tmp/pip-zNz_l_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:46:28 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)) 08:46:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:46:28 INFO - Running setup.py (path:/tmp/pip-6_cIxe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:46:28 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)) 08:46:28 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)) 08:46:28 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)) 08:46:28 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)) 08:46:28 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)) 08:46:28 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)) 08:46:28 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:46:28 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 08:46:28 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 08:46:28 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 08:46:28 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 08:46:28 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 08:46:28 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 08:46:30 INFO - Downloading blessings-1.6.tar.gz 08:46:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:46:30 INFO - Installing collected packages: blessings 08:46:30 INFO - Running setup.py install for blessings 08:46:30 INFO - Successfully installed blessings 08:46:30 INFO - Cleaning up... 08:46:30 INFO - Return code: 0 08:46:30 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:46:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:46:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:46:30 INFO - Reading from file tmpfile_stdout 08:46:30 INFO - Current package versions: 08:46:30 INFO - argparse == 1.2.1 08:46:30 INFO - blessings == 1.6 08:46:30 INFO - blobuploader == 1.2.4 08:46:30 INFO - docopt == 0.6.1 08:46:30 INFO - functools32 == 3.2.3-2 08:46:30 INFO - jsonschema == 2.5.1 08:46:30 INFO - manifestparser == 1.1 08:46:30 INFO - mozInstall == 1.12 08:46:30 INFO - mozcrash == 0.17 08:46:30 INFO - mozdebug == 0.1 08:46:30 INFO - mozdevice == 0.48 08:46:30 INFO - mozfile == 1.2 08:46:30 INFO - mozhttpd == 0.7 08:46:30 INFO - mozinfo == 0.9 08:46:30 INFO - mozleak == 0.1 08:46:30 INFO - mozlog == 3.3 08:46:30 INFO - moznetwork == 0.27 08:46:30 INFO - mozprocess == 0.23 08:46:30 INFO - mozprofile == 0.28 08:46:30 INFO - mozrunner == 6.12 08:46:30 INFO - mozscreenshot == 0.1 08:46:30 INFO - mozsystemmonitor == 0.3 08:46:30 INFO - moztest == 0.7 08:46:30 INFO - mozversion == 1.4 08:46:30 INFO - psutil == 3.1.1 08:46:30 INFO - requests == 1.2.3 08:46:30 INFO - wsgiref == 0.1.2 08:46:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:46:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:46:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:46:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:30 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 0x7f18f6650f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x205e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x212d230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2127090>, '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 0x212a9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x212ae40>, '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': '5cf47e208febbcd81da23b2e0000026f-1472226266.231314-1163103039', '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 08:46:30 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 08:46:30 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 08:46:30 INFO - Using env: {'DISPLAY': ':0', 08:46:30 INFO - 'HOME': '/home/cltbld', 08:46:30 INFO - 'LANG': 'en_US.UTF-8', 08:46:30 INFO - 'LANGUAGE': 'en_US:en', 08:46:30 INFO - 'LOGNAME': 'cltbld', 08:46:30 INFO - 'MAIL': '/var/mail/cltbld', 08:46:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:46:30 INFO - 'MOZ_NO_REMOTE': '1', 08:46:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:46:30 INFO - 'NO_EM_RESTART': '1', 08:46:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:46:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:46:30 INFO - 'PWD': '/builds/slave/test', 08:46:30 INFO - 'SHELL': '/bin/bash', 08:46:30 INFO - 'SHLVL': '1', 08:46:30 INFO - 'TERM': 'linux', 08:46:30 INFO - 'TMOUT': '86400', 08:46:30 INFO - 'USER': 'cltbld', 08:46:30 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1472226266.231314-1163103039', 08:46:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:46:30 INFO - '_': '/tools/buildbot/bin/python'} 08:46:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:46:31 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)) 08:46:31 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)) 08:46:31 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)) 08:46:31 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)) 08:46:31 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)) 08:46:31 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)) 08:46:31 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)) 08:46:31 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)) 08:46:31 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)) 08:46:31 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)) 08:46:31 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)) 08:46:31 INFO - Cleaning up... 08:46:31 INFO - Return code: 0 08:46:31 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:46: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')]} 08:46:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:46:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46: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')]} 08:46:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:46:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46: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']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f18f6650f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x205e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x212d230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2127090>, '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 0x212a9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x212ae40>, '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': '5cf47e208febbcd81da23b2e0000026f-1472226266.231314-1163103039', '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 08:46: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'] in /builds/slave/test/build 08:46: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 08:46:31 INFO - Using env: {'DISPLAY': ':0', 08:46:31 INFO - 'HOME': '/home/cltbld', 08:46:31 INFO - 'LANG': 'en_US.UTF-8', 08:46:31 INFO - 'LANGUAGE': 'en_US:en', 08:46:31 INFO - 'LOGNAME': 'cltbld', 08:46:31 INFO - 'MAIL': '/var/mail/cltbld', 08:46:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:46:31 INFO - 'MOZ_NO_REMOTE': '1', 08:46:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:46:31 INFO - 'NO_EM_RESTART': '1', 08:46:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:46:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:46:31 INFO - 'PWD': '/builds/slave/test', 08:46:31 INFO - 'SHELL': '/bin/bash', 08:46:31 INFO - 'SHLVL': '1', 08:46:31 INFO - 'TERM': 'linux', 08:46:31 INFO - 'TMOUT': '86400', 08:46:31 INFO - 'USER': 'cltbld', 08:46:31 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1472226266.231314-1163103039', 08:46:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:46:31 INFO - '_': '/tools/buildbot/bin/python'} 08:46:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:46:31 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:46:31 INFO - Cleaning up... 08:46:31 INFO - Return code: 0 08:46:31 INFO - Running post-action listener: _resource_record_post_action 08:46:31 INFO - Running post-action listener: _start_resource_monitoring 08:46:31 INFO - Starting resource monitoring. 08:46:31 INFO - [mozharness: 2016-08-26 15:46:31.487269Z] Finished create-virtualenv step (success) 08:46:31 INFO - [mozharness: 2016-08-26 15:46:31.487742Z] Running install step. 08:46:31 INFO - Running pre-action listener: _resource_record_pre_action 08:46:31 INFO - Running main action method: install 08:46:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:46:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:46:31 INFO - Reading from file tmpfile_stdout 08:46:31 INFO - Detecting whether we're running mozinstall >=1.0... 08:46:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:46:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:46:31 INFO - Reading from file tmpfile_stdout 08:46:31 INFO - Output received: 08:46:31 INFO - Usage: mozinstall [options] installer 08:46:31 INFO - Options: 08:46:31 INFO - -h, --help show this help message and exit 08:46:31 INFO - -d DEST, --destination=DEST 08:46:31 INFO - Directory to install application into. [default: 08:46:31 INFO - "/builds/slave/test"] 08:46:31 INFO - --app=APP Application being installed. [default: firefox] 08:46:31 INFO - mkdir: /builds/slave/test/build/application 08:46:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 08:46:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 08:46:47 INFO - Reading from file tmpfile_stdout 08:46:47 INFO - Output received: 08:46:47 INFO - /builds/slave/test/build/application/firefox/firefox 08:46:47 INFO - Running post-action listener: _resource_record_post_action 08:46:47 INFO - [mozharness: 2016-08-26 15:46:47.900681Z] Finished install step (success) 08:46:47 INFO - [mozharness: 2016-08-26 15:46:47.900963Z] Running run-tests step. 08:46:47 INFO - Running pre-action listener: _resource_record_pre_action 08:46:47 INFO - Running main action method: run_tests 08:46:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:46:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:46:47 INFO - Python 2.7.3 08:46:47 INFO - Return code: 0 08:46:47 INFO - grabbing minidump binary from tooltool 08:46:47 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')]} 08:46:47 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 0x2127090>, '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 0x212a9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x212ae40>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:46:47 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 08:46:47 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 08:46:47 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 08:46:47 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:46:47 INFO - Return code: 0 08:46:47 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 08:46:47 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:46:47 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:46:47 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 08:46:47 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:46:47 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/LIR-0PQARW-s9sj9Lmf4gg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-011', '--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 08:46:47 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/LIR-0PQARW-s9sj9Lmf4gg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-011 --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 08:46:47 INFO - Using env: {'DISPLAY': ':0', 08:46:47 INFO - 'HOME': '/home/cltbld', 08:46:47 INFO - 'LANG': 'en_US.UTF-8', 08:46:47 INFO - 'LANGUAGE': 'en_US:en', 08:46:47 INFO - 'LOGNAME': 'cltbld', 08:46:47 INFO - 'MAIL': '/var/mail/cltbld', 08:46:47 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:46:47 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 08:46:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:46:47 INFO - 'MOZ_NO_REMOTE': '1', 08:46:47 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:46:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:46:47 INFO - 'NO_EM_RESTART': '1', 08:46:47 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:46:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:46:47 INFO - 'PWD': '/builds/slave/test', 08:46:47 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:46:47 INFO - 'SHELL': '/bin/bash', 08:46:47 INFO - 'SHLVL': '1', 08:46:47 INFO - 'TERM': 'linux', 08:46:47 INFO - 'TMOUT': '86400', 08:46:47 INFO - 'USER': 'cltbld', 08:46:47 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1472226266.231314-1163103039', 08:46:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:46:47 INFO - '_': '/tools/buildbot/bin/python'} 08:46:47 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/LIR-0PQARW-s9sj9Lmf4gg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-011', '--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 08:46:48 INFO - mozversion application_buildid: 20160826075817 08:46:48 INFO - mozversion application_changeset: 4eda5a74d949ad4c8bc587898fb420a8ef8bd28d 08:46:48 INFO - mozversion application_display_name: Nightly 08:46:48 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:46:48 INFO - mozversion application_name: Firefox 08:46:48 INFO - mozversion application_remotingname: firefox 08:46:48 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 08:46:48 INFO - mozversion application_vendor: Mozilla 08:46:48 INFO - mozversion application_version: 51.0a1 08:46:48 INFO - mozversion platform_buildid: 20160826075817 08:46:48 INFO - mozversion platform_changeset: 4eda5a74d949ad4c8bc587898fb420a8ef8bd28d 08:46:48 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 08:46:48 INFO - mozversion platform_version: 51.0a1 08:46:48 INFO - using testdate: 1472226408 08:46:48 INFO - actual date: 1472226408 08:46:48 INFO - starting webserver on 'localhost:54452' 08:46:48 INFO - SUITE-START | Running 2 tests 08:46:48 INFO - TEST-START | dromaeo_css 08:46:48 INFO - operating with platform_type : linux_ 08:46:48 INFO - Initialising browser for dromaeo_css test... 08:46:48 INFO - TEST-INFO | started process 11963 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpJNWfKK/profile http://localhost:54452/getInfo.html) 08:46:53 INFO - PROCESS | 11963 | __metrics Screen width/height:1600/1200 08:46:53 INFO - PROCESS | 11963 | colorDepth:24 08:46:53 INFO - PROCESS | 11963 | Browser inner width/height: 1024/697 08:46:53 INFO - PROCESS | 11963 | __metrics 08:46:53 INFO - PROCESS | 11963 | 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" 08:46:53 INFO - PROCESS | 11963 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:46:54 INFO - PROCESS | 11963 | console.error: PushService: 08:46:54 INFO - PROCESS | 11963 | stateChangeProcessEnqueue: Error transitioning state 08:46:54 INFO - PROCESS | 11963 | UnknownError 08:46:55 INFO - TEST-INFO | 11963: exit 0 08:46:55 INFO - Browser initialized. 08:46:55 INFO - Running cycle 1/1 for dromaeo_css test... 08:46:55 INFO - TEST-INFO | started process 12047 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpJNWfKK/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:47:07 INFO - PROCESS | 12047 | RSS: Main: 180228096 08:47:07 INFO - PROCESS | 12047 | 08:47:58 INFO - PROCESS | 12047 | 1472226478671 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] 08:50:12 INFO - PROCESS | 12047 | Cycle 1(1): loaded http://localhost:54452/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54452/tests/dromaeo/cssquery-ext.html) 08:50:13 INFO - PROCESS | 12047 | RSS: Main: 270700544 08:50:13 INFO - PROCESS | 12047 | 08:53:15 INFO - PROCESS | 12047 | Cycle 1(1): loaded http://localhost:54452/tests/dromaeo/cssquery-ext.html (next: http://localhost:54452/tests/dromaeo/cssquery-jquery.html) 08:53:16 INFO - PROCESS | 12047 | RSS: Main: 276484096 08:53:16 INFO - PROCESS | 12047 | 08:56:18 INFO - PROCESS | 12047 | Cycle 1(1): loaded http://localhost:54452/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54452/tests/dromaeo/cssquery-mootools.html) 08:56:19 INFO - PROCESS | 12047 | RSS: Main: 217853952 08:56:19 INFO - PROCESS | 12047 | 08:59:21 INFO - PROCESS | 12047 | Cycle 1(1): loaded http://localhost:54452/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54452/tests/dromaeo/cssquery-prototype.html) 08:59:22 INFO - PROCESS | 12047 | RSS: Main: 218509312 08:59:22 INFO - PROCESS | 12047 | 09:02:25 INFO - PROCESS | 12047 | Cycle 1(1): loaded http://localhost:54452/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54452/tests/dromaeo/cssquery-yui.html) 09:02:26 INFO - PROCESS | 12047 | RSS: Main: 217157632 09:02:26 INFO - PROCESS | 12047 | 09:05:29 INFO - PROCESS | 12047 | Cycle 1(1): loaded http://localhost:54452/tests/dromaeo/cssquery-yui.html (next: http://localhost:54452/tests/dromaeo/cssquery-dojo.html) 09:05:29 INFO - PROCESS | 12047 | RSS: Main: 283856896 09:05:29 INFO - PROCESS | 12047 | 09:05:29 INFO - PROCESS | 12047 | __start_tp_report 09:05:29 INFO - PROCESS | 12047 | _x_x_mozilla_page_load 09:05:29 INFO - PROCESS | 12047 | _x_x_mozilla_page_load_details 09:05:29 INFO - PROCESS | 12047 | |i|pagename|runs| 09:05:29 INFO - PROCESS | 12047 | |0;dojo.html;2582.31565163044;2599.285196570943;2607.2569317744424;2643.643108180394;2656.150031989764;4584.770761461922;4640.930386044203;4651.4883362830105;4736.62107031438;4785.425748910131;4677.181493238678;4706.270528068155;4749.857504274869;4777.761111944406;4777.9761101194445;4280.957190428087;4283.957160428403;4359.956400436003;4403.185410699015;4515.706479078849;4426.29179331307;4435.778211089449;4449.132419178261;4484.304932735431;4494.146112238681;4277.379779931893;4322.0491491871735;4323.524412314643;4362.956370436286;4389.978050109761;4253.638440732541;4314.611684948339;4329.415528903621;4355.216061108999;4392.692511524195;4238.08881090564;4319.50325712544;4324.935125973113;4326.199653064162;4378.277584198603;4638.9536104638855;4702.059588082398;4708.7174769513595;4714.316424118518;4761.381020467352;4889.9511004889855;4998.650094493387;5055.469175736532;5062.139436295838;5091.46539613339;5066.82266120684;5096.261042148868;5096.923546146811;5099.949000509984;5112.923306150411;4618.792154353062;4737.123632128068;4740.597049250783;4758.738269395148;4784.282357646381;5120.948790512047;5175.430702622709;5190.766415511311;5191.402988656278;5275.076861549213;4992.575631071327;5002.299678045077;5004.824831130893;5005.499450054966;5054.368203974504;4860.902781944341;5068.341115654941;5077.39071311445;5079.111155547765;5086.694798312114;4951.876203094951;5003.374578177728;5006.424261210008;5075.796968121308;5078.263651770547;4991.151504244287;4996.725180115129;4998.90002199954;5006.5994720423005;5068;4253.532111467736;4253.914921701548;4253.97873010633;4255.021345090647;4299.634531064832;3629.256002519477;3648.3068217038676;3653.3789255826573;3714.0343510686876;3750.362438385481;4428.490723566767;4432.246518091868;4483.574060464251;4488.461384633865;4493.887652808706;5061.620378471629;5062.088824011639;5066.138756411346;5101.158308879068;5106.948930510722;4218.261804184255;4333.133373325348;4391.165678521071;4406.5593440655675;4447.3773671685985;4564.817407303737;4568.497465278817;4594.908101837945;4615.723056616614;4619.168549661026;4830.396200474941;4836.951630483791;4839.322494850794;4842.782074806642;4890.608751299958;4888.364512613338;4933.333999909966;5004.099262132851;5057.418396884405;5110.463401342806;4758.2386818108935;4800.711957282574;4803.447603525639;4830.468648448597;4850.248211527219;4974.427940786856;4975.104481193275;4979.950200497949;4984.700917944935;5044.924326135184;4861.392325959255;4977.303177555217;5030.8239211627415;5051.191809310492;5103.974480127576;4871.780769865435;4906.092372911023;4907.411110666742;4960.528749768766;4966.106100901728;4895.804167833389;4907.2393778964315;4907.386576677915;4910.189818679878;4961.82633607822;4688.460827004938;4813.302071199586;4836.322914791862;4904.828331008398;4924.433690125538;4892.633052521146;4898.730569818694;4922.630802689884;5001.64988450805;5016.122178618654;4801.51984801531;4805.255185446262;4808.06242782654;4818.229083346648;4880.292357608198 09:05:29 INFO - PROCESS | 12047 | |1;ext.html;546.1234718277165;558.4582954534103;569.8917205730912;575.9481646651803;592.0349829777462;38135.42796858046;40427.19145617086;40540.391894121574;43855.34216986748;43876.56123438765;40018;40695.38956915641;40752;40786;40794.38808417876;7228.421726261887;7454.552726836408;7663;7668.271514206142;7669.539827610333;7167.534110282841;7371.336579707825;7375.188729239806;9796.804063918717;10088.697339079803;9998.10017098461;10045.095941365276;10050.798984020352;10054.849177262347;10060.647877324294;10066.144377727902;10098.091171794575;10112.494375281207;10125.088742013255;10126.848097278547;9926.2059035277;10008;10013.649522266685;10015.148712359458;10015.549300281506;38390;38640.613593863985;38771.224575508335;38777;38837;3035.741961933238;3127;3158.478850989584;3173;3291.440455122635;28304.43391132166;28406.431871362456;28496;29792.255193620116;30505.542416863766;3031.651360093573;3055.5263934090253;3098.8295643739584;3134.2477805326785;3169.793963392372;248123;248422;248744;249027;249107;31352.686473135433;51264;51642;51729.22406163911;51771.223431648556;31817.840910795298;33225;33343.3331333372;33358.16604584905;33368.49947250793;13019.088663793444;13138.671533211746;13146.539871104656;13153.408096635674;13159.802602960963;10870;10892.836607450894;10918.890811091946;10918.945405273082;10920.563177472812;7208.747693830691;7569.47013709035;7821.92178078212;7868.291853733192;7951.443398962017;3070.646875609289;3094.535819627029;3094.90715278542;3109.0517392195597;3133.5613014178034;787.5274835098895;797.4577287444594;905.7826121730869;927.7402327348353;986.2849434160177;27273.45453090967;27401.45197096047;27676.446471070467;27689.600527896866;27949.720502795117;958.7794807194244;961.7066794627706;963.6819849449665;1000.5347513406156;1023.9129673977795;13989.440422383193;14622.195779232032;14766.557003289918;15196.468123615621;15196.848031519543;356724;359537;359879;360365;361806;49563.25655115176;50188;50252;50252.24621630678;50339.24491132636;1064.4730858225228;1145.1297014269048;1188.477070089158;1197.7724232395817;1202.2546021466571;27467.587986179824;28872;28895.56656650194;29731.554026689184;29774.25564360908;41343;41958.37062444127;41960;41962;42008.36987445252;308966;311225;311429.8426728157;311591;312082;24557.38606534851;25199.11803086883;25725.614115787903;25741;26581.734182657925;39158.6084139155;39361.80319098386;39414.60585394224;39840.00399990023;40619;33963.830180848934;33985.490217646264;34263.65736342605;34389.65610343964;34577.65422345845;33341.83329083339;33716.83141584276;33847;34479.13802154866;35071.649283506835;33739.49390759091;33932.830335848164;34177.65822341745;34267.82866085654;36025.27949441049;27944.720552794213;28209.858950705115;32692.403536576516;33042;33197;25675.871620641778;25699.229023129337;25736.485270294863;25785.484290314464;26120.34699132537 09:05:29 INFO - PROCESS | 12047 | |2;jquery.html;333.9115134489359;340.43657746429653;345.7406944791407;348.2894894415392;363.4802232807086;12447.128700990934;15735.449259275927;16194.838051619465;16344;17292.82707172931;16406.67186656266;16490.587735306624;17071.829281707178;17266;17438.82561174388;4985.576226020784;5058.468860769622;5073.137566613685;5087.982403519292;5128.435872054071;3721.162738383869;3920.470736450571;5010.473900240477;5091.66904151229;5095.031943930641;3897.7271590988644;3904.3752999520084;4596.954030459686;6003.759849606011;6006.96996515019;6090.208272924514;6096.1770161028135;6097;6100.938990610081;6101.969490152521;5112.744362781846;5197.428282888879;5244.449332820037;5245.26566280721;5247.212918062283;20268.391948241428;20306.49233769153;20522.79477205224;20528.486787830276;20622;1781.7861856577294;1832.3311991123278;1969.1237399357292;1997.9300724474574;2075.450005748478;12922.160059596095;12951.48194072236;13054.216746995211;13366.93316533411;13823.447062117502;1726.8186840381704;1735.0977491704368;1758.7041962761564;1767.4344209852852;1788.7764029496313;67199;67392.66303668499;68675;69982;70041.94937075948;9176.770580735534;9206;9274.258059355236;9448.291378146667;9680.128788409076;12831.358432078545;12837.101402901893;12868.227906325463;12871.292078935561;12878.484860605471;7118.216996130422;7304.780856574312;7325.377342925911;7461.067366579177;7492.887606685904;7075.257098004636;7137.643117844191;7272.600006999666;7306.378957788541;7309.707611695579;5895.145203945404;6045.818625441156;6048.213732214784;6053.364396738367;6210.223722034746;1693.8814283000072;1698.388580111159;1709.632429027765;1718.518814731877;1771.6191018930992;812.8943237379103;814.2630919018297;824.5011767880466;922.6401703335705;946.6213514594217;16437.342506299854;16986.915065424593;17298.65402691939;17734.11329433549;17895.642087158183;825.4551995682821;871.3551971541141;883.4699180491782;912.6531917871299;939.5677988125402;8695;10008.249381296286;10215.23385746072;10245.59017639301;10257.17942564593;376371;385232;399259;403931;405066;41944.1611167781;63758.36241637524;68684;69834.65082674554;70692;883.7172104926491;905.3843386497246;928.9628414863465;941.0918463682442;945.2910317262053;15733.763993539877;15894.364225431316;16035.358585656442;16236.675266494367;16352.182390880645;39212.6078739209;39547.80226098851;39995.20009599734;40001.79999099986;41206;54698.17952730792;55440.72279638576;55919.440805591425;56554;57094.7145264271;6799.830004249933;6977.895331569929;7060.435165186876;7639.885401718868;7769.922300777146;11228.045616122721;11692.620885453583;12193;12213.267203967791;12384.380780960735;10444.63443779464;10504.159667226757;10506.21203409732;10772.24594278393;10892.891071089187;10409.895901040893;10644.308119972176;10691.57233710674;10950.780984380108;11052;10674.839877402;10760.3543787373;10866.456677165952;11032.503537340679;11053.502592383202;7121.750738724119;7199.1001124859395;7217.6391180441815;7440.511897620389;7450.143233528213;9232.446053236827;10577.471126443494;10678.89321106779;10800.837987430037;10883.346999179757 09:05:29 INFO - PROCESS | 12047 | |3;mootools.html;1241.3296819717352;1372;1384.5015794314045;1467.2956980649287;1683.7053515634752;7258.927410725898;7673;7682.539047657145;7915.6042197890165;7920.524768513893;7830.412719046066;7927.048754149496;7934.682612695486;7951.244631759988;8006.519608823474;1840.3098837935775;2151.257816053459;2176.042541281839;2184.6176919039185;2192.8793916334594;1896;1907.1799126375654;1912.8182822631827;2096.308218287969;2186.081845624841;2165.664322030088;2173.608750424923;2175.2386664667397;2175.7389113306426;2178.346496051186;2094.4135642020333;2111.6938043983614;2137.2519618133683;2159.91360345586;2162.0811155258953;1998.7301714268533;2086.405374468284;2107.072887929306;2108.630989576833;2118.0998075817715;7984.28141467265;8014.318782903402;8023.638936247883;8033.919660803375;8046.718364857202;4568.794404251784;4668.439787225555;4699.201135806921;4716.787744551503;4720.480747117815;2785.9721402785917;2856.600075989363;2911.6360454943133;2922.3862988772385;2942.911712648624;915.3180880244195;945.4280160502934;949.2975198353168;958.1233171647934;961.9134277914951;10876.564937402494;10940.56237750489;10987.890121098766;10998.285111467729;11022.338659680445;3829.617038296148;3949.1706741584017;4051.39229115635;4057.33054046085;4121.155163191538;8360.372972027;8363;8376.874346884802;8379.70671026511;8382.49705017701;4601.953980460219;4604.746738929324;4609.3086037094035;4612.3542704021465;4617.561331673485;4138.337865941495;4319.503257125408;4319.8488052918;4320.567943205654;4321.67587430944;3229.564008858821;3263.7715359924578;3333.2500187457626;3341.081202669285;3355.1444381682513;979.5689896445683;995.5470261031359;998.4109375468363;1003.3528374198747;1009.7879445316419;706.9787906362817;813.9470934389245;822.4859462835727;824.418784756745;845.8477474054606;5148.30497882781;5179.689218646893;5241.318628578337;5250.291210686585;5304.965531721277;406.10656555577845;407.8817143028548;409.60882357348663;423.9576042395797;427.36322878910823;3504.7196224301993;3564.8574057037945;3635.163912300186;3661.9450708239397;3729.5710993235584;9144.222741066951;9666.081722236377;9680.56437460316;9688.176504997155;9689.224862011022;9060.909390906138;9390.154886060287;9460.101290377403;9555.713328600154;9795.265355098396;7120.287971202838;7299.306565876233;7305.050343455317;7311.707531698672;7315.743948961761;5208.322918020633;5330.120530112643;5376.032314183484;5438.428964958623;5546.722663866871;8524.786880328042;8785.604647790993;8813.515256660945;8924.910750892408;8940.642374304955;8853.822923541364;9006.909930900607;9028.864567031369;9065.32010099232;9071.319651026068;6159.692015399301;6697.464202863856;6854.554453960476;6878.449724022166;6902.930970690229;7682.462227644235;7743.4966727162555;7749.806254843674;7752.418568607264;7754.457187996786;6723.092382528296;6823.317668233137;6904.344087311697;6909.654517274015;7090.610016448938;6653.434458071119;6724.663766811542;6745.089412929388;6787.185537735503;6813.591184528944;6425.485961123192;6481.611103333815;6489.383508566679;6560.868782624225;6599.5050371223315;4493.326001099861;4517.570830771071;4522.909541809211;4611.88470288232;4743.691660042086;4334.891627709332;4478.171538265431;4492.707973981681;4540.318952157203;4545.977270113628 09:05:29 INFO - PROCESS | 12047 | |4;prototype.html;283.64827613758945;288.51241402030564;293.21272383649904;299.3190491631539;299.74970899299086;4860.416749989998;4865.367502224714;4915;4916.754162291884;4924.261360795882;4798.232282834747;4807.831725889595;4843.612510999121;4860.173770459021;4874.731889746063;3594.9640503594956;3645.3802853514835;3774.905277469544;3800.3539398302355;3807.562130355003;3808.8095595220266;3809.4285857121513;3821.4332123829236;3839.616038396166;3852.6339997700175;3778.735488515805;3782.1301100746846;3794.1842503861662;3796.9620303796883;3800.5059342285463;3602.9279414411694;3672.669459748609;3767.6640482900502;3780.206156707095;3784.8486060557543;3318.2699806042633;3333.449980753197;3334.816585087819;3337.349216902705;3337.666233376667;4677.298405239241;4690.718556886563;4699.882502937419;4702.6473014524045;4733.5503127202865;4139.544650088488;4303.397524346594;4307.633851122659;4310.870673879788;4312.568743125694;4990.176620857555;4991.800327986876;4997.100521906055;5017.573506251973;5054.69671819692;3625.329314076904;3645.2527231917393;3658.414653655402;3663.2673465307043;3666.5966743658178;5046.318746969149;5266.210068489757;5288.92066619001;5309.230161626581;5319.441458646864;3600.4959305697225;3609.9639003610155;3612.151144481045;3614.801185934746;3619.6199399063244;3662.7363559571904;3673.3755261605593;3674.81625918703;3678.70570354371;3678.8528458861347;4076.0421300994244;4139.275626765304;4215.22018426586;4235.30117530604;4240.363945408213;4978.601711863114;4980.630326660197;5012.273220383097;5026.321446604735;5026.58723812572;1243.9440225189887;1251.5181655062866;1264.0582765839508;1264.64589914824;1268.923864568129;766.938644908406;790.6600161930426;791.1732239809413;817.9222973817477;821.8233079887733;4356.934645980313;4450.841341949152;4510.481294651091;4510.842120525832;4516.209663308973;5158.767855446514;5162.664426812245;5167.173252279617;5182.611304152204;5184.144616138295;4405.691601587922;4476.6194873436125;4487.169873573334;4502.684812063124;4503.81984720614;2564.5255627708934;2728.5361488546996;2761.461515004595;2768.4739899419046;2770.9193414568335;4948.455669876311;4951.851444456672;4952.653314267966;4962.950370496321;4984.825531106394;5220.347456567929;5265.710385928811;5334.279872217201;5355.678659280456;5358.678479291255;4900.705957642482;4906.779194936236;4911.361523001987;4920.852374428773;4931.876703082452;5176.223566465061;5209.447370788363;5218.817341393033;5221.477852214748;5222.55608273301;4876.8295609054285;5117.2068329409;5127.615428842777;5142.665726727751;5166.700818135893;4968.925466118083;4972.632526055364;4972.781668491243;4975.925361119658;4982.8754281143265;3646.052026473189;3662.011256960659;3681.889543313705;3686.465462507975;3695.1131728384457;5081.7713202905215;5241.44964778708;5286.524212820865;5315.574754019746;5319.574434045189;5053.635518410082;5054.4187418447345;5064.772085256099;5124.333836601218;5127.5897928166405;5048.848534543969;5059.443461219336;5059.924101138412;5127.79488820443;5133.666311689728;4753.643476739189;4948.05986862495;5006.924896126488;5018.698878067327;5038.193888977892;4831.855044348676;4938.851834444972;5060.544550990428;5077.238414237894;5093.312402825572;4846.39420072491;4885.2427873679635;4902.8723393620385;4914.926276105933;4915.26271059344 09:05:29 INFO - PROCESS | 12047 | |5;yui.html;584.769016238586;586.1969102329806;597.1818608506345;613.662485632902;671.8256239269452;12321.137520373577;13014.60956171314;13025.153365031278;14559.344829482672;15084;14296.213708246069;14501.564953051398;14515.854841451583;14518.201498917555;14742.63143421415;611.523012050599;744.6128013433012;754.9886751698729;775.3758224629171;778.6885245901628;762.3862790453683;768.757841279995;770.3952397368067;771.2480331676627;771.3597713897452;813.7965508622844;819.9262066414021;820.9220124088203;821.2116368286422;821.2690705272313;634.8984162534018;774.573984308628;808.4583329169412;808.5835794565833;808.5957021489255;585.3297973819995;591.104476717773;591.3052163707627;591.4440425999546;592.0438491835697;13696.65758356039;13746.731789254762;13769.655758606015;13783.793243101361;13793.724125517434;11577.305361678242;11625.767484650345;11644.18490705651;11822.349770762605;12543.623691289276;10235.744106397326;10307.690769276935;11243.83134252987;11347.148963827745;11745;3273.485763330978;3313.9171520711934;3347.129746265964;3455.3434847379167;3456.1014136324484;60783.49031135601;61598.69200654013;61992.380076199566;62395.688021560054;63036.36963630397;8745.650173992968;9116.316276279305;9354.20489258407;9461.52692365379;9630.32587718867;17928.462146135636;18005.909970450324;18067.54831129232;18077.638447231246;18225;6475.352464753581;6543.770968016097;6587.143671322793;6600.669966501656;6621.9006714899315;3380.036689543468;3559.94660080099;3611.4041183205004;3677.3196958562753;3709.629037096269;2761.9585706214425;2862.9427411451657;2881.8270903745843;2886.2928582497;2888.393437378132;497.5571741150379;500.7120905479364;502.3620002596755;503.9974800125976;509.320057722944;467.5815145444808;584.6287607369351;598.3956204233755;610.6488768957867;615.7906311853991;8213.79465513355;8236.423450358538;8311.750647480465;8539.274161696201;8613.181747733955;497.8232727381727;563.9097744360964;581.7120525339984;589.2272592004813;594.0851089322396;3843.461915331856;3926.960730392717;3959.3269144245546;4049.5545489996075;4052.63526282636;104345.47827260815;106661.46669266604;107499;112007;112355;67819.66090169517;72276.99999999895;78247;86228;88386;62043.06935395973;62642;64163.679181604726;69559.6522017407;70158;8174.632141553526;8540.35947303967;8610;8765.824683506422;8809.471431714117;31445.371092578476;33593.328133436706;41871.37192942047;42474;43279;57027.42972570221;62097.37902620916;62441;62487.6875615619;62918.05622915751;10900.236983411085;11061.668149955514;11297.096232301232;11359.829602555803;11525.135614829089;11687.415629218334;11726.472308746297;12038.75922481563;12355.505779768708;12449.69680609054;11501.82747258775;11598.94200528992;11638.476268567765;12476.625701228977;12690.555830545887;10923.83614245771;10952.890471095187;11683.532658693557;11786.646400607995;11836.349000805038;11393.943030284796;11464.770704585695;11643.476043578228;12353.073519486254;12408.069394795608;9625.181859541737;10378.896211038094;10404.68785936423;10607.734806629896;10895.673129806119;9954.701358959242;10130.645427410005;10223.488825558543;10286.168521462698;10406.739831504274 09:05:29 INFO - PROCESS | 12047 | __end_tp_report 09:05:29 INFO - PROCESS | 12047 | __start_cc_report 09:05:29 INFO - PROCESS | 12047 | _x_x_mozilla_cycle_collect,996 09:05:29 INFO - PROCESS | 12047 | __end_cc_report 09:05:29 INFO - PROCESS | 12047 | __startTimestamp1472227529722__endTimestamp 09:05:29 INFO - PROCESS | 12047 | 09:05:29 INFO - PROCESS | 12047 | ------- Summary: start ------- 09:05:29 INFO - PROCESS | 12047 | Number of tests: 6 09:05:29 INFO - PROCESS | 12047 | 09:05:29 INFO - PROCESS | 12047 | [#0] dojo.html Cycles:165 Average:4672.61 Median:4813.30 stddev:492.10 (10.2%) stddev-sans-first:465.49 09:05:29 INFO - PROCESS | 12047 | Values: 2582.3 2599.3 2607.3 2643.6 2656.2 4584.8 4640.9 4651.5 4736.6 4785.4 4677.2 4706.3 4749.9 4777.8 4778.0 4281.0 4284.0 4360.0 4403.2 4515.7 4426.3 4435.8 4449.1 4484.3 4494.1 4277.4 4322.0 4323.5 4363.0 4390.0 4253.6 4314.6 4329.4 4355.2 4392.7 4238.1 4319.5 4324.9 4326.2 4378.3 4639.0 4702.1 4708.7 4714.3 4761.4 4890.0 4998.7 5055.5 5062.1 5091.5 5066.8 5096.3 5096.9 5099.9 5112.9 4618.8 4737.1 4740.6 4758.7 4784.3 5120.9 5175.4 5190.8 5191.4 5275.1 4992.6 5002.3 5004.8 5005.5 5054.4 4860.9 5068.3 5077.4 5079.1 5086.7 4951.9 5003.4 5006.4 5075.8 5078.3 4991.2 4996.7 4998.9 5006.6 5068.0 4253.5 4253.9 4254.0 4255.0 4299.6 3629.3 3648.3 3653.4 3714.0 3750.4 4428.5 4432.2 4483.6 4488.5 4493.9 5061.6 5062.1 5066.1 5101.2 5106.9 4218.3 4333.1 4391.2 4406.6 4447.4 4564.8 4568.5 4594.9 4615.7 4619.2 4830.4 4837.0 4839.3 4842.8 4890.6 4888.4 4933.3 5004.1 5057.4 5110.5 4758.2 4800.7 4803.4 4830.5 4850.2 4974.4 4975.1 4980.0 4984.7 5044.9 4861.4 4977.3 5030.8 5051.2 5104.0 4871.8 4906.1 4907.4 4960.5 4966.1 4895.8 4907.2 4907.4 4910.2 4961.8 4688.5 4813.3 4836.3 4904.8 4924.4 4892.6 4898.7 4922.6 5001.6 5016.1 4801.5 4805.3 4808.1 4818.2 4880.3 09:05:29 INFO - PROCESS | 12047 | 09:05:29 INFO - PROCESS | 12047 | [#1] ext.html Cycles:180 Average:45236.69 Median:18166.72 stddev:81484.02 (448.5%) stddev-sans-first:81643.51 09:05:29 INFO - PROCESS | 12047 | Values: 546.1 558.5 569.9 575.9 592.0 38135.4 40427.2 40540.4 43855.3 43876.6 40018.0 40695.4 40752.0 40786.0 40794.4 7228.4 7454.6 7663.0 7668.3 7669.5 7167.5 7371.3 7375.2 9796.8 10088.7 9998.1 10045.1 10050.8 10054.8 10060.6 10066.1 10098.1 10112.5 10125.1 10126.8 9926.2 10008.0 10013.6 10015.1 10015.5 38390.0 38640.6 38771.2 38777.0 38837.0 3035.7 3127.0 3158.5 3173.0 3291.4 28304.4 28406.4 28496.0 29792.3 30505.5 3031.7 3055.5 3098.8 3134.2 3169.8 248123.0 248422.0 248744.0 249027.0 249107.0 31352.7 51264.0 51642.0 51729.2 51771.2 31817.8 33225.0 33343.3 33358.2 33368.5 13019.1 13138.7 13146.5 13153.4 13159.8 10870.0 10892.8 10918.9 10918.9 10920.6 7208.7 7569.5 7821.9 7868.3 7951.4 3070.6 3094.5 3094.9 3109.1 3133.6 787.5 797.5 905.8 927.7 986.3 27273.5 27401.5 27676.4 27689.6 27949.7 958.8 961.7 963.7 1000.5 1023.9 13989.4 14622.2 14766.6 15196.5 15196.8 356724.0 359537.0 359879.0 360365.0 361806.0 49563.3 50188.0 50252.0 50252.2 50339.2 1064.5 1145.1 1188.5 1197.8 1202.3 27467.6 28872.0 28895.6 29731.6 29774.3 41343.0 41958.4 41960.0 41962.0 42008.4 308966.0 311225.0 311429.8 311591.0 312082.0 24557.4 25199.1 25725.6 25741.0 26581.7 39158.6 39361.8 39414.6 39840.0 40619.0 33963.8 33985.5 34263.7 34389.7 34577.7 33341.8 33716.8 33847.0 34479.1 35071.6 33739.5 33932.8 34177.7 34267.8 36025.3 27944.7 28209.9 32692.4 33042.0 33197.0 25675.9 25699.2 25736.5 25785.5 26120.3 09:05:29 INFO - PROCESS | 12047 | 09:05:29 INFO - PROCESS | 12047 | [#2] jquery.html Cycles:180 Average:24366.62 Median:219403.40 stddev:64842.85 (29.6%) stddev-sans-first:64999.64 09:05:29 INFO - PROCESS | 12047 | Values: 333.9 340.4 345.7 348.3 363.5 12447.1 15735.4 16194.8 16344.0 17292.8 16406.7 16490.6 17071.8 17266.0 17438.8 4985.6 5058.5 5073.1 5088.0 5128.4 3721.2 3920.5 5010.5 5091.7 5095.0 3897.7 3904.4 4597.0 6003.8 6007.0 6090.2 6096.2 6097.0 6100.9 6102.0 5112.7 5197.4 5244.4 5245.3 5247.2 20268.4 20306.5 20522.8 20528.5 20622.0 1781.8 1832.3 1969.1 1997.9 2075.5 12922.2 12951.5 13054.2 13366.9 13823.4 1726.8 1735.1 1758.7 1767.4 1788.8 67199.0 67392.7 68675.0 69982.0 70041.9 9176.8 9206.0 9274.3 9448.3 9680.1 12831.4 12837.1 12868.2 12871.3 12878.5 7118.2 7304.8 7325.4 7461.1 7492.9 7075.3 7137.6 7272.6 7306.4 7309.7 5895.1 6045.8 6048.2 6053.4 6210.2 1693.9 1698.4 1709.6 1718.5 1771.6 812.9 814.3 824.5 922.6 946.6 16437.3 16986.9 17298.7 17734.1 17895.6 825.5 871.4 883.5 912.7 939.6 8695.0 10008.2 10215.2 10245.6 10257.2 376371.0 385232.0 399259.0 403931.0 405066.0 41944.2 63758.4 68684.0 69834.7 70692.0 883.7 905.4 929.0 941.1 945.3 15733.8 15894.4 16035.4 16236.7 16352.2 39212.6 39547.8 39995.2 40001.8 41206.0 54698.2 55440.7 55919.4 56554.0 57094.7 6799.8 6977.9 7060.4 7639.9 7769.9 11228.0 11692.6 12193.0 12213.3 12384.4 10444.6 10504.2 10506.2 10772.2 10892.9 10409.9 10644.3 10691.6 10950.8 11052.0 10674.8 10760.4 10866.5 11032.5 11053.5 7121.8 7199.1 7217.6 7440.5 7450.1 9232.4 10577.5 10678.9 10800.8 10883.3 09:05:29 INFO - PROCESS | 12047 | 09:05:29 INFO - PROCESS | 12047 | [#3] mootools.html Cycles:180 Average:5105.22 Median:5245.80 stddev:2919.54 (55.7%) stddev-sans-first:2913.29 09:05:29 INFO - PROCESS | 12047 | Values: 1241.3 1372.0 1384.5 1467.3 1683.7 7258.9 7673.0 7682.5 7915.6 7920.5 7830.4 7927.0 7934.7 7951.2 8006.5 1840.3 2151.3 2176.0 2184.6 2192.9 1896.0 1907.2 1912.8 2096.3 2186.1 2165.7 2173.6 2175.2 2175.7 2178.3 2094.4 2111.7 2137.3 2159.9 2162.1 1998.7 2086.4 2107.1 2108.6 2118.1 7984.3 8014.3 8023.6 8033.9 8046.7 4568.8 4668.4 4699.2 4716.8 4720.5 2786.0 2856.6 2911.6 2922.4 2942.9 915.3 945.4 949.3 958.1 961.9 10876.6 10940.6 10987.9 10998.3 11022.3 3829.6 3949.2 4051.4 4057.3 4121.2 8360.4 8363.0 8376.9 8379.7 8382.5 4602.0 4604.7 4609.3 4612.4 4617.6 4138.3 4319.5 4319.8 4320.6 4321.7 3229.6 3263.8 3333.3 3341.1 3355.1 979.6 995.5 998.4 1003.4 1009.8 707.0 813.9 822.5 824.4 845.8 5148.3 5179.7 5241.3 5250.3 5305.0 406.1 407.9 409.6 424.0 427.4 3504.7 3564.9 3635.2 3661.9 3729.6 9144.2 9666.1 9680.6 9688.2 9689.2 9060.9 9390.2 9460.1 9555.7 9795.3 7120.3 7299.3 7305.1 7311.7 7315.7 5208.3 5330.1 5376.0 5438.4 5546.7 8524.8 8785.6 8813.5 8924.9 8940.6 8853.8 9006.9 9028.9 9065.3 9071.3 6159.7 6697.5 6854.6 6878.4 6902.9 7682.5 7743.5 7749.8 7752.4 7754.5 6723.1 6823.3 6904.3 6909.7 7090.6 6653.4 6724.7 6745.1 6787.2 6813.6 6425.5 6481.6 6489.4 6560.9 6599.5 4493.3 4517.6 4522.9 4611.9 4743.7 4334.9 4478.2 4492.7 4540.3 4546.0 09:05:29 INFO - PROCESS | 12047 | 09:05:29 INFO - PROCESS | 12047 | [#4] prototype.html Cycles:180 Average:4196.10 Median:4853.28 stddev:1230.52 (25.4%) stddev-sans-first:1198.42 09:05:29 INFO - PROCESS | 12047 | Values: 283.6 288.5 293.2 299.3 299.7 4860.4 4865.4 4915.0 4916.8 4924.3 4798.2 4807.8 4843.6 4860.2 4874.7 3595.0 3645.4 3774.9 3800.4 3807.6 3808.8 3809.4 3821.4 3839.6 3852.6 3778.7 3782.1 3794.2 3797.0 3800.5 3602.9 3672.7 3767.7 3780.2 3784.8 3318.3 3333.4 3334.8 3337.3 3337.7 4677.3 4690.7 4699.9 4702.6 4733.6 4139.5 4303.4 4307.6 4310.9 4312.6 4990.2 4991.8 4997.1 5017.6 5054.7 3625.3 3645.3 3658.4 3663.3 3666.6 5046.3 5266.2 5288.9 5309.2 5319.4 3600.5 3610.0 3612.2 3614.8 3619.6 3662.7 3673.4 3674.8 3678.7 3678.9 4076.0 4139.3 4215.2 4235.3 4240.4 4978.6 4980.6 5012.3 5026.3 5026.6 1243.9 1251.5 1264.1 1264.6 1268.9 766.9 790.7 791.2 817.9 821.8 4356.9 4450.8 4510.5 4510.8 4516.2 5158.8 5162.7 5167.2 5182.6 5184.1 4405.7 4476.6 4487.2 4502.7 4503.8 2564.5 2728.5 2761.5 2768.5 2770.9 4948.5 4951.9 4952.7 4963.0 4984.8 5220.3 5265.7 5334.3 5355.7 5358.7 4900.7 4906.8 4911.4 4920.9 4931.9 5176.2 5209.4 5218.8 5221.5 5222.6 4876.8 5117.2 5127.6 5142.7 5166.7 4968.9 4972.6 4972.8 4975.9 4982.9 3646.1 3662.0 3681.9 3686.5 3695.1 5081.8 5241.4 5286.5 5315.6 5319.6 5053.6 5054.4 5064.8 5124.3 5127.6 5048.8 5059.4 5059.9 5127.8 5133.7 4753.6 4948.1 5006.9 5018.7 5038.2 4831.9 4938.9 5060.5 5077.2 5093.3 4846.4 4885.2 4902.9 4914.9 4915.3 09:05:29 INFO - PROCESS | 12047 | 09:05:29 INFO - PROCESS | 12047 | [#5] yui.html Cycles:180 Average:17476.57 Median:42172.69 stddev:25287.36 (60.0%) stddev-sans-first:25326.49 09:05:29 INFO - PROCESS | 12047 | Values: 584.8 586.2 597.2 613.7 671.8 12321.1 13014.6 13025.2 14559.3 15084.0 14296.2 14501.6 14515.9 14518.2 14742.6 611.5 744.6 755.0 775.4 778.7 762.4 768.8 770.4 771.2 771.4 813.8 819.9 820.9 821.2 821.3 634.9 774.6 808.5 808.6 808.6 585.3 591.1 591.3 591.4 592.0 13696.7 13746.7 13769.7 13783.8 13793.7 11577.3 11625.8 11644.2 11822.3 12543.6 10235.7 10307.7 11243.8 11347.1 11745.0 3273.5 3313.9 3347.1 3455.3 3456.1 60783.5 61598.7 61992.4 62395.7 63036.4 8745.7 9116.3 9354.2 9461.5 9630.3 17928.5 18005.9 18067.5 18077.6 18225.0 6475.4 6543.8 6587.1 6600.7 6621.9 3380.0 3559.9 3611.4 3677.3 3709.6 2762.0 2862.9 2881.8 2886.3 2888.4 497.6 500.7 502.4 504.0 509.3 467.6 584.6 598.4 610.6 615.8 8213.8 8236.4 8311.8 8539.3 8613.2 497.8 563.9 581.7 589.2 594.1 3843.5 3927.0 3959.3 4049.6 4052.6 104345.5 106661.5 107499.0 112007.0 112355.0 67819.7 72277.0 78247.0 86228.0 88386.0 62043.1 62642.0 64163.7 69559.7 70158.0 8174.6 8540.4 8610.0 8765.8 8809.5 31445.4 33593.3 41871.4 42474.0 43279.0 57027.4 62097.4 62441.0 62487.7 62918.1 10900.2 11061.7 11297.1 11359.8 11525.1 11687.4 11726.5 12038.8 12355.5 12449.7 11501.8 11598.9 11638.5 12476.6 12690.6 10923.8 10952.9 11683.5 11786.6 11836.3 11393.9 11464.8 11643.5 12353.1 12408.1 9625.2 10378.9 10404.7 10607.7 10895.7 9954.7 10130.6 10223.5 10286.2 10406.7 09:05:29 INFO - PROCESS | 12047 | -------- Summary: end -------- 09:05:29 INFO - PROCESS | 12047 | 09:05:30 INFO - TEST-INFO | 12047: exit 0 09:05:30 INFO - TEST-OK | dromaeo_css | took 1122634ms 09:05:30 INFO - TEST-START | kraken 09:05:30 INFO - operating with platform_type : linux_ 09:05:30 INFO - Initialising browser for kraken test... 09:05:30 INFO - TEST-INFO | started process 12353 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp257LWy/profile http://localhost:54452/getInfo.html) 09:05:36 INFO - PROCESS | 12353 | __metrics Screen width/height:1600/1200 09:05:36 INFO - PROCESS | 12353 | colorDepth:24 09:05:36 INFO - PROCESS | 12353 | Browser inner width/height: 1024/697 09:05:36 INFO - PROCESS | 12353 | __metrics 09:05:36 INFO - PROCESS | 12353 | 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" 09:05:36 INFO - PROCESS | 12353 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:05:37 INFO - PROCESS | 12353 | console.error: PushService: 09:05:37 INFO - PROCESS | 12353 | stateChangeProcessEnqueue: Error transitioning state 09:05:37 INFO - PROCESS | 12353 | UnknownError 09:05:38 INFO - TEST-INFO | 12353: exit 0 09:05:38 INFO - Browser initialized. 09:05:38 INFO - Running cycle 1/1 for kraken test... 09:05:38 INFO - TEST-INFO | started process 12433 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp257LWy/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:05:50 INFO - PROCESS | 12433 | RSS: Main: 180195328 09:05:50 INFO - PROCESS | 12433 | 09:05:53 INFO - PROCESS | 12433 | Cycle 1(1): loaded http://localhost:54452/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54452/tests/kraken/driver.html?testName=audio-beat-detection) 09:05:54 INFO - PROCESS | 12433 | RSS: Main: 234029056 09:05:54 INFO - PROCESS | 12433 | 09:05:57 INFO - PROCESS | 12433 | Cycle 1(1): loaded http://localhost:54452/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54452/tests/kraken/driver.html?testName=audio-dft) 09:05:57 INFO - PROCESS | 12433 | RSS: Main: 224362496 09:05:57 INFO - PROCESS | 12433 | 09:06:00 INFO - PROCESS | 12433 | Cycle 1(1): loaded http://localhost:54452/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54452/tests/kraken/driver.html?testName=audio-fft) 09:06:01 INFO - PROCESS | 12433 | RSS: Main: 303382528 09:06:01 INFO - PROCESS | 12433 | 09:06:03 INFO - PROCESS | 12433 | Cycle 1(1): loaded http://localhost:54452/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54452/tests/kraken/driver.html?testName=audio-oscillator) 09:06:04 INFO - PROCESS | 12433 | RSS: Main: 212213760 09:06:04 INFO - PROCESS | 12433 | 09:06:06 INFO - PROCESS | 12433 | Cycle 1(1): loaded http://localhost:54452/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54452/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:06:07 INFO - PROCESS | 12433 | RSS: Main: 214429696 09:06:07 INFO - PROCESS | 12433 | 09:06:10 INFO - PROCESS | 12433 | Cycle 1(1): loaded http://localhost:54452/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54452/tests/kraken/driver.html?testName=imaging-darkroom) 09:06:10 INFO - PROCESS | 12433 | RSS: Main: 252760064 09:06:10 INFO - PROCESS | 12433 | 09:06:14 INFO - PROCESS | 12433 | Cycle 1(1): loaded http://localhost:54452/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54452/tests/kraken/driver.html?testName=imaging-desaturate) 09:06:14 INFO - PROCESS | 12433 | RSS: Main: 246874112 09:06:14 INFO - PROCESS | 12433 | 09:06:17 INFO - PROCESS | 12433 | Cycle 1(1): loaded http://localhost:54452/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54452/tests/kraken/driver.html?testName=json-parse-financial) 09:06:18 INFO - PROCESS | 12433 | RSS: Main: 250048512 09:06:18 INFO - PROCESS | 12433 | 09:06:20 INFO - PROCESS | 12433 | Cycle 1(1): loaded http://localhost:54452/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54452/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:06:20 INFO - PROCESS | 12433 | RSS: Main: 213762048 09:06:20 INFO - PROCESS | 12433 | 09:06:22 INFO - PROCESS | 12433 | Cycle 1(1): loaded http://localhost:54452/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54452/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:06:23 INFO - PROCESS | 12433 | RSS: Main: 221016064 09:06:23 INFO - PROCESS | 12433 | 09:06:25 INFO - PROCESS | 12433 | Cycle 1(1): loaded http://localhost:54452/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54452/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:06:26 INFO - PROCESS | 12433 | RSS: Main: 221753344 09:06:26 INFO - PROCESS | 12433 | 09:06:28 INFO - PROCESS | 12433 | Cycle 1(1): loaded http://localhost:54452/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54452/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:06:29 INFO - PROCESS | 12433 | RSS: Main: 223092736 09:06:29 INFO - PROCESS | 12433 | 09:06:32 INFO - PROCESS | 12433 | Cycle 1(1): loaded http://localhost:54452/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54452/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:06:33 INFO - PROCESS | 12433 | RSS: Main: 220811264 09:06:33 INFO - PROCESS | 12433 | 09:06:34 INFO - PROCESS | 12433 | Cycle 1(1): loaded http://localhost:54452/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54452/tests/kraken/driver.html?testName=ai-astar) 09:06:35 INFO - PROCESS | 12433 | RSS: Main: 271421440 09:06:35 INFO - PROCESS | 12433 | 09:06:35 INFO - PROCESS | 12433 | __start_tp_report 09:06:35 INFO - PROCESS | 12433 | _x_x_mozilla_page_load 09:06:35 INFO - PROCESS | 12433 | _x_x_mozilla_page_load_details 09:06:35 INFO - PROCESS | 12433 | |i|pagename|runs| 09:06:35 INFO - PROCESS | 12433 | |0;ai-astar;118;117;133;116;112;114;115;113;113;114 09:06:35 INFO - PROCESS | 12433 | |1;audio-beat-detection;154;124;156;187;128;153;148;133;184;128 09:06:35 INFO - PROCESS | 12433 | |2;audio-dft;163;163;163;162;164;162;163;162;162;160 09:06:35 INFO - PROCESS | 12433 | |3;audio-fft;75;74;77;183;77;79;76;76;154;77 09:06:35 INFO - PROCESS | 12433 | |4;audio-oscillator;85;85;144;85;83;84;146;83;83;134 09:06:35 INFO - PROCESS | 12433 | |5;imaging-gaussian-blur;117;117;117;117;117;117;117;117;116;117 09:06:35 INFO - PROCESS | 12433 | |6;imaging-darkroom;147;148;147;148;147;148;147;148;148;147 09:06:35 INFO - PROCESS | 12433 | |7;imaging-desaturate;96;95;95;95;95;96;96;95;95;95 09:06:35 INFO - PROCESS | 12433 | |8;json-parse-financial;64;64;65;65;64;64;64;64;65;64 09:06:35 INFO - PROCESS | 12433 | |9;json-stringify-tinderbox;65;66;66;66;65;65;67;65;66;65 09:06:35 INFO - PROCESS | 12433 | |10;stanford-crypto-aes;83;82;82;83;84;81;82;82;81;83 09:06:35 INFO - PROCESS | 12433 | |11;stanford-crypto-ccm;138;134;128;133;131;129;130;130;182;140 09:06:35 INFO - PROCESS | 12433 | |12;stanford-crypto-pbkdf2;183;176;179;173;172;175;174;176;174;176 09:06:35 INFO - PROCESS | 12433 | |13;stanford-crypto-sha256-iterative;72;70;68;68;70;68;68;68;68;69 09:06:35 INFO - PROCESS | 12433 | __end_tp_report 09:06:35 INFO - PROCESS | 12433 | __start_cc_report 09:06:35 INFO - PROCESS | 12433 | _x_x_mozilla_cycle_collect,1990 09:06:35 INFO - PROCESS | 12433 | __end_cc_report 09:06:35 INFO - PROCESS | 12433 | __startTimestamp1472227595228__endTimestamp 09:06:35 INFO - PROCESS | 12433 | 09:06:35 INFO - PROCESS | 12433 | ------- Summary: start ------- 09:06:35 INFO - PROCESS | 12433 | Number of tests: 14 09:06:35 INFO - PROCESS | 12433 | 09:06:35 INFO - PROCESS | 12433 | [#0] ai-astar Cycles:10 Average:116.50 Median:115.50 stddev:6.10 (5.3%) stddev-sans-first:6.44 09:06:35 INFO - PROCESS | 12433 | Values: 118.0 117.0 133.0 116.0 112.0 114.0 115.0 113.0 113.0 114.0 09:06:35 INFO - PROCESS | 12433 | 09:06:35 INFO - PROCESS | 12433 | [#1] audio-beat-detection Cycles:10 Average:149.50 Median:153.50 stddev:22.41 (14.6%) stddev-sans-first:23.71 09:06:35 INFO - PROCESS | 12433 | Values: 154.0 124.0 156.0 187.0 128.0 153.0 148.0 133.0 184.0 128.0 09:06:35 INFO - PROCESS | 12433 | 09:06:35 INFO - PROCESS | 12433 | [#2] audio-dft Cycles:10 Average:162.40 Median:163.00 stddev:1.07 (0.7%) stddev-sans-first:1.12 09:06:35 INFO - PROCESS | 12433 | Values: 163.0 163.0 163.0 162.0 164.0 162.0 163.0 162.0 162.0 160.0 09:06:35 INFO - PROCESS | 12433 | 09:06:35 INFO - PROCESS | 12433 | [#3] audio-fft Cycles:10 Average:94.80 Median:76.50 stddev:39.46 (51.6%) stddev-sans-first:41.20 09:06:35 INFO - PROCESS | 12433 | Values: 75.0 74.0 77.0 183.0 77.0 79.0 76.0 76.0 154.0 77.0 09:06:35 INFO - PROCESS | 12433 | 09:06:35 INFO - PROCESS | 12433 | [#4] audio-oscillator Cycles:10 Average:101.20 Median:83.50 stddev:27.87 (33.4%) stddev-sans-first:28.94 09:06:35 INFO - PROCESS | 12433 | Values: 85.0 85.0 144.0 85.0 83.0 84.0 146.0 83.0 83.0 134.0 09:06:35 INFO - PROCESS | 12433 | 09:06:35 INFO - PROCESS | 12433 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 09:06:35 INFO - PROCESS | 12433 | Values: 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 116.0 117.0 09:06:35 INFO - PROCESS | 12433 | 09:06:35 INFO - PROCESS | 12433 | [#6] imaging-darkroom Cycles:10 Average:147.50 Median:148.00 stddev:0.53 (0.4%) stddev-sans-first:0.53 09:06:35 INFO - PROCESS | 12433 | Values: 147.0 148.0 147.0 148.0 147.0 148.0 147.0 148.0 148.0 147.0 09:06:35 INFO - PROCESS | 12433 | 09:06:35 INFO - PROCESS | 12433 | [#7] imaging-desaturate Cycles:10 Average:95.30 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.44 09:06:35 INFO - PROCESS | 12433 | Values: 96.0 95.0 95.0 95.0 95.0 96.0 96.0 95.0 95.0 95.0 09:06:35 INFO - PROCESS | 12433 | 09:06:35 INFO - PROCESS | 12433 | [#8] json-parse-financial Cycles:10 Average:64.30 Median:64.00 stddev:0.48 (0.8%) stddev-sans-first:0.50 09:06:35 INFO - PROCESS | 12433 | Values: 64.0 64.0 65.0 65.0 64.0 64.0 64.0 64.0 65.0 64.0 09:06:35 INFO - PROCESS | 12433 | 09:06:35 INFO - PROCESS | 12433 | [#9] json-stringify-tinderbox Cycles:10 Average:65.60 Median:66.00 stddev:0.70 (1.1%) stddev-sans-first:0.71 09:06:35 INFO - PROCESS | 12433 | Values: 65.0 66.0 66.0 66.0 65.0 65.0 67.0 65.0 66.0 65.0 09:06:35 INFO - PROCESS | 12433 | 09:06:35 INFO - PROCESS | 12433 | [#10] stanford-crypto-aes Cycles:10 Average:82.30 Median:82.50 stddev:0.95 (1.1%) stddev-sans-first:0.97 09:06:35 INFO - PROCESS | 12433 | Values: 83.0 82.0 82.0 83.0 84.0 81.0 82.0 82.0 81.0 83.0 09:06:35 INFO - PROCESS | 12433 | 09:06:35 INFO - PROCESS | 12433 | [#11] stanford-crypto-ccm Cycles:10 Average:137.50 Median:133.50 stddev:16.11 (12.1%) stddev-sans-first:17.09 09:06:35 INFO - PROCESS | 12433 | Values: 138.0 134.0 128.0 133.0 131.0 129.0 130.0 130.0 182.0 140.0 09:06:35 INFO - PROCESS | 12433 | 09:06:35 INFO - PROCESS | 12433 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.80 Median:176.00 stddev:3.19 (1.8%) stddev-sans-first:2.06 09:06:35 INFO - PROCESS | 12433 | Values: 183.0 176.0 179.0 173.0 172.0 175.0 174.0 176.0 174.0 176.0 09:06:35 INFO - PROCESS | 12433 | 09:06:35 INFO - PROCESS | 12433 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.90 Median:68.50 stddev:1.37 (2.0%) stddev-sans-first:0.88 09:06:35 INFO - PROCESS | 12433 | Values: 72.0 70.0 68.0 68.0 70.0 68.0 68.0 68.0 68.0 69.0 09:06:35 INFO - PROCESS | 12433 | -------- Summary: end -------- 09:06:35 INFO - PROCESS | 12433 | 09:06:36 INFO - TEST-INFO | 12433: exit 0 09:06:36 INFO - TEST-OK | kraken | took 65747ms 09:06:36 INFO - SUITE-END | took 1188s 09:06:36 INFO - Completed test suite (00:19:48) 09:06:36 INFO - javascript benchmark 09:06:36 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2582.31565163044, 2599.285196570943, 2607.2569317744424, 2643.643108180394, 2656.150031989764, 4584.770761461922, 4640.930386044203, 4651.4883362830105, 4736.62107031438, 4785.425748910131, 4677.181493238678, 4706.270528068155, 4749.857504274869, 4777.761111944406, 4777.9761101194445, 4280.957190428087, 4283.957160428403, 4359.956400436003, 4403.185410699015, 4515.706479078849, 4426.29179331307, 4435.778211089449, 4449.132419178261, 4484.304932735431, 4494.146112238681, 4277.379779931893, 4322.0491491871735, 4323.524412314643, 4362.956370436286, 4389.978050109761, 4253.638440732541, 4314.611684948339, 4329.415528903621, 4355.216061108999, 4392.692511524195, 4238.08881090564, 4319.50325712544, 4324.935125973113, 4326.199653064162, 4378.277584198603, 4638.9536104638855, 4702.059588082398, 4708.7174769513595, 4714.316424118518, 4761.381020467352, 4889.9511004889855, 4998.650094493387, 5055.469175736532, 5062.139436295838, 5091.46539613339, 5066.82266120684, 5096.261042148868, 5096.923546146811, 5099.949000509984, 5112.923306150411, 4618.792154353062, 4737.123632128068, 4740.597049250783, 4758.738269395148, 4784.282357646381, 5120.948790512047, 5175.430702622709, 5190.766415511311, 5191.402988656278, 5275.076861549213, 4992.575631071327, 5002.299678045077, 5004.824831130893, 5005.499450054966, 5054.368203974504, 4860.902781944341, 5068.341115654941, 5077.39071311445, 5079.111155547765, 5086.694798312114, 4951.876203094951, 5003.374578177728, 5006.424261210008, 5075.796968121308, 5078.263651770547, 4991.151504244287, 4996.725180115129, 4998.90002199954, 5006.5994720423005, 5068.0, 4253.532111467736, 4253.914921701548, 4253.97873010633, 4255.021345090647, 4299.634531064832, 3629.256002519477, 3648.3068217038676, 3653.3789255826573, 3714.0343510686876, 3750.362438385481, 4428.490723566767, 4432.246518091868, 4483.574060464251, 4488.461384633865, 4493.887652808706, 5061.620378471629, 5062.088824011639, 5066.138756411346, 5101.158308879068, 5106.948930510722, 4218.261804184255, 4333.133373325348, 4391.165678521071, 4406.5593440655675, 4447.3773671685985, 4564.817407303737, 4568.497465278817, 4594.908101837945, 4615.723056616614, 4619.168549661026, 4830.396200474941, 4836.951630483791, 4839.322494850794, 4842.782074806642, 4890.608751299958, 4888.364512613338, 4933.333999909966, 5004.099262132851, 5057.418396884405, 5110.463401342806, 4758.2386818108935, 4800.711957282574, 4803.447603525639, 4830.468648448597, 4850.248211527219, 4974.427940786856, 4975.104481193275, 4979.950200497949, 4984.700917944935, 5044.924326135184, 4861.392325959255, 4977.303177555217, 5030.8239211627415, 5051.191809310492, 5103.974480127576, 4871.780769865435, 4906.092372911023, 4907.411110666742, 4960.528749768766, 4966.106100901728, 4895.804167833389, 4907.2393778964315, 4907.386576677915, 4910.189818679878, 4961.82633607822, 4688.460827004938, 4813.302071199586, 4836.322914791862, 4904.828331008398, 4924.433690125538, 4892.633052521146, 4898.730569818694, 4922.630802689884, 5001.64988450805, 5016.122178618654, 4801.51984801531, 4805.255185446262, 4808.06242782654, 4818.229083346648, 4880.292357608198], "value": 4640.774761044886, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [546.1234718277165, 558.4582954534103, 569.8917205730912, 575.9481646651803, 592.0349829777462, 38135.42796858046, 40427.19145617086, 40540.391894121574, 43855.34216986748, 43876.56123438765, 40018.0, 40695.38956915641, 40752.0, 40786.0, 40794.38808417876, 7228.421726261887, 7454.552726836408, 7663.0, 7668.271514206142, 7669.539827610333, 7167.534110282841, 7371.336579707825, 7375.188729239806, 9796.804063918717, 10088.697339079803, 9998.10017098461, 10045.095941365276, 10050.798984020352, 10054.849177262347, 10060.647877324294, 10066.144377727902, 10098.091171794575, 10112.494375281207, 10125.088742013255, 10126.848097278547, 9926.2059035277, 10008.0, 10013.649522266685, 10015.148712359458, 10015.549300281506, 38390.0, 38640.613593863985, 38771.224575508335, 38777.0, 38837.0, 3035.741961933238, 3127.0, 3158.478850989584, 3173.0, 3291.440455122635, 28304.43391132166, 28406.431871362456, 28496.0, 29792.255193620116, 30505.542416863766, 3031.651360093573, 3055.5263934090253, 3098.8295643739584, 3134.2477805326785, 3169.793963392372, 248123.0, 248422.0, 248744.0, 249027.0, 249107.0, 31352.686473135433, 51264.0, 51642.0, 51729.22406163911, 51771.223431648556, 31817.840910795298, 33225.0, 33343.3331333372, 33358.16604584905, 33368.49947250793, 13019.088663793444, 13138.671533211746, 13146.539871104656, 13153.408096635674, 13159.802602960963, 10870.0, 10892.836607450894, 10918.890811091946, 10918.945405273082, 10920.563177472812, 7208.747693830691, 7569.47013709035, 7821.92178078212, 7868.291853733192, 7951.443398962017, 3070.646875609289, 3094.535819627029, 3094.90715278542, 3109.0517392195597, 3133.5613014178034, 787.5274835098895, 797.4577287444594, 905.7826121730869, 927.7402327348353, 986.2849434160177, 27273.45453090967, 27401.45197096047, 27676.446471070467, 27689.600527896866, 27949.720502795117, 958.7794807194244, 961.7066794627706, 963.6819849449665, 1000.5347513406156, 1023.9129673977795, 13989.440422383193, 14622.195779232032, 14766.557003289918, 15196.468123615621, 15196.848031519543, 356724.0, 359537.0, 359879.0, 360365.0, 361806.0, 49563.25655115176, 50188.0, 50252.0, 50252.24621630678, 50339.24491132636, 1064.4730858225228, 1145.1297014269048, 1188.477070089158, 1197.7724232395817, 1202.2546021466571, 27467.587986179824, 28872.0, 28895.56656650194, 29731.554026689184, 29774.25564360908, 41343.0, 41958.37062444127, 41960.0, 41962.0, 42008.36987445252, 308966.0, 311225.0, 311429.8426728157, 311591.0, 312082.0, 24557.38606534851, 25199.11803086883, 25725.614115787903, 25741.0, 26581.734182657925, 39158.6084139155, 39361.80319098386, 39414.60585394224, 39840.00399990023, 40619.0, 33963.830180848934, 33985.490217646264, 34263.65736342605, 34389.65610343964, 34577.65422345845, 33341.83329083339, 33716.83141584276, 33847.0, 34479.13802154866, 35071.649283506835, 33739.49390759091, 33932.830335848164, 34177.65822341745, 34267.82866085654, 36025.27949441049, 27944.720552794213, 28209.858950705115, 32692.403536576516, 33042.0, 33197.0, 25675.871620641778, 25699.229023129337, 25736.485270294863, 25785.484290314464, 26120.34699132537], "value": 16585.674192584407, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [333.9115134489359, 340.43657746429653, 345.7406944791407, 348.2894894415392, 363.4802232807086, 12447.128700990934, 15735.449259275927, 16194.838051619465, 16344.0, 17292.82707172931, 16406.67186656266, 16490.587735306624, 17071.829281707178, 17266.0, 17438.82561174388, 4985.576226020784, 5058.468860769622, 5073.137566613685, 5087.982403519292, 5128.435872054071, 3721.162738383869, 3920.470736450571, 5010.473900240477, 5091.66904151229, 5095.031943930641, 3897.7271590988644, 3904.3752999520084, 4596.954030459686, 6003.759849606011, 6006.96996515019, 6090.208272924514, 6096.1770161028135, 6097.0, 6100.938990610081, 6101.969490152521, 5112.744362781846, 5197.428282888879, 5244.449332820037, 5245.26566280721, 5247.212918062283, 20268.391948241428, 20306.49233769153, 20522.79477205224, 20528.486787830276, 20622.0, 1781.7861856577294, 1832.3311991123278, 1969.1237399357292, 1997.9300724474574, 2075.450005748478, 12922.160059596095, 12951.48194072236, 13054.216746995211, 13366.93316533411, 13823.447062117502, 1726.8186840381704, 1735.0977491704368, 1758.7041962761564, 1767.4344209852852, 1788.7764029496313, 67199.0, 67392.66303668499, 68675.0, 69982.0, 70041.94937075948, 9176.770580735534, 9206.0, 9274.258059355236, 9448.291378146667, 9680.128788409076, 12831.358432078545, 12837.101402901893, 12868.227906325463, 12871.292078935561, 12878.484860605471, 7118.216996130422, 7304.780856574312, 7325.377342925911, 7461.067366579177, 7492.887606685904, 7075.257098004636, 7137.643117844191, 7272.600006999666, 7306.378957788541, 7309.707611695579, 5895.145203945404, 6045.818625441156, 6048.213732214784, 6053.364396738367, 6210.223722034746, 1693.8814283000072, 1698.388580111159, 1709.632429027765, 1718.518814731877, 1771.6191018930992, 812.8943237379103, 814.2630919018297, 824.5011767880466, 922.6401703335705, 946.6213514594217, 16437.342506299854, 16986.915065424593, 17298.65402691939, 17734.11329433549, 17895.642087158183, 825.4551995682821, 871.3551971541141, 883.4699180491782, 912.6531917871299, 939.5677988125402, 8695.0, 10008.249381296286, 10215.23385746072, 10245.59017639301, 10257.17942564593, 376371.0, 385232.0, 399259.0, 403931.0, 405066.0, 41944.1611167781, 63758.36241637524, 68684.0, 69834.65082674554, 70692.0, 883.7172104926491, 905.3843386497246, 928.9628414863465, 941.0918463682442, 945.2910317262053, 15733.763993539877, 15894.364225431316, 16035.358585656442, 16236.675266494367, 16352.182390880645, 39212.6078739209, 39547.80226098851, 39995.20009599734, 40001.79999099986, 41206.0, 54698.17952730792, 55440.72279638576, 55919.440805591425, 56554.0, 57094.7145264271, 6799.830004249933, 6977.895331569929, 7060.435165186876, 7639.885401718868, 7769.922300777146, 11228.045616122721, 11692.620885453583, 12193.0, 12213.267203967791, 12384.380780960735, 10444.63443779464, 10504.159667226757, 10506.21203409732, 10772.24594278393, 10892.891071089187, 10409.895901040893, 10644.308119972176, 10691.57233710674, 10950.780984380108, 11052.0, 10674.839877402, 10760.3543787373, 10866.456677165952, 11032.503537340679, 11053.502592383202, 7121.750738724119, 7199.1001124859395, 7217.6391180441815, 7440.511897620389, 7450.143233528213, 9232.446053236827, 10577.471126443494, 10678.89321106779, 10800.837987430037, 10883.346999179757], "value": 8297.937451310123, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1241.3296819717352, 1372.0, 1384.5015794314045, 1467.2956980649287, 1683.7053515634752, 7258.927410725898, 7673.0, 7682.539047657145, 7915.6042197890165, 7920.524768513893, 7830.412719046066, 7927.048754149496, 7934.682612695486, 7951.244631759988, 8006.519608823474, 1840.3098837935775, 2151.257816053459, 2176.042541281839, 2184.6176919039185, 2192.8793916334594, 1896.0, 1907.1799126375654, 1912.8182822631827, 2096.308218287969, 2186.081845624841, 2165.664322030088, 2173.608750424923, 2175.2386664667397, 2175.7389113306426, 2178.346496051186, 2094.4135642020333, 2111.6938043983614, 2137.2519618133683, 2159.91360345586, 2162.0811155258953, 1998.7301714268533, 2086.405374468284, 2107.072887929306, 2108.630989576833, 2118.0998075817715, 7984.28141467265, 8014.318782903402, 8023.638936247883, 8033.919660803375, 8046.718364857202, 4568.794404251784, 4668.439787225555, 4699.201135806921, 4716.787744551503, 4720.480747117815, 2785.9721402785917, 2856.600075989363, 2911.6360454943133, 2922.3862988772385, 2942.911712648624, 915.3180880244195, 945.4280160502934, 949.2975198353168, 958.1233171647934, 961.9134277914951, 10876.564937402494, 10940.56237750489, 10987.890121098766, 10998.285111467729, 11022.338659680445, 3829.617038296148, 3949.1706741584017, 4051.39229115635, 4057.33054046085, 4121.155163191538, 8360.372972027, 8363.0, 8376.874346884802, 8379.70671026511, 8382.49705017701, 4601.953980460219, 4604.746738929324, 4609.3086037094035, 4612.3542704021465, 4617.561331673485, 4138.337865941495, 4319.503257125408, 4319.8488052918, 4320.567943205654, 4321.67587430944, 3229.564008858821, 3263.7715359924578, 3333.2500187457626, 3341.081202669285, 3355.1444381682513, 979.5689896445683, 995.5470261031359, 998.4109375468363, 1003.3528374198747, 1009.7879445316419, 706.9787906362817, 813.9470934389245, 822.4859462835727, 824.418784756745, 845.8477474054606, 5148.30497882781, 5179.689218646893, 5241.318628578337, 5250.291210686585, 5304.965531721277, 406.10656555577845, 407.8817143028548, 409.60882357348663, 423.9576042395797, 427.36322878910823, 3504.7196224301993, 3564.8574057037945, 3635.163912300186, 3661.9450708239397, 3729.5710993235584, 9144.222741066951, 9666.081722236377, 9680.56437460316, 9688.176504997155, 9689.224862011022, 9060.909390906138, 9390.154886060287, 9460.101290377403, 9555.713328600154, 9795.265355098396, 7120.287971202838, 7299.306565876233, 7305.050343455317, 7311.707531698672, 7315.743948961761, 5208.322918020633, 5330.120530112643, 5376.032314183484, 5438.428964958623, 5546.722663866871, 8524.786880328042, 8785.604647790993, 8813.515256660945, 8924.910750892408, 8940.642374304955, 8853.822923541364, 9006.909930900607, 9028.864567031369, 9065.32010099232, 9071.319651026068, 6159.692015399301, 6697.464202863856, 6854.554453960476, 6878.449724022166, 6902.930970690229, 7682.462227644235, 7743.4966727162555, 7749.806254843674, 7752.418568607264, 7754.457187996786, 6723.092382528296, 6823.317668233137, 6904.344087311697, 6909.654517274015, 7090.610016448938, 6653.434458071119, 6724.663766811542, 6745.089412929388, 6787.185537735503, 6813.591184528944, 6425.485961123192, 6481.611103333815, 6489.383508566679, 6560.868782624225, 6599.5050371223315, 4493.326001099861, 4517.570830771071, 4522.909541809211, 4611.88470288232, 4743.691660042086, 4334.891627709332, 4478.171538265431, 4492.707973981681, 4540.318952157203, 4545.977270113628], "value": 4005.380092942518, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [283.64827613758945, 288.51241402030564, 293.21272383649904, 299.3190491631539, 299.74970899299086, 4860.416749989998, 4865.367502224714, 4915.0, 4916.754162291884, 4924.261360795882, 4798.232282834747, 4807.831725889595, 4843.612510999121, 4860.173770459021, 4874.731889746063, 3594.9640503594956, 3645.3802853514835, 3774.905277469544, 3800.3539398302355, 3807.562130355003, 3808.8095595220266, 3809.4285857121513, 3821.4332123829236, 3839.616038396166, 3852.6339997700175, 3778.735488515805, 3782.1301100746846, 3794.1842503861662, 3796.9620303796883, 3800.5059342285463, 3602.9279414411694, 3672.669459748609, 3767.6640482900502, 3780.206156707095, 3784.8486060557543, 3318.2699806042633, 3333.449980753197, 3334.816585087819, 3337.349216902705, 3337.666233376667, 4677.298405239241, 4690.718556886563, 4699.882502937419, 4702.6473014524045, 4733.5503127202865, 4139.544650088488, 4303.397524346594, 4307.633851122659, 4310.870673879788, 4312.568743125694, 4990.176620857555, 4991.800327986876, 4997.100521906055, 5017.573506251973, 5054.69671819692, 3625.329314076904, 3645.2527231917393, 3658.414653655402, 3663.2673465307043, 3666.5966743658178, 5046.318746969149, 5266.210068489757, 5288.92066619001, 5309.230161626581, 5319.441458646864, 3600.4959305697225, 3609.9639003610155, 3612.151144481045, 3614.801185934746, 3619.6199399063244, 3662.7363559571904, 3673.3755261605593, 3674.81625918703, 3678.70570354371, 3678.8528458861347, 4076.0421300994244, 4139.275626765304, 4215.22018426586, 4235.30117530604, 4240.363945408213, 4978.601711863114, 4980.630326660197, 5012.273220383097, 5026.321446604735, 5026.58723812572, 1243.9440225189887, 1251.5181655062866, 1264.0582765839508, 1264.64589914824, 1268.923864568129, 766.938644908406, 790.6600161930426, 791.1732239809413, 817.9222973817477, 821.8233079887733, 4356.934645980313, 4450.841341949152, 4510.481294651091, 4510.842120525832, 4516.209663308973, 5158.767855446514, 5162.664426812245, 5167.173252279617, 5182.611304152204, 5184.144616138295, 4405.691601587922, 4476.6194873436125, 4487.169873573334, 4502.684812063124, 4503.81984720614, 2564.5255627708934, 2728.5361488546996, 2761.461515004595, 2768.4739899419046, 2770.9193414568335, 4948.455669876311, 4951.851444456672, 4952.653314267966, 4962.950370496321, 4984.825531106394, 5220.347456567929, 5265.710385928811, 5334.279872217201, 5355.678659280456, 5358.678479291255, 4900.705957642482, 4906.779194936236, 4911.361523001987, 4920.852374428773, 4931.876703082452, 5176.223566465061, 5209.447370788363, 5218.817341393033, 5221.477852214748, 5222.55608273301, 4876.8295609054285, 5117.2068329409, 5127.615428842777, 5142.665726727751, 5166.700818135893, 4968.925466118083, 4972.632526055364, 4972.781668491243, 4975.925361119658, 4982.8754281143265, 3646.052026473189, 3662.011256960659, 3681.889543313705, 3686.465462507975, 3695.1131728384457, 5081.7713202905215, 5241.44964778708, 5286.524212820865, 5315.574754019746, 5319.574434045189, 5053.635518410082, 5054.4187418447345, 5064.772085256099, 5124.333836601218, 5127.5897928166405, 5048.848534543969, 5059.443461219336, 5059.924101138412, 5127.79488820443, 5133.666311689728, 4753.643476739189, 4948.05986862495, 5006.924896126488, 5018.698878067327, 5038.193888977892, 4831.855044348676, 4938.851834444972, 5060.544550990428, 5077.238414237894, 5093.312402825572, 4846.39420072491, 4885.2427873679635, 4902.8723393620385, 4914.926276105933, 4915.26271059344], "value": 3797.3553271821966, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [584.769016238586, 586.1969102329806, 597.1818608506345, 613.662485632902, 671.8256239269452, 12321.137520373577, 13014.60956171314, 13025.153365031278, 14559.344829482672, 15084.0, 14296.213708246069, 14501.564953051398, 14515.854841451583, 14518.201498917555, 14742.63143421415, 611.523012050599, 744.6128013433012, 754.9886751698729, 775.3758224629171, 778.6885245901628, 762.3862790453683, 768.757841279995, 770.3952397368067, 771.2480331676627, 771.3597713897452, 813.7965508622844, 819.9262066414021, 820.9220124088203, 821.2116368286422, 821.2690705272313, 634.8984162534018, 774.573984308628, 808.4583329169412, 808.5835794565833, 808.5957021489255, 585.3297973819995, 591.104476717773, 591.3052163707627, 591.4440425999546, 592.0438491835697, 13696.65758356039, 13746.731789254762, 13769.655758606015, 13783.793243101361, 13793.724125517434, 11577.305361678242, 11625.767484650345, 11644.18490705651, 11822.349770762605, 12543.623691289276, 10235.744106397326, 10307.690769276935, 11243.83134252987, 11347.148963827745, 11745.0, 3273.485763330978, 3313.9171520711934, 3347.129746265964, 3455.3434847379167, 3456.1014136324484, 60783.49031135601, 61598.69200654013, 61992.380076199566, 62395.688021560054, 63036.36963630397, 8745.650173992968, 9116.316276279305, 9354.20489258407, 9461.52692365379, 9630.32587718867, 17928.462146135636, 18005.909970450324, 18067.54831129232, 18077.638447231246, 18225.0, 6475.352464753581, 6543.770968016097, 6587.143671322793, 6600.669966501656, 6621.9006714899315, 3380.036689543468, 3559.94660080099, 3611.4041183205004, 3677.3196958562753, 3709.629037096269, 2761.9585706214425, 2862.9427411451657, 2881.8270903745843, 2886.2928582497, 2888.393437378132, 497.5571741150379, 500.7120905479364, 502.3620002596755, 503.9974800125976, 509.320057722944, 467.5815145444808, 584.6287607369351, 598.3956204233755, 610.6488768957867, 615.7906311853991, 8213.79465513355, 8236.423450358538, 8311.750647480465, 8539.274161696201, 8613.181747733955, 497.8232727381727, 563.9097744360964, 581.7120525339984, 589.2272592004813, 594.0851089322396, 3843.461915331856, 3926.960730392717, 3959.3269144245546, 4049.5545489996075, 4052.63526282636, 104345.47827260815, 106661.46669266604, 107499.0, 112007.0, 112355.0, 67819.66090169517, 72276.99999999895, 78247.0, 86228.0, 88386.0, 62043.06935395973, 62642.0, 64163.679181604726, 69559.6522017407, 70158.0, 8174.632141553526, 8540.35947303967, 8610.0, 8765.824683506422, 8809.471431714117, 31445.371092578476, 33593.328133436706, 41871.37192942047, 42474.0, 43279.0, 57027.42972570221, 62097.37902620916, 62441.0, 62487.6875615619, 62918.05622915751, 10900.236983411085, 11061.668149955514, 11297.096232301232, 11359.829602555803, 11525.135614829089, 11687.415629218334, 11726.472308746297, 12038.75922481563, 12355.505779768708, 12449.69680609054, 11501.82747258775, 11598.94200528992, 11638.476268567765, 12476.625701228977, 12690.555830545887, 10923.83614245771, 10952.890471095187, 11683.532658693557, 11786.646400607995, 11836.349000805038, 11393.943030284796, 11464.770704585695, 11643.476043578228, 12353.073519486254, 12408.069394795608, 9625.181859541737, 10378.896211038094, 10404.68785936423, 10607.734806629896, 10895.673129806119, 9954.701358959242, 10130.645427410005, 10223.488825558543, 10286.168521462698, 10406.739831504274], "value": 6468.293131753253, "unit": "score"}], "name": "dromaeo_css", "value": 6305.354877576011, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 117.0, 133.0, 116.0, 112.0, 114.0, 115.0, 113.0, 113.0, 114.0], "value": 116.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 124.0, 156.0, 187.0, 128.0, 153.0, 148.0, 133.0, 184.0, 128.0], "value": 149.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 163.0, 163.0, 162.0, 164.0, 162.0, 163.0, 162.0, 162.0, 160.0], "value": 162.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 74.0, 77.0, 183.0, 77.0, 79.0, 76.0, 76.0, 154.0, 77.0], "value": 94.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 144.0, 85.0, 83.0, 84.0, 146.0, 83.0, 83.0, 134.0], "value": 101.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 116.0, 117.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 148.0, 147.0, 148.0, 147.0, 148.0, 147.0, 148.0, 148.0, 147.0], "value": 147.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 95.0, 95.0, 95.0, 96.0, 96.0, 95.0, 95.0, 95.0], "value": 95.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 65.0, 65.0, 64.0, 64.0, 64.0, 64.0, 65.0, 64.0], "value": 64.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 66.0, 66.0, 65.0, 65.0, 67.0, 65.0, 66.0, 65.0], "value": 65.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 82.0, 83.0, 84.0, 81.0, 82.0, 82.0, 81.0, 83.0], "value": 82.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 134.0, 128.0, 133.0, 131.0, 129.0, 130.0, 130.0, 182.0, 140.0], "value": 137.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 176.0, 179.0, 173.0, 172.0, 175.0, 174.0, 176.0, 174.0, 176.0], "value": 175.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 70.0, 68.0, 68.0, 70.0, 68.0, 68.0, 68.0, 68.0, 69.0], "value": 68.9, "unit": "score"}], "name": "kraken", "value": 1578.4999999999998, "alertThreshold": 2.0}]} 09:06:36 INFO - Return code: 0 09:06:37 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:06:37 INFO - # TBPL SUCCESS # 09:06:37 INFO - Running post-action listener: _resource_record_post_action 09:06:37 INFO - [mozharness: 2016-08-26 16:06:37.038800Z] Finished run-tests step (success) 09:06:37 INFO - Running post-run listener: _resource_record_post_run 09:06:37 INFO - Total resource usage - Wall time: 1205s; CPU: 13.0%; Read bytes: 12288; Write bytes: 403841024; Read time: 296; Write time: 647148 09:06:37 INFO - TinderboxPrint: CPU usage
13.1% 09:06:37 INFO - TinderboxPrint: I/O read bytes / time
12,288 / 296 09:06:37 INFO - TinderboxPrint: I/O write bytes / time
403,841,024 / 647,148 09:06:37 INFO - TinderboxPrint: CPU idle
8,356.9 (86.9%) 09:06:37 INFO - TinderboxPrint: CPU user
1,200.6 (12.5%) 09:06:37 INFO - TinderboxPrint: Swap in / out
0 / 0 09:06:37 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 0; Write bytes: 113938432; Read time: 0; Write time: 227568 09:06:37 INFO - run-tests - Wall time: 1189s; CPU: 13.0%; Read bytes: 8192; Write bytes: 288415744; Read time: 280; Write time: 418072 09:06:37 INFO - Running post-run listener: _upload_blobber_files 09:06:37 INFO - Blob upload gear active. 09:06:37 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:06:37 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 09:06:37 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'] 09:06:37 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 09:06:38 INFO - (blobuploader) - INFO - Open directory for files ... 09:06:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:06:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:06:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:06:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:06:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:06:39 INFO - (blobuploader) - INFO - Done attempting. 09:06:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:06:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:06:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:06:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:06:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:06:39 INFO - (blobuploader) - INFO - Done attempting. 09:06:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:06:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:06:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:06:47 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:06:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:06:47 INFO - (blobuploader) - INFO - Done attempting. 09:06:47 INFO - (blobuploader) - INFO - Iteration through files over. 09:06:47 INFO - Return code: 0 09:06:47 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:06:47 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:06:47 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/fcb28fb997e92d782b87fd5a88297ade6d055215273edfa687c3d2d3ea2c556428901339f845ee32f301d6f443cd39daa31f3b5ead581e55519cc5175a74f57f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/7a013542b0a82de89e1b980780fedfe381103c67f4abd241caf0c620285c51dbba2e2dfe44c4012a0f59f7ed275a5b5ed879f8d760254614528e9a09fd5fbde4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/6100013170f5d4d873f11faa14fdddde8a39918840e923472b9b146dacb7a66020b357766941cabf4d0faf895945bc52adc996c425dfda7d9585b3dfa6b4a83b"} 09:06:47 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:06:47 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:06:47 INFO - Contents: 09:06:47 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/fcb28fb997e92d782b87fd5a88297ade6d055215273edfa687c3d2d3ea2c556428901339f845ee32f301d6f443cd39daa31f3b5ead581e55519cc5175a74f57f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/7a013542b0a82de89e1b980780fedfe381103c67f4abd241caf0c620285c51dbba2e2dfe44c4012a0f59f7ed275a5b5ed879f8d760254614528e9a09fd5fbde4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/6100013170f5d4d873f11faa14fdddde8a39918840e923472b9b146dacb7a66020b357766941cabf4d0faf895945bc52adc996c425dfda7d9585b3dfa6b4a83b"} 09:06:47 INFO - Running post-run listener: copy_logs_to_upload_dir 09:06:47 INFO - Copying logs to upload dir... 09:06:47 INFO - mkdir: /builds/slave/test/build/upload/logs 09:06:47 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1255.152123 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2016-08-26 09:06:47.409557) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-26 09:06:47.413460) ========= 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=5cf47e208febbcd81da23b2e0000026f-1472226266.231314-1163103039 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/fcb28fb997e92d782b87fd5a88297ade6d055215273edfa687c3d2d3ea2c556428901339f845ee32f301d6f443cd39daa31f3b5ead581e55519cc5175a74f57f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/7a013542b0a82de89e1b980780fedfe381103c67f4abd241caf0c620285c51dbba2e2dfe44c4012a0f59f7ed275a5b5ed879f8d760254614528e9a09fd5fbde4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/6100013170f5d4d873f11faa14fdddde8a39918840e923472b9b146dacb7a66020b357766941cabf4d0faf895945bc52adc996c425dfda7d9585b3dfa6b4a83b"} build_url:https://queue.taskcluster.net/v1/task/LIR-0PQARW-s9sj9Lmf4gg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011336 build_url: 'https://queue.taskcluster.net/v1/task/LIR-0PQARW-s9sj9Lmf4gg/artifacts/public/build/firefox-51.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/fcb28fb997e92d782b87fd5a88297ade6d055215273edfa687c3d2d3ea2c556428901339f845ee32f301d6f443cd39daa31f3b5ead581e55519cc5175a74f57f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/7a013542b0a82de89e1b980780fedfe381103c67f4abd241caf0c620285c51dbba2e2dfe44c4012a0f59f7ed275a5b5ed879f8d760254614528e9a09fd5fbde4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/6100013170f5d4d873f11faa14fdddde8a39918840e923472b9b146dacb7a66020b357766941cabf4d0faf895945bc52adc996c425dfda7d9585b3dfa6b4a83b"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-26 09:06:47.438714) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 09:06:47.439070) ========= 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=5cf47e208febbcd81da23b2e0000026f-1472226266.231314-1163103039 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004450 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 09:06:47.521974) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-26 09:06:47.522303) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-26 09:06:47.522588) ========= ========= Total master_lag: 0.18 =========