builder: autoland_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-053 starttime: 1474951818.01 results: success (0) buildid: 20160926193551 builduid: 14c3493286ca4e5382bc1df52caad847 revision: 7b050ca8ec6474c6d7131d0ebd352e8624992016 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-26 21:50:18.006167) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-26 21:50:18.006651) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-26 21:50:18.006968) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-26 21:50:18.039449) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-26 21:50:18.039760) ========= 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=920470bf432669ad7e3a5387000001ca-1474945679.223815-740405567 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-26 21:50:19-- 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% 46.4M=0s 2016-09-26 21:50:20 (46.4 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.159583 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-26 21:50:18.213876) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-26 21:50:18.214199) ========= 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=920470bf432669ad7e3a5387000001ca-1474945679.223815-740405567 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.021785 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-26 21:50:18.274510) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-26 21:50:18.274912) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 7b050ca8ec6474c6d7131d0ebd352e8624992016 --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 7b050ca8ec6474c6d7131d0ebd352e8624992016 --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=920470bf432669ad7e3a5387000001ca-1474945679.223815-740405567 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-26 21:50:20,200 truncating revision to first 12 chars 2016-09-26 21:50:20,200 Setting DEBUG logging. 2016-09-26 21:50:20,200 attempt 1/10 2016-09-26 21:50:20,200 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/7b050ca8ec64?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-26 21:50:20,705 unpacking tar archive at: autoland-7b050ca8ec64/testing/mozharness/ program finished with exit code 0 elapsedTime=0.838182 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-26 21:50:19.137920) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-26 21:50:19.138695) ========= script_repo_revision: 7b050ca8ec6474c6d7131d0ebd352e8624992016 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-26 21:50:19.139416) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-26 21:50:19.139741) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-26 21:50:19.151431) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 43 secs) (at 2016-09-26 21:50:19.152013) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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=920470bf432669ad7e3a5387000001ca-1474945679.223815-740405567 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 21:50:21 INFO - MultiFileLogger online at 20160926 21:50:21 in /builds/slave/test 21:50:21 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Autoland-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland-Non-PGO 21:50:21 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 21:50:21 INFO - {'append_to_log': False, 21:50:21 INFO - 'base_work_dir': '/builds/slave/test', 21:50:21 INFO - 'blob_upload_branch': 'Autoland-Non-PGO', 21:50:21 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 21:50:21 INFO - 'branch': 'Autoland-Non-PGO', 21:50:21 INFO - 'buildbot_json_path': 'buildprops.json', 21:50:21 INFO - 'config_files': ('talos/linux_config.py',), 21:50:21 INFO - 'default_actions': ('clobber', 21:50:21 INFO - 'read-buildbot-config', 21:50:21 INFO - 'download-and-extract', 21:50:21 INFO - 'populate-webroot', 21:50:21 INFO - 'create-virtualenv', 21:50:21 INFO - 'install', 21:50:21 INFO - 'run-tests'), 21:50:21 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:50:21 INFO - 'download_minidump_stackwalk': True, 21:50:21 INFO - 'download_symbols': 'ondemand', 21:50:21 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:50:21 INFO - 'tooltool.py': '/tools/tooltool.py', 21:50:21 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:50:21 INFO - '/tools/misc-python/virtualenv.py')}, 21:50:21 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:50:21 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:50:21 INFO - 'installer_path': 'installer.exe', 21:50:21 INFO - 'log_level': 'info', 21:50:21 INFO - 'log_name': 'talos', 21:50:21 INFO - 'log_to_console': True, 21:50:21 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 21:50:21 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 21:50:21 INFO - 'opt_config_files': (), 21:50:21 INFO - 'pip_index': False, 21:50:21 INFO - 'sps_profile': False, 21:50:21 INFO - 'sps_profile_interval': 0, 21:50:21 INFO - 'suite': 'dromaeojs-e10s', 21:50:21 INFO - 'system_bits': '32', 21:50:21 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:50:21 INFO - 'title': 'talos-linux64-ix-053', 21:50:21 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:50:21 INFO - 'use_talos_json': True, 21:50:21 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 21:50:21 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:50:21 INFO - 'work_dir': 'build'} 21:50:21 INFO - [mozharness: 2016-09-27 04:50:21.175516Z] Running clobber step. 21:50:21 INFO - Running pre-action listener: _resource_record_pre_action 21:50:21 INFO - Running main action method: clobber 21:50:21 INFO - rmtree: /builds/slave/test/build 21:50:21 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 21:50:21 INFO - Running post-action listener: _resource_record_post_action 21:50:21 INFO - [mozharness: 2016-09-27 04:50:21.633010Z] Finished clobber step (success) 21:50:21 INFO - [mozharness: 2016-09-27 04:50:21.633109Z] Running read-buildbot-config step. 21:50:21 INFO - Running pre-action listener: _resource_record_pre_action 21:50:21 INFO - Running main action method: read_buildbot_config 21:50:21 INFO - Using buildbot properties: 21:50:21 INFO - { 21:50:21 INFO - "project": "", 21:50:21 INFO - "product": "firefox", 21:50:21 INFO - "script_repo_revision": "production", 21:50:21 INFO - "builddir": "autoland_ubuntu64_hw_test-dromaeojs-e10s", 21:50:21 INFO - "repository": "", 21:50:21 INFO - "buildername": "Ubuntu HW 12.04 x64 autoland talos dromaeojs-e10s", 21:50:21 INFO - "buildid": "20160926193551", 21:50:21 INFO - "pgo_build": "False", 21:50:21 INFO - "basedir": "/builds/slave/test", 21:50:21 INFO - "buildnumber": 466, 21:50:21 INFO - "slavename": "talos-linux64-ix-053", 21:50:21 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 21:50:21 INFO - "platform": "ubuntu64_hw", 21:50:21 INFO - "scheduler": "tests-autoland-linux64-talos-8-3600", 21:50:21 INFO - "branch": "autoland", 21:50:21 INFO - "repo_path": "integration/autoland", 21:50:21 INFO - "revision": "7b050ca8ec6474c6d7131d0ebd352e8624992016", 21:50:21 INFO - "stage_platform": "linux64", 21:50:21 INFO - "builduid": "14c3493286ca4e5382bc1df52caad847", 21:50:21 INFO - "slavebuilddir": "test" 21:50:21 INFO - } 21:50:21 INFO - Found installer url https://queue.taskcluster.net/v1/task/GkGR1c4-TUiBQh_LwqpQSw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2. 21:50:21 INFO - Running post-action listener: _resource_record_post_action 21:50:21 INFO - [mozharness: 2016-09-27 04:50:21.635523Z] Finished read-buildbot-config step (success) 21:50:21 INFO - [mozharness: 2016-09-27 04:50:21.635609Z] Running download-and-extract step. 21:50:21 INFO - Running pre-action listener: _resource_record_pre_action 21:50:21 INFO - Running main action method: download_and_extract 21:50:21 INFO - mkdir: /builds/slave/test/build/tests 21:50:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:50:21 INFO - trying https://queue.taskcluster.net/v1/task/GkGR1c4-TUiBQh_LwqpQSw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 21:50:21 INFO - Downloading https://queue.taskcluster.net/v1/task/GkGR1c4-TUiBQh_LwqpQSw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 21:50:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GkGR1c4-TUiBQh_LwqpQSw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 21:50:22 INFO - Downloaded 1361 bytes. 21:50:22 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 21:50:22 INFO - Using the following test package requirements: 21:50:22 INFO - {u'common': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'], 21:50:22 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 21:50:22 INFO - u'firefox-52.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 21:50:22 INFO - u'gtest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 21:50:22 INFO - u'firefox-52.0a1.en-US.linux-x86_64.gtest.tests.zip'], 21:50:22 INFO - u'jittest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 21:50:22 INFO - u'jsshell-linux-x86_64.zip'], 21:50:22 INFO - u'mochitest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 21:50:22 INFO - u'firefox-52.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 21:50:22 INFO - u'mozbase': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'], 21:50:22 INFO - u'reftest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 21:50:22 INFO - u'firefox-52.0a1.en-US.linux-x86_64.reftest.tests.zip'], 21:50:22 INFO - u'talos': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 21:50:22 INFO - u'firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'], 21:50:22 INFO - u'web-platform': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 21:50:22 INFO - u'firefox-52.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 21:50:22 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 21:50:22 INFO - u'firefox-52.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 21:50:22 INFO - Downloading packages: [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 21:50:22 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GkGR1c4-TUiBQh_LwqpQSw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip 21:50:22 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GkGR1c4-TUiBQh_LwqpQSw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 21:50:22 INFO - Fetch https://queue.taskcluster.net/v1/task/GkGR1c4-TUiBQh_LwqpQSw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip into memory 21:50:22 INFO - Expected file size: 20410861 21:50:27 INFO - Downloading packages: [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 21:50:27 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GkGR1c4-TUiBQh_LwqpQSw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip 21:50:27 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GkGR1c4-TUiBQh_LwqpQSw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 21:50:27 INFO - Fetch https://queue.taskcluster.net/v1/task/GkGR1c4-TUiBQh_LwqpQSw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip into memory 21:50:27 INFO - Expected file size: 20410861 21:50:32 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GkGR1c4-TUiBQh_LwqpQSw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip 21:50:32 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GkGR1c4-TUiBQh_LwqpQSw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 21:50:32 INFO - Fetch https://queue.taskcluster.net/v1/task/GkGR1c4-TUiBQh_LwqpQSw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip into memory 21:50:32 INFO - Expected file size: 14414057 21:50:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:50:33 INFO - trying https://queue.taskcluster.net/v1/task/GkGR1c4-TUiBQh_LwqpQSw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 21:50:33 INFO - Downloading https://queue.taskcluster.net/v1/task/GkGR1c4-TUiBQh_LwqpQSw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 21:50:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GkGR1c4-TUiBQh_LwqpQSw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 21:50:34 INFO - Downloaded 55742258 bytes. 21:50:34 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/GkGR1c4-TUiBQh_LwqpQSw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 21:50:34 INFO - mkdir: /builds/slave/test/properties 21:50:34 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 21:50:34 INFO - Writing to file /builds/slave/test/properties/build_url 21:50:34 INFO - Contents: 21:50:34 INFO - build_url:https://queue.taskcluster.net/v1/task/GkGR1c4-TUiBQh_LwqpQSw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 21:50:35 INFO - Running post-action listener: _resource_record_post_action 21:50:35 INFO - Running post-action listener: set_extra_try_arguments 21:50:35 INFO - [mozharness: 2016-09-27 04:50:35.219655Z] Finished download-and-extract step (success) 21:50:35 INFO - [mozharness: 2016-09-27 04:50:35.219952Z] Running populate-webroot step. 21:50:35 INFO - Running pre-action listener: _resource_record_pre_action 21:50:35 INFO - Running main action method: populate_webroot 21:50:35 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 21:50:35 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 21:50:35 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 21:50:35 INFO - '--tppagecycles', 21:50:35 INFO - '7'], 21:50:35 INFO - 'tests': ['tsvgm']}}, 21:50:35 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 21:50:35 INFO - 'tests': ['tresize', 'tcanvasmark']}, 21:50:35 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 21:50:35 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 21:50:35 INFO - 'tests': ['dromaeo_css', 'kraken']}, 21:50:35 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 21:50:35 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:50:35 INFO - 'talos_options': ['--disable-e10s'], 21:50:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:50:35 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:50:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:50:35 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:50:35 INFO - 'talos_options': ['--disable-e10s'], 21:50:35 INFO - 'tests': ['damp', 'tps']}, 21:50:35 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:50:35 INFO - 'tests': ['damp', 'tps']}, 21:50:35 INFO - 'g3': {'talos_options': ['--disable-e10s'], 21:50:35 INFO - 'tests': ['dromaeo_dom']}, 21:50:35 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:50:35 INFO - 'g4': {'talos_options': ['--disable-e10s'], 21:50:35 INFO - 'tests': ['basic_compositor_video']}, 21:50:35 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 21:50:35 INFO - 'other': {'talos_options': ['--disable-e10s'], 21:50:35 INFO - 'tests': ['a11yr', 21:50:35 INFO - 'ts_paint', 21:50:35 INFO - 'tpaint', 21:50:35 INFO - 'sessionrestore', 21:50:35 INFO - 'sessionrestore_no_auto_restore', 21:50:35 INFO - 'tabpaint']}, 21:50:35 INFO - 'other-e10s': {'tests': ['a11yr', 21:50:35 INFO - 'ts_paint', 21:50:35 INFO - 'tpaint', 21:50:35 INFO - 'sessionrestore', 21:50:35 INFO - 'sessionrestore_no_auto_restore', 21:50:35 INFO - 'tabpaint']}, 21:50:35 INFO - 'other-e10s_l64': {'tests': ['a11yr', 21:50:35 INFO - 'ts_paint', 21:50:35 INFO - 'tpaint', 21:50:35 INFO - 'sessionrestore', 21:50:35 INFO - 'sessionrestore_no_auto_restore', 21:50:35 INFO - 'tabpaint']}, 21:50:35 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 21:50:35 INFO - 'ts_paint', 21:50:35 INFO - 'tpaint', 21:50:35 INFO - 'sessionrestore', 21:50:35 INFO - 'sessionrestore_no_auto_restore', 21:50:35 INFO - 'tabpaint']}, 21:50:35 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 21:50:35 INFO - 'tests': ['a11yr', 21:50:35 INFO - 'ts_paint', 21:50:35 INFO - 'tpaint', 21:50:35 INFO - 'sessionrestore', 21:50:35 INFO - 'sessionrestore_no_auto_restore', 21:50:35 INFO - 'tabpaint']}, 21:50:35 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 21:50:35 INFO - 'tests': ['a11yr', 21:50:35 INFO - 'ts_paint', 21:50:35 INFO - 'tpaint', 21:50:35 INFO - 'sessionrestore', 21:50:35 INFO - 'sessionrestore_no_auto_restore', 21:50:35 INFO - 'tabpaint']}, 21:50:35 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 21:50:35 INFO - 'tests': ['tsvgx', 21:50:35 INFO - 'tsvgr_opacity', 21:50:35 INFO - 'tart', 21:50:35 INFO - 'tscrollx', 21:50:35 INFO - 'cart']}, 21:50:35 INFO - 'svgr-e10s': {'tests': ['tsvgx', 21:50:35 INFO - 'tsvgr_opacity', 21:50:35 INFO - 'tart', 21:50:35 INFO - 'tscrollx', 21:50:35 INFO - 'cart']}, 21:50:35 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:50:35 INFO - 'talos_options': ['--disable-e10s'], 21:50:35 INFO - 'tests': ['tp5o']}, 21:50:35 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:50:35 INFO - 'tests': ['tp5o']}, 21:50:35 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:50:35 INFO - 'talos_options': ['--disable-e10s', 21:50:35 INFO - '--xperf_path', 21:50:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:50:35 INFO - 'tests': ['tp5n']}, 21:50:35 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:50:35 INFO - 'talos_options': ['--xperf_path', 21:50:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:50:35 INFO - 'tests': ['tp5n']}}, 21:50:35 INFO - 'talos.zip': {'path': '', 21:50:35 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:50:35 INFO - Running post-action listener: _resource_record_post_action 21:50:35 INFO - [mozharness: 2016-09-27 04:50:35.237751Z] Finished populate-webroot step (success) 21:50:35 INFO - [mozharness: 2016-09-27 04:50:35.237844Z] Running create-virtualenv step. 21:50:35 INFO - Running pre-action listener: _resource_record_pre_action 21:50:35 INFO - Running main action method: create_virtualenv 21:50:35 INFO - Creating virtualenv /builds/slave/test/build/venv 21:50:35 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 21:50:35 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 21:50:35 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:50:35 INFO - Using real prefix '/usr' 21:50:35 INFO - New python executable in /builds/slave/test/build/venv/bin/python 21:50:35 INFO - Installing distribute.............................................................................................................................................................................................done. 21:50:37 INFO - Installing pip.................done. 21:50:37 INFO - Return code: 0 21:50:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 21:50:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 21:50:38 INFO - Reading from file tmpfile_stdout 21:50:38 INFO - Output received: 21:50:38 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 21:50:38 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 21:50:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:50:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:50:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:50:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:50:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:50:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:50:38 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 0x7fd900eb4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x255e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x262bca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2628f60>, '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 0x2629d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x262a1d0>, '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': '920470bf432669ad7e3a5387000001ca-1474945679.223815-740405567', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 21:50:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 21:50:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 21:50:38 INFO - Using env: {'DISPLAY': ':0', 21:50:38 INFO - 'HOME': '/home/cltbld', 21:50:38 INFO - 'LANG': 'en_US.UTF-8', 21:50:38 INFO - 'LANGUAGE': 'en_US:en', 21:50:38 INFO - 'LOGNAME': 'cltbld', 21:50:38 INFO - 'MAIL': '/var/mail/cltbld', 21:50:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:50:38 INFO - 'MOZ_NO_REMOTE': '1', 21:50:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:50:38 INFO - 'NO_EM_RESTART': '1', 21:50:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:50:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:50:38 INFO - 'PWD': '/builds/slave/test', 21:50:38 INFO - 'SHELL': '/bin/bash', 21:50:38 INFO - 'SHLVL': '1', 21:50:38 INFO - 'TERM': 'linux', 21:50:38 INFO - 'TMOUT': '86400', 21:50:38 INFO - 'USER': 'cltbld', 21:50:38 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1474945679.223815-740405567', 21:50:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:50:38 INFO - '_': '/tools/buildbot/bin/python'} 21:50:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:50:38 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 21:50:38 INFO - Cleaning up... 21:50:38 INFO - Return code: 0 21:50:38 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 21:50:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:50:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:50:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:50:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:50:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:50:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:50:38 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 0x7fd900eb4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x255e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x262bca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2628f60>, '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 0x2629d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x262a1d0>, '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': '920470bf432669ad7e3a5387000001ca-1474945679.223815-740405567', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 21:50:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 21:50:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 21:50:38 INFO - Using env: {'DISPLAY': ':0', 21:50:38 INFO - 'HOME': '/home/cltbld', 21:50:38 INFO - 'LANG': 'en_US.UTF-8', 21:50:38 INFO - 'LANGUAGE': 'en_US:en', 21:50:38 INFO - 'LOGNAME': 'cltbld', 21:50:38 INFO - 'MAIL': '/var/mail/cltbld', 21:50:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:50:38 INFO - 'MOZ_NO_REMOTE': '1', 21:50:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:50:38 INFO - 'NO_EM_RESTART': '1', 21:50:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:50:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:50:38 INFO - 'PWD': '/builds/slave/test', 21:50:38 INFO - 'SHELL': '/bin/bash', 21:50:38 INFO - 'SHLVL': '1', 21:50:38 INFO - 'TERM': 'linux', 21:50:38 INFO - 'TMOUT': '86400', 21:50:38 INFO - 'USER': 'cltbld', 21:50:38 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1474945679.223815-740405567', 21:50:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:50:38 INFO - '_': '/tools/buildbot/bin/python'} 21:50:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:50:38 INFO - Downloading/unpacking psutil>=3.1.1 21:50:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:50:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:50:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:50:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:50:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:50:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:50:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 21:50:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:50:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:50:40 INFO - Installing collected packages: psutil 21:50:40 INFO - Running setup.py install for psutil 21:50:40 INFO - building 'psutil._psutil_linux' extension 21:50:40 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 21:50:40 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 21:50:40 INFO - building 'psutil._psutil_posix' extension 21:50:40 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 21:50:40 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 21:50:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:50:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:50:40 INFO - Successfully installed psutil 21:50:40 INFO - Cleaning up... 21:50:41 INFO - Return code: 0 21:50:41 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 21:50:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:50:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:50:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:50:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:50:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:50:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:50:41 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 0x7fd900eb4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x255e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x262bca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2628f60>, '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 0x2629d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x262a1d0>, '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': '920470bf432669ad7e3a5387000001ca-1474945679.223815-740405567', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 21:50:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 21:50:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 21:50:41 INFO - Using env: {'DISPLAY': ':0', 21:50:41 INFO - 'HOME': '/home/cltbld', 21:50:41 INFO - 'LANG': 'en_US.UTF-8', 21:50:41 INFO - 'LANGUAGE': 'en_US:en', 21:50:41 INFO - 'LOGNAME': 'cltbld', 21:50:41 INFO - 'MAIL': '/var/mail/cltbld', 21:50:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:50:41 INFO - 'MOZ_NO_REMOTE': '1', 21:50:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:50:41 INFO - 'NO_EM_RESTART': '1', 21:50:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:50:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:50:41 INFO - 'PWD': '/builds/slave/test', 21:50:41 INFO - 'SHELL': '/bin/bash', 21:50:41 INFO - 'SHLVL': '1', 21:50:41 INFO - 'TERM': 'linux', 21:50:41 INFO - 'TMOUT': '86400', 21:50:41 INFO - 'USER': 'cltbld', 21:50:41 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1474945679.223815-740405567', 21:50:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:50:41 INFO - '_': '/tools/buildbot/bin/python'} 21:50:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:50:41 INFO - Downloading/unpacking mozsystemmonitor==0.3 21:50:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:50:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:50:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:50:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:50:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:50:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:50:43 INFO - Downloading mozsystemmonitor-0.3.tar.gz 21:50:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:50:43 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 21:50:43 INFO - Installing collected packages: mozsystemmonitor 21:50:43 INFO - Running setup.py install for mozsystemmonitor 21:50:43 INFO - Successfully installed mozsystemmonitor 21:50:43 INFO - Cleaning up... 21:50:43 INFO - Return code: 0 21:50:43 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 21:50:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:50:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:50:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:50:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:50:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:50:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:50:43 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 0x7fd900eb4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x255e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x262bca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2628f60>, '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 0x2629d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x262a1d0>, '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': '920470bf432669ad7e3a5387000001ca-1474945679.223815-740405567', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 21:50:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 21:50:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 21:50:43 INFO - Using env: {'DISPLAY': ':0', 21:50:43 INFO - 'HOME': '/home/cltbld', 21:50:43 INFO - 'LANG': 'en_US.UTF-8', 21:50:43 INFO - 'LANGUAGE': 'en_US:en', 21:50:43 INFO - 'LOGNAME': 'cltbld', 21:50:43 INFO - 'MAIL': '/var/mail/cltbld', 21:50:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:50:43 INFO - 'MOZ_NO_REMOTE': '1', 21:50:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:50:43 INFO - 'NO_EM_RESTART': '1', 21:50:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:50:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:50:43 INFO - 'PWD': '/builds/slave/test', 21:50:43 INFO - 'SHELL': '/bin/bash', 21:50:43 INFO - 'SHLVL': '1', 21:50:43 INFO - 'TERM': 'linux', 21:50:43 INFO - 'TMOUT': '86400', 21:50:43 INFO - 'USER': 'cltbld', 21:50:43 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1474945679.223815-740405567', 21:50:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:50:43 INFO - '_': '/tools/buildbot/bin/python'} 21:50:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:50:43 INFO - Downloading/unpacking jsonschema==2.5.1 21:50:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:50:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:50:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:50:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:50:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:50:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:50:45 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:50:45 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 21:50:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:50:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:50:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:50:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:50:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:50:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:50:45 INFO - Downloading functools32-3.2.3-2.tar.gz 21:50:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 21:50:45 INFO - warning: no files found matching '*.txt' 21:50:45 INFO - no previously-included directories found matching 'build' 21:50:45 INFO - no previously-included directories found matching 'dist' 21:50:45 INFO - no previously-included directories found matching '.git*' 21:50:45 INFO - Installing collected packages: jsonschema, functools32 21:50:45 INFO - Running setup.py install for functools32 21:50:45 INFO - warning: no files found matching '*.txt' 21:50:45 INFO - no previously-included directories found matching 'build' 21:50:45 INFO - no previously-included directories found matching 'dist' 21:50:45 INFO - no previously-included directories found matching '.git*' 21:50:45 INFO - Successfully installed jsonschema functools32 21:50:45 INFO - Cleaning up... 21:50:45 INFO - Return code: 0 21:50:45 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 21:50:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:50:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:50:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:50:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:50:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:50:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:50:45 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 0x7fd900eb4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x255e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x262bca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2628f60>, '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 0x2629d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x262a1d0>, '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': '920470bf432669ad7e3a5387000001ca-1474945679.223815-740405567', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 21:50:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 21:50:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 21:50:45 INFO - Using env: {'DISPLAY': ':0', 21:50:45 INFO - 'HOME': '/home/cltbld', 21:50:45 INFO - 'LANG': 'en_US.UTF-8', 21:50:45 INFO - 'LANGUAGE': 'en_US:en', 21:50:45 INFO - 'LOGNAME': 'cltbld', 21:50:45 INFO - 'MAIL': '/var/mail/cltbld', 21:50:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:50:45 INFO - 'MOZ_NO_REMOTE': '1', 21:50:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:50:45 INFO - 'NO_EM_RESTART': '1', 21:50:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:50:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:50:45 INFO - 'PWD': '/builds/slave/test', 21:50:45 INFO - 'SHELL': '/bin/bash', 21:50:45 INFO - 'SHLVL': '1', 21:50:45 INFO - 'TERM': 'linux', 21:50:45 INFO - 'TMOUT': '86400', 21:50:45 INFO - 'USER': 'cltbld', 21:50:45 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1474945679.223815-740405567', 21:50:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:50:45 INFO - '_': '/tools/buildbot/bin/python'} 21:50:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:50:46 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 21:50:46 INFO - Cleaning up... 21:50:46 INFO - Return code: 0 21:50:46 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 21:50:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:50:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:50:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:50:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:50:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:50:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:50:46 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 0x7fd900eb4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x255e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x262bca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2628f60>, '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 0x2629d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x262a1d0>, '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': '920470bf432669ad7e3a5387000001ca-1474945679.223815-740405567', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 21:50:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 21:50:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 21:50:46 INFO - Using env: {'DISPLAY': ':0', 21:50:46 INFO - 'HOME': '/home/cltbld', 21:50:46 INFO - 'LANG': 'en_US.UTF-8', 21:50:46 INFO - 'LANGUAGE': 'en_US:en', 21:50:46 INFO - 'LOGNAME': 'cltbld', 21:50:46 INFO - 'MAIL': '/var/mail/cltbld', 21:50:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:50:46 INFO - 'MOZ_NO_REMOTE': '1', 21:50:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:50:46 INFO - 'NO_EM_RESTART': '1', 21:50:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:50:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:50:46 INFO - 'PWD': '/builds/slave/test', 21:50:46 INFO - 'SHELL': '/bin/bash', 21:50:46 INFO - 'SHLVL': '1', 21:50:46 INFO - 'TERM': 'linux', 21:50:46 INFO - 'TMOUT': '86400', 21:50:46 INFO - 'USER': 'cltbld', 21:50:46 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1474945679.223815-740405567', 21:50:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:50:46 INFO - '_': '/tools/buildbot/bin/python'} 21:50:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:50:46 INFO - Downloading/unpacking blobuploader==1.2.4 21:50:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:50:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:50:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:50:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:50:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:50:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:50:48 INFO - Downloading blobuploader-1.2.4.tar.gz 21:50:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:50:48 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:50:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:50:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:50:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:50:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:50:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:50:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:50:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 21:50:48 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:50:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:50:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:50:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:50:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:50:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:50:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:50:48 INFO - Downloading docopt-0.6.1.tar.gz 21:50:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 21:50:49 INFO - Installing collected packages: blobuploader, requests, docopt 21:50:49 INFO - Running setup.py install for blobuploader 21:50:49 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:50:49 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 21:50:49 INFO - Running setup.py install for requests 21:50:49 INFO - Running setup.py install for docopt 21:50:49 INFO - Successfully installed blobuploader requests docopt 21:50:49 INFO - Cleaning up... 21:50:49 INFO - Return code: 0 21:50:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:50:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:50:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:50:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:50:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:50:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:50:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:50:49 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 0x7fd900eb4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x255e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x262bca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2628f60>, '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 0x2629d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x262a1d0>, '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': '920470bf432669ad7e3a5387000001ca-1474945679.223815-740405567', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 21:50:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 21:50:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 21:50:49 INFO - Using env: {'DISPLAY': ':0', 21:50:49 INFO - 'HOME': '/home/cltbld', 21:50:49 INFO - 'LANG': 'en_US.UTF-8', 21:50:49 INFO - 'LANGUAGE': 'en_US:en', 21:50:49 INFO - 'LOGNAME': 'cltbld', 21:50:49 INFO - 'MAIL': '/var/mail/cltbld', 21:50:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:50:49 INFO - 'MOZ_NO_REMOTE': '1', 21:50:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:50:49 INFO - 'NO_EM_RESTART': '1', 21:50:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:50:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:50:49 INFO - 'PWD': '/builds/slave/test', 21:50:49 INFO - 'SHELL': '/bin/bash', 21:50:49 INFO - 'SHLVL': '1', 21:50:49 INFO - 'TERM': 'linux', 21:50:49 INFO - 'TMOUT': '86400', 21:50:49 INFO - 'USER': 'cltbld', 21:50:49 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1474945679.223815-740405567', 21:50:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:50:49 INFO - '_': '/tools/buildbot/bin/python'} 21:50:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:50:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:50:49 INFO - Running setup.py (path:/tmp/pip-Ddsdg1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:50:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:50:49 INFO - Running setup.py (path:/tmp/pip-GlFaF5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:50:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:50:49 INFO - Running setup.py (path:/tmp/pip-r6PdQL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:50:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:50:50 INFO - Running setup.py (path:/tmp/pip-tZwJoG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:50:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:50:50 INFO - Running setup.py (path:/tmp/pip-dweOcA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:50:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:50:50 INFO - Running setup.py (path:/tmp/pip-3K4SkC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:50:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:50:50 INFO - Running setup.py (path:/tmp/pip-hE4yqB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:50:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:50:50 INFO - Running setup.py (path:/tmp/pip-1vXcXj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:50:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:50:50 INFO - Running setup.py (path:/tmp/pip-iFyjnz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:50:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:50:50 INFO - Running setup.py (path:/tmp/pip-t719xG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:50:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:50:50 INFO - Running setup.py (path:/tmp/pip-YmUkt9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:50:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:50:50 INFO - Running setup.py (path:/tmp/pip-Va9fA4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:50:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:50:50 INFO - Running setup.py (path:/tmp/pip-KUNhQD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:50:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:50:50 INFO - Running setup.py (path:/tmp/pip-IPiE38-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:50:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:50:50 INFO - Running setup.py (path:/tmp/pip-yyQPEs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:50:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:50:50 INFO - Running setup.py (path:/tmp/pip-m4byid-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:50:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:50:50 INFO - Running setup.py (path:/tmp/pip-4OhpFw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:50:51 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:50:51 INFO - Running setup.py install for manifestparser 21:50:51 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 21:50:51 INFO - Running setup.py install for mozcrash 21:50:51 INFO - Running setup.py install for mozdebug 21:50:51 INFO - Running setup.py install for mozdevice 21:50:51 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 21:50:51 INFO - Installing dm script to /builds/slave/test/build/venv/bin 21:50:51 INFO - Running setup.py install for mozfile 21:50:51 INFO - Running setup.py install for mozhttpd 21:50:51 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 21:50:51 INFO - Running setup.py install for mozinfo 21:50:51 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 21:50:51 INFO - Running setup.py install for mozInstall 21:50:51 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 21:50:51 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 21:50:51 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 21:50:51 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 21:50:51 INFO - Running setup.py install for mozleak 21:50:51 INFO - Running setup.py install for mozlog 21:50:52 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 21:50:52 INFO - Running setup.py install for moznetwork 21:50:52 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 21:50:52 INFO - Running setup.py install for mozprocess 21:50:52 INFO - Running setup.py install for mozprofile 21:50:52 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 21:50:52 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 21:50:52 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 21:50:52 INFO - Running setup.py install for mozrunner 21:50:52 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 21:50:52 INFO - Running setup.py install for mozscreenshot 21:50:52 INFO - Running setup.py install for moztest 21:50:52 INFO - Running setup.py install for mozversion 21:50:52 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 21:50:52 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:50:52 INFO - Cleaning up... 21:50:52 INFO - Return code: 0 21:50:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:50:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:50:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:50:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:50:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:50:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:50:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:50:52 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 0x7fd900eb4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x255e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x262bca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2628f60>, '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 0x2629d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x262a1d0>, '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': '920470bf432669ad7e3a5387000001ca-1474945679.223815-740405567', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 21:50:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 21:50:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 21:50:52 INFO - Using env: {'DISPLAY': ':0', 21:50:52 INFO - 'HOME': '/home/cltbld', 21:50:52 INFO - 'LANG': 'en_US.UTF-8', 21:50:52 INFO - 'LANGUAGE': 'en_US:en', 21:50:52 INFO - 'LOGNAME': 'cltbld', 21:50:52 INFO - 'MAIL': '/var/mail/cltbld', 21:50:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:50:52 INFO - 'MOZ_NO_REMOTE': '1', 21:50:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:50:52 INFO - 'NO_EM_RESTART': '1', 21:50:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:50:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:50:52 INFO - 'PWD': '/builds/slave/test', 21:50:52 INFO - 'SHELL': '/bin/bash', 21:50:52 INFO - 'SHLVL': '1', 21:50:52 INFO - 'TERM': 'linux', 21:50:52 INFO - 'TMOUT': '86400', 21:50:52 INFO - 'USER': 'cltbld', 21:50:52 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1474945679.223815-740405567', 21:50:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:50:52 INFO - '_': '/tools/buildbot/bin/python'} 21:50:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:50:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:50:53 INFO - Running setup.py (path:/tmp/pip-HbjFAZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:50:53 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 21:50:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:50:53 INFO - Running setup.py (path:/tmp/pip-TYXjTq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:50:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 21:50:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:50:53 INFO - Running setup.py (path:/tmp/pip-VjivGZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:50:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 21:50:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:50:53 INFO - Running setup.py (path:/tmp/pip-MyS0xL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:50:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 21:50:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:50:53 INFO - Running setup.py (path:/tmp/pip-VKLhDM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:50:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 21:50:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:50:53 INFO - Running setup.py (path:/tmp/pip-AjY_pp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:50:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 21:50:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:50:53 INFO - Running setup.py (path:/tmp/pip-G0EfzP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:50:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 21:50:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:50:53 INFO - Running setup.py (path:/tmp/pip-R9il6r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:50:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 21:50:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:50:53 INFO - Running setup.py (path:/tmp/pip-P8Kfgw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:50:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 21:50:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:50:53 INFO - Running setup.py (path:/tmp/pip-98GJMz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:50:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:50:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:50:53 INFO - Running setup.py (path:/tmp/pip-BotYgT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:50:53 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 21:50:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:50:53 INFO - Running setup.py (path:/tmp/pip-nk4bdt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:50:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 21:50:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:50:53 INFO - Running setup.py (path:/tmp/pip-2KG148-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:50:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 21:50:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:50:53 INFO - Running setup.py (path:/tmp/pip-KkMS1B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:50:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 21:50:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:50:54 INFO - Running setup.py (path:/tmp/pip-xTvX6K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:50:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 21:50:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:50:54 INFO - Running setup.py (path:/tmp/pip-L31UiI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:50:54 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 21:50:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:50:54 INFO - Running setup.py (path:/tmp/pip-A4s0lI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:50:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 21:50:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 21:50:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 21:50:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 21:50:54 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 21:50:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 21:50:54 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:50:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:50:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:50:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:50:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:50:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:50:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:50:56 INFO - Downloading blessings-1.6.tar.gz 21:50:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 21:50:56 INFO - Installing collected packages: blessings 21:50:56 INFO - Running setup.py install for blessings 21:50:56 INFO - Successfully installed blessings 21:50:56 INFO - Cleaning up... 21:50:56 INFO - Return code: 0 21:50:56 INFO - Done creating virtualenv /builds/slave/test/build/venv. 21:50:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:50:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:50:56 INFO - Reading from file tmpfile_stdout 21:50:56 INFO - Current package versions: 21:50:56 INFO - argparse == 1.2.1 21:50:56 INFO - blessings == 1.6 21:50:56 INFO - blobuploader == 1.2.4 21:50:56 INFO - docopt == 0.6.1 21:50:56 INFO - functools32 == 3.2.3-2 21:50:56 INFO - jsonschema == 2.5.1 21:50:56 INFO - manifestparser == 1.1 21:50:56 INFO - mozInstall == 1.12 21:50:56 INFO - mozcrash == 0.17 21:50:56 INFO - mozdebug == 0.1 21:50:56 INFO - mozdevice == 0.48 21:50:56 INFO - mozfile == 1.2 21:50:56 INFO - mozhttpd == 0.7 21:50:56 INFO - mozinfo == 0.9 21:50:56 INFO - mozleak == 0.1 21:50:56 INFO - mozlog == 3.4 21:50:56 INFO - moznetwork == 0.27 21:50:56 INFO - mozprocess == 0.23 21:50:56 INFO - mozprofile == 0.28 21:50:56 INFO - mozrunner == 6.12 21:50:56 INFO - mozscreenshot == 0.1 21:50:56 INFO - mozsystemmonitor == 0.3 21:50:56 INFO - moztest == 0.7 21:50:56 INFO - mozversion == 1.4 21:50:56 INFO - psutil == 3.1.1 21:50:56 INFO - requests == 1.2.3 21:50:56 INFO - wsgiref == 0.1.2 21:50:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:50:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:50:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:50:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:50:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:50:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:50:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:50:56 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 0x7fd900eb4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x255e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x262bca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2628f60>, '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 0x2629d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x262a1d0>, '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': '920470bf432669ad7e3a5387000001ca-1474945679.223815-740405567', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 21:50:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 21:50:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 21:50:56 INFO - Using env: {'DISPLAY': ':0', 21:50:56 INFO - 'HOME': '/home/cltbld', 21:50:56 INFO - 'LANG': 'en_US.UTF-8', 21:50:56 INFO - 'LANGUAGE': 'en_US:en', 21:50:56 INFO - 'LOGNAME': 'cltbld', 21:50:56 INFO - 'MAIL': '/var/mail/cltbld', 21:50:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:50:56 INFO - 'MOZ_NO_REMOTE': '1', 21:50:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:50:56 INFO - 'NO_EM_RESTART': '1', 21:50:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:50:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:50:56 INFO - 'PWD': '/builds/slave/test', 21:50:56 INFO - 'SHELL': '/bin/bash', 21:50:56 INFO - 'SHLVL': '1', 21:50:56 INFO - 'TERM': 'linux', 21:50:56 INFO - 'TMOUT': '86400', 21:50:56 INFO - 'USER': 'cltbld', 21:50:56 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1474945679.223815-740405567', 21:50:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:50:56 INFO - '_': '/tools/buildbot/bin/python'} 21:50:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:50:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 21:50:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 21:50:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 21:50:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 21:50:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 21:50:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 21:50:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 21:50:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 21:50:56 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 21:50:56 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 21:50:56 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 21:50:56 INFO - Cleaning up... 21:50:56 INFO - Return code: 0 21:50:56 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 21:50:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:50:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:50:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:50:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:50:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:50:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:50:56 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 0x7fd900eb4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x255e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x262bca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2628f60>, '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 0x2629d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x262a1d0>, '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': '920470bf432669ad7e3a5387000001ca-1474945679.223815-740405567', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 21:50:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 21:50:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 21:50:56 INFO - Using env: {'DISPLAY': ':0', 21:50:56 INFO - 'HOME': '/home/cltbld', 21:50:56 INFO - 'LANG': 'en_US.UTF-8', 21:50:56 INFO - 'LANGUAGE': 'en_US:en', 21:50:56 INFO - 'LOGNAME': 'cltbld', 21:50:56 INFO - 'MAIL': '/var/mail/cltbld', 21:50:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:50:56 INFO - 'MOZ_NO_REMOTE': '1', 21:50:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:50:56 INFO - 'NO_EM_RESTART': '1', 21:50:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:50:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:50:56 INFO - 'PWD': '/builds/slave/test', 21:50:56 INFO - 'SHELL': '/bin/bash', 21:50:56 INFO - 'SHLVL': '1', 21:50:56 INFO - 'TERM': 'linux', 21:50:56 INFO - 'TMOUT': '86400', 21:50:56 INFO - 'USER': 'cltbld', 21:50:56 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1474945679.223815-740405567', 21:50:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:50:56 INFO - '_': '/tools/buildbot/bin/python'} 21:50:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:50:57 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 21:50:57 INFO - Cleaning up... 21:50:57 INFO - Return code: 0 21:50:57 INFO - Running post-action listener: _resource_record_post_action 21:50:57 INFO - Running post-action listener: _start_resource_monitoring 21:50:57 INFO - Starting resource monitoring. 21:50:57 INFO - [mozharness: 2016-09-27 04:50:57.108260Z] Finished create-virtualenv step (success) 21:50:57 INFO - [mozharness: 2016-09-27 04:50:57.108762Z] Running install step. 21:50:57 INFO - Running pre-action listener: _resource_record_pre_action 21:50:57 INFO - Running main action method: install 21:50:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:50:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:50:57 INFO - Reading from file tmpfile_stdout 21:50:57 INFO - Detecting whether we're running mozinstall >=1.0... 21:50:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 21:50:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 21:50:57 INFO - Reading from file tmpfile_stdout 21:50:57 INFO - Output received: 21:50:57 INFO - Usage: mozinstall [options] installer 21:50:57 INFO - Options: 21:50:57 INFO - -h, --help show this help message and exit 21:50:57 INFO - -d DEST, --destination=DEST 21:50:57 INFO - Directory to install application into. [default: 21:50:57 INFO - "/builds/slave/test"] 21:50:57 INFO - --app=APP Application being installed. [default: firefox] 21:50:57 INFO - mkdir: /builds/slave/test/build/application 21:50:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 21:50:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 21:51:13 INFO - Reading from file tmpfile_stdout 21:51:13 INFO - Output received: 21:51:13 INFO - /builds/slave/test/build/application/firefox/firefox 21:51:13 INFO - Running post-action listener: _resource_record_post_action 21:51:13 INFO - [mozharness: 2016-09-27 04:51:13.480681Z] Finished install step (success) 21:51:13 INFO - [mozharness: 2016-09-27 04:51:13.480925Z] Running run-tests step. 21:51:13 INFO - Running pre-action listener: _resource_record_pre_action 21:51:13 INFO - Running main action method: run_tests 21:51:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 21:51:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 21:51:13 INFO - Python 2.7.3 21:51:13 INFO - Return code: 0 21:51:13 INFO - grabbing minidump binary from tooltool 21:51:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:51:13 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 0x2628f60>, '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 0x2629d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x262a1d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 21:51:13 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 21:51:13 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 21:51:13 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 21:51:13 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:51:13 INFO - Return code: 0 21:51:13 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 21:51:13 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 21:51:13 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 21:51:13 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 21:51:13 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 21:51:13 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-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/GkGR1c4-TUiBQh_LwqpQSw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-053', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 21:51:13 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-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/GkGR1c4-TUiBQh_LwqpQSw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-053 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 21:51:13 INFO - Using env: {'DISPLAY': ':0', 21:51:13 INFO - 'HOME': '/home/cltbld', 21:51:13 INFO - 'LANG': 'en_US.UTF-8', 21:51:13 INFO - 'LANGUAGE': 'en_US:en', 21:51:13 INFO - 'LOGNAME': 'cltbld', 21:51:13 INFO - 'MAIL': '/var/mail/cltbld', 21:51:13 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:51:13 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 21:51:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:51:13 INFO - 'MOZ_NO_REMOTE': '1', 21:51:13 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:51:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:51:13 INFO - 'NO_EM_RESTART': '1', 21:51:13 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:51:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:51:13 INFO - 'PWD': '/builds/slave/test', 21:51:13 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:51:13 INFO - 'SHELL': '/bin/bash', 21:51:13 INFO - 'SHLVL': '1', 21:51:13 INFO - 'TERM': 'linux', 21:51:13 INFO - 'TMOUT': '86400', 21:51:13 INFO - 'USER': 'cltbld', 21:51:13 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1474945679.223815-740405567', 21:51:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:51:13 INFO - '_': '/tools/buildbot/bin/python'} 21:51:13 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-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/GkGR1c4-TUiBQh_LwqpQSw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-053', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 21:51:13 INFO - mozversion application_buildid: 20160926193551 21:51:13 INFO - mozversion application_changeset: 7b050ca8ec6474c6d7131d0ebd352e8624992016 21:51:13 INFO - mozversion application_display_name: Nightly 21:51:13 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:51:13 INFO - mozversion application_name: Firefox 21:51:13 INFO - mozversion application_remotingname: firefox 21:51:13 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 21:51:13 INFO - mozversion application_vendor: Mozilla 21:51:13 INFO - mozversion application_version: 52.0a1 21:51:13 INFO - mozversion platform_buildid: 20160926193551 21:51:13 INFO - mozversion platform_changeset: 7b050ca8ec6474c6d7131d0ebd352e8624992016 21:51:13 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 21:51:13 INFO - mozversion platform_version: 52.0a1 21:51:13 INFO - using testdate: 1474951873 21:51:13 INFO - actual date: 1474951873 21:51:13 INFO - starting webserver on 'localhost:58966' 21:51:13 INFO - SUITE-START | Running 2 tests 21:51:13 INFO - TEST-START | dromaeo_css 21:51:13 INFO - operating with platform_type : linux_ 21:51:13 INFO - Initialising browser for dromaeo_css test... 21:51:13 INFO - TEST-INFO | started process 21177 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpbSM73w/profile http://localhost:58966/getInfo.html) 21:51:18 INFO - PROCESS | 21177 | __metrics Screen width/height:1600/1200 21:51:18 INFO - PROCESS | 21177 | colorDepth:24 21:51:18 INFO - PROCESS | 21177 | Browser inner width/height: 1024/697 21:51:18 INFO - PROCESS | 21177 | __metrics 21:51:19 ERROR - PROCESS | 21177 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3067: TypeError: this.tabs is undefined 21:51:20 INFO - TEST-INFO | 21177: exit 0 21:51:20 INFO - Browser initialized. 21:51:20 INFO - Running cycle 1/1 for dromaeo_css test... 21:51:20 INFO - TEST-INFO | started process 21298 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpbSM73w/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:51:32 INFO - PROCESS | 21298 | RSS: Main: 186634240 21:51:32 INFO - PROCESS | 21298 | 21:52:22 INFO - PROCESS | 21298 | 1474951942319 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 21:54:35 INFO - PROCESS | 21298 | Cycle 1(1): loaded http://localhost:58966/tests/dromaeo/cssquery-dojo.html (next: http://localhost:58966/tests/dromaeo/cssquery-ext.html) 21:54:36 INFO - PROCESS | 21298 | RSS: Main: 200429568 21:54:36 INFO - PROCESS | 21298 | 21:57:38 INFO - PROCESS | 21298 | Cycle 1(1): loaded http://localhost:58966/tests/dromaeo/cssquery-ext.html (next: http://localhost:58966/tests/dromaeo/cssquery-jquery.html) 21:57:39 INFO - PROCESS | 21298 | RSS: Main: 196829184 21:57:39 INFO - PROCESS | 21298 | 22:00:41 INFO - PROCESS | 21298 | Cycle 1(1): loaded http://localhost:58966/tests/dromaeo/cssquery-jquery.html (next: http://localhost:58966/tests/dromaeo/cssquery-mootools.html) 22:00:41 INFO - PROCESS | 21298 | RSS: Main: 199266304 22:00:41 INFO - PROCESS | 21298 | 22:03:43 INFO - PROCESS | 21298 | Cycle 1(1): loaded http://localhost:58966/tests/dromaeo/cssquery-mootools.html (next: http://localhost:58966/tests/dromaeo/cssquery-prototype.html) 22:03:44 INFO - PROCESS | 21298 | RSS: Main: 195563520 22:03:44 INFO - PROCESS | 21298 | 22:06:47 INFO - PROCESS | 21298 | Cycle 1(1): loaded http://localhost:58966/tests/dromaeo/cssquery-prototype.html (next: http://localhost:58966/tests/dromaeo/cssquery-yui.html) 22:06:47 INFO - PROCESS | 21298 | RSS: Main: 198897664 22:06:47 INFO - PROCESS | 21298 | 22:09:50 INFO - PROCESS | 21298 | Cycle 1(1): loaded http://localhost:58966/tests/dromaeo/cssquery-yui.html (next: http://localhost:58966/tests/dromaeo/cssquery-dojo.html) 22:09:50 INFO - PROCESS | 21298 | RSS: Main: 199430144 22:09:50 INFO - PROCESS | 21298 | 22:09:50 INFO - PROCESS | 21298 | __start_tp_report 22:09:50 INFO - PROCESS | 21298 | _x_x_mozilla_page_load 22:09:50 INFO - PROCESS | 21298 | _x_x_mozilla_page_load_details 22:09:50 INFO - PROCESS | 21298 | |i|pagename|runs| 22:09:50 INFO - PROCESS | 21298 | |0;dojo.html;2592.2741632342927;2597.2467984284544;2601.1546247469573;2616;2636.0378461861415;4531.56950089741;4650.534946505352;4672.252439609663;4702.459217190019;4705.882352941178;4565.429321334833;4585.495595484494;4643.953560464394;4666.8366607168755;4678.789454474549;4271.081717430752;4276.722013069156;4369.388285640013;4380.364847097169;4426.070525189714;4307.461567304087;4340.066885619591;4356.520782713915;4404.69167158299;4446.1107778444275;4184.8744537663915;4218.11419601884;4222.746635201867;4343.913121737579;4370.994671225636;4206.66346692267;4225.471816022997;4233.343831706091;4237.618614324695;4325.07010992635;4222.282212023963;4249.150169965988;4272.6154646082;4279.97860010698;4298.118885628439;4614.746188959606;4628.190066738307;4660.254359302529;4672.602828759559;4692.483826779052;4978.079055374731;4988.900221995576;4989.476105008958;4993.525615066563;5019.121653710586;5001.224810154432;5009.574186194178;5015.122353588144;5021.322121513622;5022.347094877642;4552.772361381918;4595.586397224266;4651.651126165551;4684.531546845288;4720.362751028601;5128.230765385222;5139.049275883974;5149.6910185389;5210.53105220532;5216.504432078947;4908.214685650279;4908.901821963612;4913.091078150554;4931.901361972812;4983.103041452573;4811.78346974387;4929.433115191799;4932.950670493321;5022.748862556858;5032.295478632995;4920.3111564381015;4932.21084626458;4936.975315123474;4948.826791062295;4997.800087996512;4925.261210818406;4931.260310953386;4935.383077115361;4939.086269040239;4993.525615066563;4013;4057.127717540743;4122.42286079949;4146.0464093258715;4200.537940826506;3638.1086633774366;3651.835667394974;3705.481232627434;3706.240220754739;3730.0301921500063;4344.239758042393;4384;4395.978020109943;4396.560343965578;4441.489228738672;4906.582940450102;4954.826581069718;5024.974875125601;5053.418856831511;5111.667741596784;4316.287812510901;4326.891827704332;4347.174036932973;4347.891302717458;4395.692301538862;4422.800973956179;4430.601245887886;4435.445569303837;4437.8224871004795;4482.843100491467;4562.520935301745;4680.344751734692;4686.8125274988615;4688.765561721969;4732.337472753748;4819.132556139928;5009.499050094962;5041;5053.140966035856;5120.692758434506;4770.570648641639;4774.40319960005;4776.522347765195;4779.976100119477;4831.782569784298;4791.209450440639;4826.251930950708;4848.12733707936;4967.453580106113;4976.576990955809;4892.412910450768;4931.087748766489;4947.183714687037;4993.126202914548;5007.874803129951;4784.091022705814;4819.518048195152;4820.373351464427;4825.831095911626;4860.635452341018;4729.43246810385;4790.473047964651;4813.253945638432;4822.445418776745;4878.975605121952;4774.976125119352;4795.95204047969;4797.064572408347;4832.589229915356;4881.194602890625;4918.655694101379;4921.384826896638;4928.605711542997;4943.901121977612;4981.900361992667;4604.58558729716;4766.713997160182;4767.928481072856;4774.307725379731;4837.564619184291 22:09:50 INFO - PROCESS | 21298 | |1;ext.html;547.808267106513;548.7448336523516;550.609067562031;558.9133684278938;562.6708375600274;36880.81559592202;38848.61151388489;38917.80541097294;41628.37557436641;41738.373924391155;38955.41566876499;39009.60990390095;39457;39468.21063578727;39482.40776388349;7342.743003994848;7478.813029674247;7480.214577469369;7483;7485.6631451584535;7574.053243344582;7693.115291741466;7707.730229441971;7719.65261563231;7732.536047837119;7650.923490765077;7718.614069296541;7730.5361678299205;7742.380609551223;7753.224677532258;7698.307152356315;7699.26856948589;7716.035495563055;7746.535207887517;7765.728199513046;7678.961605191994;7764.417668674872;7767.145613982458;7768.922310776877;7769.72805951789;37441.06397340061;37651.87044388673;37883.62116378829;37931.43102853459;37954.24091518182;2929.633795775528;2961.333699917523;2971.5691224772286;3021.546767984798;3034.620672415948;27522.76147573364;28296.292592685142;28651.283717907012;28749.425011499654;29203.56194657082;3257.1857035741064;3275.148461400029;3280.0979730574045;3298.818564978925;3318.2699806042874;236484;237379;237812;237852;238728;48230.75884620625;48449.5155048445;48511;48545.51454485481;48591.51408485941;32192.517112242866;33011.504827427605;33169.66830331714;34823.65176348255;51758.482415175364;13853.099548529313;14029.158250505003;14031.22828244436;14054.437822486985;14058.437662493387;9453.952730236304;9582.137607615348;9647.517624118766;9671.661491847892;9695.51522423876;17351.913240433718;17367.56581085483;17386.913065434845;17419.825801742074;17490.562735931704;6626.7017984190825;6885.449164066963;6978.720851165998;7041.084658994298;7088.503804733619;777.4208214879837;917.9489484540164;922.2299380017761;955.4362925873768;1034.5086084110137;26746.465070698476;27486.862565687043;27576.724232757817;27624;27971.30071748182;945.6784693204344;952.2857856607544;953.814006268771;963.2246041936309;996.2229461020415;13661.248631325174;14543.854561454462;14665.560033198808;14808.777868331983;14919.40322387114;358171;362527;363079;363429;364190;47733.76133119312;48202;48266.75866620715;48291.75854120707;48380;1147.2198904744687;1153.6020073074776;1170.584442522909;1170.7073231692077;1171.5021116025655;25326.74673253244;26282.73717262803;27228.455430891667;27533;27609.723902760714;40954.79522602368;41145.17709645731;41152.58847411607;41241.1751764957;41248.587514124476;309524;311011;312034;312127;313045;24434.755652443248;24718.752812471645;24745.381365466008;24787.13245036415;24824.875875620506;38923.69148622399;39782.2043559133;39938;40587.391189131595;41221.79389103036;33997.830010850776;34149.31701366008;34157.487637685954;34600.30799383948;34694;34414.82792586021;34478.48282275718;34697.826510867286;35208.64791352054;35346.64653353434;33864.66135338615;34222.14444638805;34246.828765856015;34441;35181.648183517835;29991.400171996873;32194.19514512156;32425.35149297048;32515;32520;24054.39864003414;24565;24783.87608061948;24833.751662483144;25334.239972800107 22:09:50 INFO - PROCESS | 21298 | |2;jquery.html;360.5673192169397;364.7756629672751;369.64698712729336;378.4512456937441;387.46916724088004;15518.922405387972;15611.609709757262;17009.829901700996;17094.572635684115;17135.228914698837;13559.389827457764;15740.527784166494;16573;16677.66644667106;16977.49067527973;4900.852974410774;5075.4163271223915;5082.720450375228;5084.644074914758;5102.948970510284;4939.382577177853;5015.07221164084;5015.247712843066;5015.749212539358;5022.17134172861;5018.272350509174;5309;5315.441878602749;5387.676739395649;5395.487428694268;5141.331626888518;5349.277847490597;5374;5379.757910894019;5392.083345831198;5096.592272618182;5115.769790359443;5129.051125541787;5131.384233891957;5134.717590532519;11816.940915295454;11883.346415947119;11984;12035.338056406894;12164.695882602917;1754.929802807886;1806.8554515638718;1820.8543316534644;1888.7544619199552;2240.9103635854544;8527.573621318908;8738.388312818106;9117.5441227939;9223.216026637743;9329.486878221694;1687.873409494293;1733.9479815605553;1763.7618921445696;1781.4477511971331;1783.2242974306089;67942.3205767941;69289.65355173242;69787.65106174488;70487.64756176186;70800;8670.393072484865;8843.955780221058;8967.282617390592;9396.154346108882;9606;12753.872461275454;12755.107142500121;12757.42591583381;12777;12777.361131943366;6405.711742971577;6456.354364563506;6631.204255489372;6784.59292442455;6824.965875170592;6266.404691554295;6307.810765676945;6451.806445806634;6458.515611329169;6517.478601711851;4014.538328092307;4033.253848038122;4094.6928980326593;4137.8758637240935;4222.3244280914905;1683.8737094717944;1707.9146042697816;1733.3846484498074;1744.4156207670574;1774.2636805725479;781.3046388713935;834.4159088637978;854.7521218846589;881.3169793410159;904.213334399077;15946.122963237134;16620.418285359956;16895.662086758195;17294.827051729575;17661.205245763973;810.4529442626322;842.3429724814654;844.1642773654038;848.3552500099969;891.6656253904786;7579.090509138829;9323.673671421468;9675.854862177073;9725.854112188323;9827.262955278382;337497;343976;344336;347353;351042;71717.28282717211;73053.26946730571;73211;73993;77891;823.7693445835175;841.8821365008904;868.2749903830246;883.65095787165;890.5369208011798;14937.178455185069;15027.398904044154;15194.772078419053;15460.84539154639;15470.690586188439;32752.508712368857;34424.655753442144;36009.639903601674;36121.63878361183;36345.81827090848;48428;50042.24936625881;50319.74840125776;50523;50697;7418.554886706815;7556.433267504849;7606.20134885829;7693.1922148173635;7784.338331241908;10310.690679279634;12006.819897701367;12020.278783273032;12024.519019239133;12063.939680301542;4949.82675606352;5244.816431424882;9006.909930900607;10518.789624207626;10626.574937002433;10175;10278.33190842593;10324.174066074545;10347.741306467398;10453.634122805664;10362.689119326433;10454.947725261325;10497.895021049691;10516.947415262875;10710.143188545053;6419;6758;6853.897191542031;7676;7900.802479938047;9752.902470975483;10552.947235263775;10773.19201059908;10812.837807433052;10945.562177512811 22:09:50 INFO - PROCESS | 21298 | |3;mootools.html;1332.900032497563;1370.9108907920986;1417.7022825206707;1602.8958117722354;1627.5198816349175;6885.242623311432;7103.289671032894;7211.891821622673;7784.987951566289;7805.60971951403;7152.642367881611;7436.6653500592465;7436.962815185916;7580.6209689515445;7640.350570201525;2135.113927719998;2166.2851259084464;2168.5229249565073;2173.2285038811233;2174.4889950861534;2122.4481634774993;2159.3090211132367;2180.967285490719;2191.7589065202815;2194.221051526709;2142.3787101740477;2159.773223811501;2167.501474660829;2167.967480487786;2200.0759680934048;2066.225165562914;2085.937421877346;2086.9478263043393;2092.163134746106;2092.9790702093087;2087.2590230468118;2142.1966762464076;2146.034284571949;2151.7310336207975;2153.7846215378495;7613.42899282556;7617.504862183996;7680.961595191989;7688.269614386624;7696.6536505857375;4458.487273963503;4519.231730605772;4592.5177856324935;4594.701344412602;4604.1252162089095;2716.361655011071;2804.775617950559;2811.704770999036;2851.0591504803574;2873.123697272331;908.6910450446813;953.2516974175239;954.370115723619;972.9173020293283;980.0052946259552;10293.691189264335;10313.948430257875;10328.896711032869;10374.066334029974;10413.219008574311;3931.4495970564144;4135.8966025848995;4238.046439551138;4258.190943720683;4304.5910638489295;8860.069692682304;8906.242969347548;8906.688265910661;8910.153535414125;8927.732168034969;4604.562566556172;4607.170709272363;4608.9308660370125;4641.048585040058;4661.300804879227;3662.2675464907124;3786.1859700164596;4101.7744024078365;4245.129748401632;4245.893852653647;3486;3486.320167567351;3641.308151451216;3668.816559172084;3670.100825297819;1202.6933132051272;1205.6744678936639;1211.0674780419204;1218.3177420644467;1222.1994593541244;739.4084732214208;769.4767558060572;770.6724642027116;771.714465647714;802.4022103532906;5079.187330027178;5122.846314610568;5175.301334319894;5361.490658387447;5547.696291371524;816.8407160603745;830.6760363458257;851.29342645604;878.6792820620427;920.8075542172768;3424;3495.143689795965;3517.82410879455;3588.69496092835;3590.2998915211806;9064.909350906539;9442.197413219816;9477.715668529956;9502.144806967406;9522.714318570454;9419.623215071457;9420.105090016437;9427.481488518197;9444.811103777885;9485.430874147572;6992.650367481708;7003.54476958996;7070.328318809705;7125.287471252833;7163.31948464895;4845.830395936126;4990.575801056805;5013.774380152829;5022.1211288023715;5177.663451875616;8394.580270986304;8469.74590762278;8479.703210387606;8541.743747687578;8590.570471476527;8521.446106003337;8585.570721463777;8601.612927418157;8683.913160868311;8748.431351962103;5992.490638295793;6164.198654175108;6343.143675603919;6490.350964903472;6496.447801936888;6448.774292899726;6622.271550129578;6658.83352916181;6770.424513916175;6773.288804675438;6394.23269207698;6419.967900160469;6487.54587178893;6557.245916719447;6706.832329191614;6319.146915166578;6394.584352017104;6448.967755161194;6456.12842266288;6484.675766211765;5844.912326315024;5940.940590594038;5945.227120474311;5949.3455719871645;5951.018082016419;4187.309093999456;4212.7472351659;4294.205571969195;4312.296642826129;4315.978420107879;4266.87199384019;4364.105358401456;4379.912401752011;4380.7152535085115;4480.61914737251 22:09:50 INFO - PROCESS | 21298 | |4;prototype.html;288.6665900884479;300.35873410269073;305.73095675805286;306.9800462969908;308.57878995171586;4808.663393562452;4886.584640305574;4926.8768280793;4995.325631039804;5009.398872135344;4707.4586422561415;4816.63875209359;4858.659893807435;4860.392450943632;4884.853454396374;3878.4957955465893;3883.533975922886;3894.240623078501;3897.6881849451975;3905.941410878839;3752.2233547175165;3817.8854634361014;3885.4560361549406;3886.7667939923554;3890.6498415142632;3828.5979972102946;3837.34765089934;3842.865499707511;3844.5578758442857;3845.1732877431346;3818.503594532721;3821.2548553032307;3821.7706937583835;3823.1589050408866;3842.405401758267;3365.6297807241185;3368.3936891359544;3370.3259348130473;3377.510261012164;3385.5611365169702;4715.316279139505;4806.182948898696;4822.951770482286;4871.147549178915;4875.098106850244;4338.175746608134;4349.390365780538;4351;4355.432044463991;4372.322290045048;5175.767090480937;5186.92219616706;5193.221016847466;5193.454687257859;5200.765965531522;3591.586967498745;3677.8161091945562;3682.705383569335;3694.833732482045;3699.611540788232;5389.757460914268;5433.483819037225;5434.619576629638;5445.292112025451;5458.042918883817;3698.1494256320657;3708.2212735325343;3750.643688849555;3751.718621103428;3753.9061523462133;3762.6049264827348;3769.660730534265;3769.8680546180753;3776.3013842438686;3790.65884070435;4195.643370313496;4271.594198551133;4301.3117901135665;4306.590873866978;4309.353596960481;5141.665791723601;5156.896862062813;5161.329027226511;5162.870928226825;5197.155009971481;1236.8701286364983;1264.5700461843016;1273.3251376770327;1301.3168086754529;1315.4869600855675;848.5545088828414;867.3841572483482;868.2576397180446;873.4453621950107;876.991230087691;4562.361269422284;4566.748828814447;4581.152486789954;4587.839425620087;4588.587027167504;5163.638545301868;5319.0957537218765;5330.466953304638;5331.493508116722;5339.932013597296;4538.364628951949;4553.180427523078;4575.565321294472;4577.908441831145;4581.198290299251;2482.3049546127113;2498.850068995866;2507.285423654286;2509.3475696319088;2511.899524019019;4417.425734654475;4417.9396944733035;4437.356583295404;4456.799444025027;4473.955260447354;4475.955917108229;4706.835260765857;4724.622030237641;4735.644826638016;4756.405449318835;4327.459067616611;4397.307036790859;4403.471583410012;4405.625521830681;4418.182636212311;4602.010567727954;4617.007343421181;4631.397918270603;4633.590532922929;4652.999210339586;4272.23099842019;4486.887827804331;4523.660725445539;4578.008566273682;4583.03756211186;4379.715318504287;4407.713498622579;4425.845095421645;4459.331100334975;4483.601273318759;3400.9457068308902;3409.931801363909;3410.4543273076188;3423.195549046022;3427.3830710472353;4499.392582001388;4665.766711664471;4714.646401519831;4717.551832575899;4727.1963766160525;4538.432695913011;4547.704399214041;4561.540307101695;4562.61217796491;4579.450465944108;4485.196752029981;4487.3973095603815;4500.819967201406;4508.80516663078;4534.659900507541;4428.468583769968;4489.34904438848;4752.809887604595;4883.559349991832;5046.495350464924;5230.424653288059;5230.895382092413;5234.633575649668;5238.76425560843;5239.214117882349;5085.618578606545;5088.389393272831;5088.898222035465;5097.974510127426;5100.668456550312 22:09:50 INFO - PROCESS | 21298 | |5;yui.html;600.6876424259385;670.3631550027475;673.2089794491471;691.1533371619767;692.3581958896204;12937.935310323459;13773.311334433276;13803.930980345085;13967.65080872976;14741.705165896676;12645.936770316159;12689.555865544708;12857.292848893307;12943.352832358369;12960.54638087667;745.3105877063708;764.7438108233748;766.5515673331108;766.5668897073164;767.2519293688665;612.3111499562991;629.3297638015522;736.5875109938424;755.6675062972274;761.9085709714842;817.199144838059;817.4849844597925;819.7868554175927;820.1675299570941;822.4078663362369;815.8572249856252;816.1593558634556;817.1950628630794;818.3780326951501;818.423011776696;599.868029033612;599.9250093738283;600.0788789208588;600.1447936690199;600.7326739600879;11976.341301228429;12014.63956081319;12030.157888947777;12041.157119001673;12052.758944821055;11837;12134.757304853942;12317.260964342167;12491.250524968531;12721.745565088646;9985.850212246822;10020.14828739558;10027.74930626733;10094.9495252524;10164.94917525415;3109.5491153782573;3140;3153.385089907469;3163.14595059335;3232.69289417505;50328;62513;63010.684946575435;63024;63891.680541596994;9112.772180695536;9358.745284891855;9528.714138575853;9543.761405964906;9609.279304052225;17224.913875430793;17394.391196308068;17399.217035233192;17519.824801752075;17586.296548138187;6213.130161777355;6349.14286571307;6361.4592759615025;6412.422881940648;6570.211574611078;3576.3204991051616;3585.46218067292;3597.226596281812;3611.2777444511203;3627.165751877083;2780.749732524042;2891.421715656877;2909.24359666484;2912.1409184290687;2912.475754364235;494.70812220789185;498.8852563910248;506.7668872318701;509.71455984648713;515.5540457504306;433.38892162051354;479.8344571122957;492.8151943021367;498.62603047714214;499.5029945204545;7588.734394296283;7684.807879803049;7798.25916537928;8054.436189466681;8147.959260203779;517.2516892902042;539.8002738986601;571.2031715756497;579.7101449275362;590.344717363726;3523.9647603523636;3797.1266608679607;3805.5243094613174;3869.1487872667967;3935.0752573145287;88011;91142;92724.53637731768;92806;92979.5351023254;46876.5312346879;48794.75602621964;76137.61931190308;80800.59599701964;82207.17792822115;58820.41179588235;70527;72591;72612.63693681498;73031.63484182651;6986.126734158231;7070.893936591058;7084.220735718964;7204.0994875640545;7346.77959661211;41278.79360603298;42391.15217695567;43327.566724332355;43377.56622433862;43968.34047489226;57217.427825721206;57825;61857.69071154615;62674.6866265684;62861.37138628555;8996.59515321799;9228.215601673934;9259.629614815332;9300.674476393293;9417.529123543933;9594.904050959402;9639.807203855744;9791.216702663913;9793.069658382443;9872.950635246778;8966.148215919477;9039;9047.366684332033;9062.184403403726;9142.131497507726;9208.815823683623;9247.306452015991;9371.237524751577;9447.433154010781;9648.276379271723;8895.733128005912;8943.418677785923;9060.90939090627;9076.137766912132;9211.76970575741;8470.957645211734;8795.25240354577;8796.692115775917;8856.911430885608;9333.626654933727;8024.759257222293;8546.2308392245;8584.385974498175;9082.772930676785;9242 22:09:50 INFO - PROCESS | 21298 | __end_tp_report 22:09:50 INFO - PROCESS | 21298 | __start_cc_report 22:09:50 INFO - PROCESS | 21298 | _x_x_mozilla_cycle_collect,338 22:09:50 INFO - PROCESS | 21298 | __end_cc_report 22:09:50 INFO - PROCESS | 21298 | __startTimestamp1474952990899__endTimestamp 22:09:50 INFO - PROCESS | 21298 | 22:09:50 INFO - PROCESS | 21298 | ------- Summary: start ------- 22:09:50 INFO - PROCESS | 21298 | Number of tests: 6 22:09:50 INFO - PROCESS | 21298 | 22:09:50 INFO - PROCESS | 21298 | [#0] dojo.html Cycles:165 Average:4607.37 Median:4766.71 stddev:484.57 (10.2%) stddev-sans-first:459.55 22:09:50 INFO - PROCESS | 21298 | Values: 2592.3 2597.2 2601.2 2616.0 2636.0 4531.6 4650.5 4672.3 4702.5 4705.9 4565.4 4585.5 4644.0 4666.8 4678.8 4271.1 4276.7 4369.4 4380.4 4426.1 4307.5 4340.1 4356.5 4404.7 4446.1 4184.9 4218.1 4222.7 4343.9 4371.0 4206.7 4225.5 4233.3 4237.6 4325.1 4222.3 4249.2 4272.6 4280.0 4298.1 4614.7 4628.2 4660.3 4672.6 4692.5 4978.1 4988.9 4989.5 4993.5 5019.1 5001.2 5009.6 5015.1 5021.3 5022.3 4552.8 4595.6 4651.7 4684.5 4720.4 5128.2 5139.0 5149.7 5210.5 5216.5 4908.2 4908.9 4913.1 4931.9 4983.1 4811.8 4929.4 4933.0 5022.7 5032.3 4920.3 4932.2 4937.0 4948.8 4997.8 4925.3 4931.3 4935.4 4939.1 4993.5 4013.0 4057.1 4122.4 4146.0 4200.5 3638.1 3651.8 3705.5 3706.2 3730.0 4344.2 4384.0 4396.0 4396.6 4441.5 4906.6 4954.8 5025.0 5053.4 5111.7 4316.3 4326.9 4347.2 4347.9 4395.7 4422.8 4430.6 4435.4 4437.8 4482.8 4562.5 4680.3 4686.8 4688.8 4732.3 4819.1 5009.5 5041.0 5053.1 5120.7 4770.6 4774.4 4776.5 4780.0 4831.8 4791.2 4826.3 4848.1 4967.5 4976.6 4892.4 4931.1 4947.2 4993.1 5007.9 4784.1 4819.5 4820.4 4825.8 4860.6 4729.4 4790.5 4813.3 4822.4 4879.0 4775.0 4796.0 4797.1 4832.6 4881.2 4918.7 4921.4 4928.6 4943.9 4981.9 4604.6 4766.7 4767.9 4774.3 4837.6 22:09:50 INFO - PROCESS | 21298 | 22:09:50 INFO - PROCESS | 21298 | [#1] ext.html Cycles:180 Average:45036.47 Median:360349.00 stddev:81016.49 (22.5%) stddev-sans-first:81174.90 22:09:50 INFO - PROCESS | 21298 | Values: 547.8 548.7 550.6 558.9 562.7 36880.8 38848.6 38917.8 41628.4 41738.4 38955.4 39009.6 39457.0 39468.2 39482.4 7342.7 7478.8 7480.2 7483.0 7485.7 7574.1 7693.1 7707.7 7719.7 7732.5 7650.9 7718.6 7730.5 7742.4 7753.2 7698.3 7699.3 7716.0 7746.5 7765.7 7679.0 7764.4 7767.1 7768.9 7769.7 37441.1 37651.9 37883.6 37931.4 37954.2 2929.6 2961.3 2971.6 3021.5 3034.6 27522.8 28296.3 28651.3 28749.4 29203.6 3257.2 3275.1 3280.1 3298.8 3318.3 236484.0 237379.0 237812.0 237852.0 238728.0 48230.8 48449.5 48511.0 48545.5 48591.5 32192.5 33011.5 33169.7 34823.7 51758.5 13853.1 14029.2 14031.2 14054.4 14058.4 9454.0 9582.1 9647.5 9671.7 9695.5 17351.9 17367.6 17386.9 17419.8 17490.6 6626.7 6885.4 6978.7 7041.1 7088.5 777.4 917.9 922.2 955.4 1034.5 26746.5 27486.9 27576.7 27624.0 27971.3 945.7 952.3 953.8 963.2 996.2 13661.2 14543.9 14665.6 14808.8 14919.4 358171.0 362527.0 363079.0 363429.0 364190.0 47733.8 48202.0 48266.8 48291.8 48380.0 1147.2 1153.6 1170.6 1170.7 1171.5 25326.7 26282.7 27228.5 27533.0 27609.7 40954.8 41145.2 41152.6 41241.2 41248.6 309524.0 311011.0 312034.0 312127.0 313045.0 24434.8 24718.8 24745.4 24787.1 24824.9 38923.7 39782.2 39938.0 40587.4 41221.8 33997.8 34149.3 34157.5 34600.3 34694.0 34414.8 34478.5 34697.8 35208.6 35346.6 33864.7 34222.1 34246.8 34441.0 35181.6 29991.4 32194.2 32425.4 32515.0 32520.0 24054.4 24565.0 24783.9 24833.8 25334.2 22:09:50 INFO - PROCESS | 21298 | 22:09:50 INFO - PROCESS | 21298 | [#2] jquery.html Cycles:180 Average:22423.35 Median:27528.66 stddev:57263.83 (208.0%) stddev-sans-first:57400.51 22:09:50 INFO - PROCESS | 21298 | Values: 360.6 364.8 369.6 378.5 387.5 15518.9 15611.6 17009.8 17094.6 17135.2 13559.4 15740.5 16573.0 16677.7 16977.5 4900.9 5075.4 5082.7 5084.6 5102.9 4939.4 5015.1 5015.2 5015.7 5022.2 5018.3 5309.0 5315.4 5387.7 5395.5 5141.3 5349.3 5374.0 5379.8 5392.1 5096.6 5115.8 5129.1 5131.4 5134.7 11816.9 11883.3 11984.0 12035.3 12164.7 1754.9 1806.9 1820.9 1888.8 2240.9 8527.6 8738.4 9117.5 9223.2 9329.5 1687.9 1733.9 1763.8 1781.4 1783.2 67942.3 69289.7 69787.7 70487.6 70800.0 8670.4 8844.0 8967.3 9396.2 9606.0 12753.9 12755.1 12757.4 12777.0 12777.4 6405.7 6456.4 6631.2 6784.6 6825.0 6266.4 6307.8 6451.8 6458.5 6517.5 4014.5 4033.3 4094.7 4137.9 4222.3 1683.9 1707.9 1733.4 1744.4 1774.3 781.3 834.4 854.8 881.3 904.2 15946.1 16620.4 16895.7 17294.8 17661.2 810.5 842.3 844.2 848.4 891.7 7579.1 9323.7 9675.9 9725.9 9827.3 337497.0 343976.0 344336.0 347353.0 351042.0 71717.3 73053.3 73211.0 73993.0 77891.0 823.8 841.9 868.3 883.7 890.5 14937.2 15027.4 15194.8 15460.8 15470.7 32752.5 34424.7 36009.6 36121.6 36345.8 48428.0 50042.2 50319.7 50523.0 50697.0 7418.6 7556.4 7606.2 7693.2 7784.3 10310.7 12006.8 12020.3 12024.5 12063.9 4949.8 5244.8 9006.9 10518.8 10626.6 10175.0 10278.3 10324.2 10347.7 10453.6 10362.7 10454.9 10497.9 10516.9 10710.1 6419.0 6758.0 6853.9 7676.0 7900.8 9752.9 10552.9 10773.2 10812.8 10945.6 22:09:50 INFO - PROCESS | 21298 | 22:09:50 INFO - PROCESS | 21298 | [#3] mootools.html Cycles:180 Average:4965.06 Median:5149.07 stddev:2757.02 (53.5%) stddev-sans-first:2751.24 22:09:50 INFO - PROCESS | 21298 | Values: 1332.9 1370.9 1417.7 1602.9 1627.5 6885.2 7103.3 7211.9 7785.0 7805.6 7152.6 7436.7 7437.0 7580.6 7640.4 2135.1 2166.3 2168.5 2173.2 2174.5 2122.4 2159.3 2181.0 2191.8 2194.2 2142.4 2159.8 2167.5 2168.0 2200.1 2066.2 2085.9 2086.9 2092.2 2093.0 2087.3 2142.2 2146.0 2151.7 2153.8 7613.4 7617.5 7681.0 7688.3 7696.7 4458.5 4519.2 4592.5 4594.7 4604.1 2716.4 2804.8 2811.7 2851.1 2873.1 908.7 953.3 954.4 972.9 980.0 10293.7 10313.9 10328.9 10374.1 10413.2 3931.4 4135.9 4238.0 4258.2 4304.6 8860.1 8906.2 8906.7 8910.2 8927.7 4604.6 4607.2 4608.9 4641.0 4661.3 3662.3 3786.2 4101.8 4245.1 4245.9 3486.0 3486.3 3641.3 3668.8 3670.1 1202.7 1205.7 1211.1 1218.3 1222.2 739.4 769.5 770.7 771.7 802.4 5079.2 5122.8 5175.3 5361.5 5547.7 816.8 830.7 851.3 878.7 920.8 3424.0 3495.1 3517.8 3588.7 3590.3 9064.9 9442.2 9477.7 9502.1 9522.7 9419.6 9420.1 9427.5 9444.8 9485.4 6992.7 7003.5 7070.3 7125.3 7163.3 4845.8 4990.6 5013.8 5022.1 5177.7 8394.6 8469.7 8479.7 8541.7 8590.6 8521.4 8585.6 8601.6 8683.9 8748.4 5992.5 6164.2 6343.1 6490.4 6496.4 6448.8 6622.3 6658.8 6770.4 6773.3 6394.2 6420.0 6487.5 6557.2 6706.8 6319.1 6394.6 6449.0 6456.1 6484.7 5844.9 5940.9 5945.2 5949.3 5951.0 4187.3 4212.7 4294.2 4312.3 4316.0 4266.9 4364.1 4379.9 4380.7 4480.6 22:09:50 INFO - PROCESS | 21298 | 22:09:50 INFO - PROCESS | 21298 | [#4] prototype.html Cycles:180 Average:4099.26 Median:4488.37 stddev:1174.68 (26.2%) stddev-sans-first:1142.63 22:09:50 INFO - PROCESS | 21298 | Values: 288.7 300.4 305.7 307.0 308.6 4808.7 4886.6 4926.9 4995.3 5009.4 4707.5 4816.6 4858.7 4860.4 4884.9 3878.5 3883.5 3894.2 3897.7 3905.9 3752.2 3817.9 3885.5 3886.8 3890.6 3828.6 3837.3 3842.9 3844.6 3845.2 3818.5 3821.3 3821.8 3823.2 3842.4 3365.6 3368.4 3370.3 3377.5 3385.6 4715.3 4806.2 4823.0 4871.1 4875.1 4338.2 4349.4 4351.0 4355.4 4372.3 5175.8 5186.9 5193.2 5193.5 5200.8 3591.6 3677.8 3682.7 3694.8 3699.6 5389.8 5433.5 5434.6 5445.3 5458.0 3698.1 3708.2 3750.6 3751.7 3753.9 3762.6 3769.7 3769.9 3776.3 3790.7 4195.6 4271.6 4301.3 4306.6 4309.4 5141.7 5156.9 5161.3 5162.9 5197.2 1236.9 1264.6 1273.3 1301.3 1315.5 848.6 867.4 868.3 873.4 877.0 4562.4 4566.7 4581.2 4587.8 4588.6 5163.6 5319.1 5330.5 5331.5 5339.9 4538.4 4553.2 4575.6 4577.9 4581.2 2482.3 2498.9 2507.3 2509.3 2511.9 4417.4 4417.9 4437.4 4456.8 4474.0 4476.0 4706.8 4724.6 4735.6 4756.4 4327.5 4397.3 4403.5 4405.6 4418.2 4602.0 4617.0 4631.4 4633.6 4653.0 4272.2 4486.9 4523.7 4578.0 4583.0 4379.7 4407.7 4425.8 4459.3 4483.6 3400.9 3409.9 3410.5 3423.2 3427.4 4499.4 4665.8 4714.6 4717.6 4727.2 4538.4 4547.7 4561.5 4562.6 4579.5 4485.2 4487.4 4500.8 4508.8 4534.7 4428.5 4489.3 4752.8 4883.6 5046.5 5230.4 5230.9 5234.6 5238.8 5239.2 5085.6 5088.4 5088.9 5098.0 5100.7 22:09:50 INFO - PROCESS | 21298 | 22:09:50 INFO - PROCESS | 21298 | [#5] yui.html Cycles:180 Average:16237.04 Median:6355.30 stddev:23408.41 (368.3%) stddev-sans-first:23444.63 22:09:50 INFO - PROCESS | 21298 | Values: 600.7 670.4 673.2 691.2 692.4 12937.9 13773.3 13803.9 13967.7 14741.7 12645.9 12689.6 12857.3 12943.4 12960.5 745.3 764.7 766.6 766.6 767.3 612.3 629.3 736.6 755.7 761.9 817.2 817.5 819.8 820.2 822.4 815.9 816.2 817.2 818.4 818.4 599.9 599.9 600.1 600.1 600.7 11976.3 12014.6 12030.2 12041.2 12052.8 11837.0 12134.8 12317.3 12491.3 12721.7 9985.9 10020.1 10027.7 10094.9 10164.9 3109.5 3140.0 3153.4 3163.1 3232.7 50328.0 62513.0 63010.7 63024.0 63891.7 9112.8 9358.7 9528.7 9543.8 9609.3 17224.9 17394.4 17399.2 17519.8 17586.3 6213.1 6349.1 6361.5 6412.4 6570.2 3576.3 3585.5 3597.2 3611.3 3627.2 2780.7 2891.4 2909.2 2912.1 2912.5 494.7 498.9 506.8 509.7 515.6 433.4 479.8 492.8 498.6 499.5 7588.7 7684.8 7798.3 8054.4 8148.0 517.3 539.8 571.2 579.7 590.3 3524.0 3797.1 3805.5 3869.1 3935.1 88011.0 91142.0 92724.5 92806.0 92979.5 46876.5 48794.8 76137.6 80800.6 82207.2 58820.4 70527.0 72591.0 72612.6 73031.6 6986.1 7070.9 7084.2 7204.1 7346.8 41278.8 42391.2 43327.6 43377.6 43968.3 57217.4 57825.0 61857.7 62674.7 62861.4 8996.6 9228.2 9259.6 9300.7 9417.5 9594.9 9639.8 9791.2 9793.1 9873.0 8966.1 9039.0 9047.4 9062.2 9142.1 9208.8 9247.3 9371.2 9447.4 9648.3 8895.7 8943.4 9060.9 9076.1 9211.8 8471.0 8795.3 8796.7 8856.9 9333.6 8024.8 8546.2 8584.4 9082.8 9242.0 22:09:50 INFO - PROCESS | 21298 | -------- Summary: end -------- 22:09:50 INFO - PROCESS | 21298 | 22:09:52 INFO - TEST-INFO | 21298: exit 0 22:09:52 INFO - TEST-OK | dromaeo_css | took 1118306ms 22:09:52 INFO - TEST-START | kraken 22:09:52 INFO - operating with platform_type : linux_ 22:09:52 INFO - Initialising browser for kraken test... 22:09:52 INFO - TEST-INFO | started process 21659 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpLoycta/profile http://localhost:58966/getInfo.html) 22:09:57 INFO - PROCESS | 21659 | __metrics Screen width/height:1600/1200 22:09:57 INFO - PROCESS | 21659 | colorDepth:24 22:09:57 INFO - PROCESS | 21659 | Browser inner width/height: 1024/697 22:09:57 INFO - PROCESS | 21659 | __metrics 22:09:57 ERROR - PROCESS | 21659 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3067: TypeError: this.tabs is undefined 22:09:58 INFO - TEST-INFO | 21659: exit 0 22:09:58 INFO - Browser initialized. 22:09:58 INFO - Running cycle 1/1 for kraken test... 22:09:58 INFO - TEST-INFO | started process 21779 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpLoycta/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:10:11 INFO - PROCESS | 21779 | RSS: Main: 186482688 22:10:11 INFO - PROCESS | 21779 | 22:10:14 INFO - PROCESS | 21779 | Cycle 1(1): loaded http://localhost:58966/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:58966/tests/kraken/driver.html?testName=audio-beat-detection) 22:10:15 INFO - PROCESS | 21779 | RSS: Main: 200011776 22:10:15 INFO - PROCESS | 21779 | 22:10:17 INFO - PROCESS | 21779 | Cycle 1(1): loaded http://localhost:58966/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:58966/tests/kraken/driver.html?testName=audio-dft) 22:10:18 INFO - PROCESS | 21779 | RSS: Main: 198078464 22:10:18 INFO - PROCESS | 21779 | 22:10:21 INFO - PROCESS | 21779 | Cycle 1(1): loaded http://localhost:58966/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:58966/tests/kraken/driver.html?testName=audio-fft) 22:10:22 INFO - PROCESS | 21779 | RSS: Main: 193650688 22:10:22 INFO - PROCESS | 21779 | 22:10:24 INFO - PROCESS | 21779 | Cycle 1(1): loaded http://localhost:58966/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:58966/tests/kraken/driver.html?testName=audio-oscillator) 22:10:24 INFO - PROCESS | 21779 | RSS: Main: 193794048 22:10:24 INFO - PROCESS | 21779 | 22:10:27 INFO - PROCESS | 21779 | Cycle 1(1): loaded http://localhost:58966/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:58966/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:10:27 INFO - PROCESS | 21779 | RSS: Main: 194191360 22:10:27 INFO - PROCESS | 21779 | 22:10:30 INFO - PROCESS | 21779 | Cycle 1(1): loaded http://localhost:58966/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:58966/tests/kraken/driver.html?testName=imaging-darkroom) 22:10:31 INFO - PROCESS | 21779 | RSS: Main: 194326528 22:10:31 INFO - PROCESS | 21779 | 22:10:35 INFO - PROCESS | 21779 | Cycle 1(1): loaded http://localhost:58966/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:58966/tests/kraken/driver.html?testName=imaging-desaturate) 22:10:35 INFO - PROCESS | 21779 | RSS: Main: 192737280 22:10:35 INFO - PROCESS | 21779 | 22:10:38 INFO - PROCESS | 21779 | Cycle 1(1): loaded http://localhost:58966/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:58966/tests/kraken/driver.html?testName=json-parse-financial) 22:10:39 INFO - PROCESS | 21779 | RSS: Main: 194322432 22:10:39 INFO - PROCESS | 21779 | 22:10:40 INFO - PROCESS | 21779 | Cycle 1(1): loaded http://localhost:58966/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:58966/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:10:41 INFO - PROCESS | 21779 | RSS: Main: 194453504 22:10:41 INFO - PROCESS | 21779 | 22:10:43 INFO - PROCESS | 21779 | Cycle 1(1): loaded http://localhost:58966/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:58966/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:10:44 INFO - PROCESS | 21779 | RSS: Main: 194449408 22:10:44 INFO - PROCESS | 21779 | 22:10:46 INFO - PROCESS | 21779 | Cycle 1(1): loaded http://localhost:58966/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:58966/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:10:47 INFO - PROCESS | 21779 | RSS: Main: 194949120 22:10:47 INFO - PROCESS | 21779 | 22:10:49 INFO - PROCESS | 21779 | Cycle 1(1): loaded http://localhost:58966/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:58966/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:10:50 INFO - PROCESS | 21779 | RSS: Main: 195248128 22:10:50 INFO - PROCESS | 21779 | 22:10:53 INFO - PROCESS | 21779 | Cycle 1(1): loaded http://localhost:58966/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:58966/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:10:54 INFO - PROCESS | 21779 | RSS: Main: 195600384 22:10:54 INFO - PROCESS | 21779 | 22:10:55 INFO - PROCESS | 21779 | Cycle 1(1): loaded http://localhost:58966/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:58966/tests/kraken/driver.html?testName=ai-astar) 22:10:56 INFO - PROCESS | 21779 | RSS: Main: 197332992 22:10:56 INFO - PROCESS | 21779 | 22:10:56 INFO - PROCESS | 21779 | __start_tp_report 22:10:56 INFO - PROCESS | 21779 | _x_x_mozilla_page_load 22:10:56 INFO - PROCESS | 21779 | _x_x_mozilla_page_load_details 22:10:56 INFO - PROCESS | 21779 | |i|pagename|runs| 22:10:56 INFO - PROCESS | 21779 | |0;ai-astar;106;114;117;117;116;113;117;115;114;115 22:10:56 INFO - PROCESS | 21779 | |1;audio-beat-detection;153;126;126;172;127;124;125;125;157;124 22:10:56 INFO - PROCESS | 21779 | |2;audio-dft;166;170;164;162;166;165;165;163;162;164 22:10:56 INFO - PROCESS | 21779 | |3;audio-fft;77;76;77;112;79;93;77;76;106;77 22:10:56 INFO - PROCESS | 21779 | |4;audio-oscillator;84;82;111;84;83;85;101;84;85;103 22:10:56 INFO - PROCESS | 21779 | |5;imaging-gaussian-blur;118;118;119;118;118;118;119;118;118;118 22:10:56 INFO - PROCESS | 21779 | |6;imaging-darkroom;148;148;149;149;147;148;148;149;148;147 22:10:56 INFO - PROCESS | 21779 | |7;imaging-desaturate;97;97;96;97;96;96;96;96;96;95 22:10:56 INFO - PROCESS | 21779 | |8;json-parse-financial;64;65;65;66;65;67;67;65;65;71 22:10:56 INFO - PROCESS | 21779 | |9;json-stringify-tinderbox;66;66;67;67;66;66;67;67;67;68 22:10:56 INFO - PROCESS | 21779 | |10;stanford-crypto-aes;84;84;83;84;82;82;83;83;82;83 22:10:56 INFO - PROCESS | 21779 | |11;stanford-crypto-ccm;145;136;147;138;138;145;136;148;175;139 22:10:56 INFO - PROCESS | 21779 | |12;stanford-crypto-pbkdf2;181;178;176;178;181;172;177;176;173;175 22:10:56 INFO - PROCESS | 21779 | |13;stanford-crypto-sha256-iterative;71;70;70;68;68;68;69;68;69;80 22:10:56 INFO - PROCESS | 21779 | __end_tp_report 22:10:56 INFO - PROCESS | 21779 | __start_cc_report 22:10:56 INFO - PROCESS | 21779 | _x_x_mozilla_cycle_collect,858 22:10:56 INFO - PROCESS | 21779 | __end_cc_report 22:10:56 INFO - PROCESS | 21779 | __startTimestamp1474953056227__endTimestamp 22:10:56 INFO - PROCESS | 21779 | 22:10:56 INFO - PROCESS | 21779 | ------- Summary: start ------- 22:10:56 INFO - PROCESS | 21779 | Number of tests: 14 22:10:56 INFO - PROCESS | 21779 | 22:10:56 INFO - PROCESS | 21779 | [#0] ai-astar Cycles:10 Average:114.40 Median:115.50 stddev:3.27 (2.8%) stddev-sans-first:1.50 22:10:56 INFO - PROCESS | 21779 | Values: 106.0 114.0 117.0 117.0 116.0 113.0 117.0 115.0 114.0 115.0 22:10:56 INFO - PROCESS | 21779 | 22:10:56 INFO - PROCESS | 21779 | [#1] audio-beat-detection Cycles:10 Average:135.90 Median:126.50 stddev:17.75 (14.0%) stddev-sans-first:17.72 22:10:56 INFO - PROCESS | 21779 | Values: 153.0 126.0 126.0 172.0 127.0 124.0 125.0 125.0 157.0 124.0 22:10:56 INFO - PROCESS | 21779 | 22:10:56 INFO - PROCESS | 21779 | [#2] audio-dft Cycles:10 Average:164.70 Median:165.00 stddev:2.36 (1.4%) stddev-sans-first:2.46 22:10:56 INFO - PROCESS | 21779 | Values: 166.0 170.0 164.0 162.0 166.0 165.0 165.0 163.0 162.0 164.0 22:10:56 INFO - PROCESS | 21779 | 22:10:56 INFO - PROCESS | 21779 | [#3] audio-fft Cycles:10 Average:85.00 Median:77.00 stddev:13.70 (17.8%) stddev-sans-first:14.22 22:10:56 INFO - PROCESS | 21779 | Values: 77.0 76.0 77.0 112.0 79.0 93.0 77.0 76.0 106.0 77.0 22:10:56 INFO - PROCESS | 21779 | 22:10:56 INFO - PROCESS | 21779 | [#4] audio-oscillator Cycles:10 Average:90.20 Median:84.00 stddev:10.55 (12.6%) stddev-sans-first:10.95 22:10:56 INFO - PROCESS | 21779 | Values: 84.0 82.0 111.0 84.0 83.0 85.0 101.0 84.0 85.0 103.0 22:10:56 INFO - PROCESS | 21779 | 22:10:56 INFO - PROCESS | 21779 | [#5] imaging-gaussian-blur Cycles:10 Average:118.20 Median:118.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 22:10:56 INFO - PROCESS | 21779 | Values: 118.0 118.0 119.0 118.0 118.0 118.0 119.0 118.0 118.0 118.0 22:10:56 INFO - PROCESS | 21779 | 22:10:56 INFO - PROCESS | 21779 | [#6] imaging-darkroom Cycles:10 Average:148.10 Median:148.00 stddev:0.74 (0.5%) stddev-sans-first:0.78 22:10:56 INFO - PROCESS | 21779 | Values: 148.0 148.0 149.0 149.0 147.0 148.0 148.0 149.0 148.0 147.0 22:10:56 INFO - PROCESS | 21779 | 22:10:56 INFO - PROCESS | 21779 | [#7] imaging-desaturate Cycles:10 Average:96.20 Median:96.00 stddev:0.63 (0.7%) stddev-sans-first:0.60 22:10:56 INFO - PROCESS | 21779 | Values: 97.0 97.0 96.0 97.0 96.0 96.0 96.0 96.0 96.0 95.0 22:10:56 INFO - PROCESS | 21779 | 22:10:56 INFO - PROCESS | 21779 | [#8] json-parse-financial Cycles:10 Average:66.00 Median:65.50 stddev:2.00 (3.1%) stddev-sans-first:1.99 22:10:56 INFO - PROCESS | 21779 | Values: 64.0 65.0 65.0 66.0 65.0 67.0 67.0 65.0 65.0 71.0 22:10:56 INFO - PROCESS | 21779 | 22:10:56 INFO - PROCESS | 21779 | [#9] json-stringify-tinderbox Cycles:10 Average:66.70 Median:67.00 stddev:0.67 (1.0%) stddev-sans-first:0.67 22:10:56 INFO - PROCESS | 21779 | Values: 66.0 66.0 67.0 67.0 66.0 66.0 67.0 67.0 67.0 68.0 22:10:56 INFO - PROCESS | 21779 | 22:10:56 INFO - PROCESS | 21779 | [#10] stanford-crypto-aes Cycles:10 Average:83.00 Median:83.00 stddev:0.82 (1.0%) stddev-sans-first:0.78 22:10:56 INFO - PROCESS | 21779 | Values: 84.0 84.0 83.0 84.0 82.0 82.0 83.0 83.0 82.0 83.0 22:10:56 INFO - PROCESS | 21779 | 22:10:56 INFO - PROCESS | 21779 | [#11] stanford-crypto-ccm Cycles:10 Average:144.70 Median:145.00 stddev:11.59 (8.0%) stddev-sans-first:12.29 22:10:56 INFO - PROCESS | 21779 | Values: 145.0 136.0 147.0 138.0 138.0 145.0 136.0 148.0 175.0 139.0 22:10:56 INFO - PROCESS | 21779 | 22:10:56 INFO - PROCESS | 21779 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:176.70 Median:177.50 stddev:2.98 (1.7%) stddev-sans-first:2.73 22:10:56 INFO - PROCESS | 21779 | Values: 181.0 178.0 176.0 178.0 181.0 172.0 177.0 176.0 173.0 175.0 22:10:56 INFO - PROCESS | 21779 | 22:10:56 INFO - PROCESS | 21779 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.10 Median:69.50 stddev:3.63 (5.2%) stddev-sans-first:3.84 22:10:56 INFO - PROCESS | 21779 | Values: 71.0 70.0 70.0 68.0 68.0 68.0 69.0 68.0 69.0 80.0 22:10:56 INFO - PROCESS | 21779 | -------- Summary: end -------- 22:10:56 INFO - PROCESS | 21779 | 22:10:56 INFO - PROCESS | 21779 | 22:10:56 INFO - PROCESS | 21779 | ###!!! [Parent][OnMaybeDequeueOne] Error: Channel error: cannot send/recv 22:10:56 INFO - PROCESS | 21779 | 22:10:56 INFO - PROCESS | 21779 | 22:10:56 INFO - PROCESS | 21779 | ###!!! [Parent][OnMaybeDequeueOne] Error: Channel error: cannot send/recv 22:10:56 INFO - PROCESS | 21779 | 22:10:56 INFO - PROCESS | 21779 | 22:10:56 INFO - PROCESS | 21779 | ###!!! [Parent][OnMaybeDequeueOne] Error: Channel error: cannot send/recv 22:10:56 INFO - PROCESS | 21779 | 22:10:56 INFO - PROCESS | 21779 | 22:10:56 INFO - PROCESS | 21779 | ###!!! [Parent][MessageChannel] Error: (msgtype=0x2000B,name=PAPZ::Msg_Destroy) Channel error: cannot send/recv 22:10:56 INFO - PROCESS | 21779 | 22:10:56 INFO - PROCESS | 21779 | 22:10:56 INFO - PROCESS | 21779 | ###!!! [Parent][OnMaybeDequeueOne] Error: Channel error: cannot send/recv 22:10:56 INFO - PROCESS | 21779 | 22:10:57 INFO - TEST-INFO | 21779: exit 0 22:10:57 INFO - TEST-OK | kraken | took 65501ms 22:10:57 INFO - SUITE-END | took 1183s 22:10:57 INFO - Completed test suite (00:19:44) 22:10:57 INFO - javascript benchmark 22:10:57 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6154.009764658967, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2592.2741632342927, 2597.2467984284544, 2601.1546247469573, 2616.0, 2636.0378461861415, 4531.56950089741, 4650.534946505352, 4672.252439609663, 4702.459217190019, 4705.882352941178, 4565.429321334833, 4585.495595484494, 4643.953560464394, 4666.8366607168755, 4678.789454474549, 4271.081717430752, 4276.722013069156, 4369.388285640013, 4380.364847097169, 4426.070525189714, 4307.461567304087, 4340.066885619591, 4356.520782713915, 4404.69167158299, 4446.1107778444275, 4184.8744537663915, 4218.11419601884, 4222.746635201867, 4343.913121737579, 4370.994671225636, 4206.66346692267, 4225.471816022997, 4233.343831706091, 4237.618614324695, 4325.07010992635, 4222.282212023963, 4249.150169965988, 4272.6154646082, 4279.97860010698, 4298.118885628439, 4614.746188959606, 4628.190066738307, 4660.254359302529, 4672.602828759559, 4692.483826779052, 4978.079055374731, 4988.900221995576, 4989.476105008958, 4993.525615066563, 5019.121653710586, 5001.224810154432, 5009.574186194178, 5015.122353588144, 5021.322121513622, 5022.347094877642, 4552.772361381918, 4595.586397224266, 4651.651126165551, 4684.531546845288, 4720.362751028601, 5128.230765385222, 5139.049275883974, 5149.6910185389, 5210.53105220532, 5216.504432078947, 4908.214685650279, 4908.901821963612, 4913.091078150554, 4931.901361972812, 4983.103041452573, 4811.78346974387, 4929.433115191799, 4932.950670493321, 5022.748862556858, 5032.295478632995, 4920.3111564381015, 4932.21084626458, 4936.975315123474, 4948.826791062295, 4997.800087996512, 4925.261210818406, 4931.260310953386, 4935.383077115361, 4939.086269040239, 4993.525615066563, 4013.0, 4057.127717540743, 4122.42286079949, 4146.0464093258715, 4200.537940826506, 3638.1086633774366, 3651.835667394974, 3705.481232627434, 3706.240220754739, 3730.0301921500063, 4344.239758042393, 4384.0, 4395.978020109943, 4396.560343965578, 4441.489228738672, 4906.582940450102, 4954.826581069718, 5024.974875125601, 5053.418856831511, 5111.667741596784, 4316.287812510901, 4326.891827704332, 4347.174036932973, 4347.891302717458, 4395.692301538862, 4422.800973956179, 4430.601245887886, 4435.445569303837, 4437.8224871004795, 4482.843100491467, 4562.520935301745, 4680.344751734692, 4686.8125274988615, 4688.765561721969, 4732.337472753748, 4819.132556139928, 5009.499050094962, 5041.0, 5053.140966035856, 5120.692758434506, 4770.570648641639, 4774.40319960005, 4776.522347765195, 4779.976100119477, 4831.782569784298, 4791.209450440639, 4826.251930950708, 4848.12733707936, 4967.453580106113, 4976.576990955809, 4892.412910450768, 4931.087748766489, 4947.183714687037, 4993.126202914548, 5007.874803129951, 4784.091022705814, 4819.518048195152, 4820.373351464427, 4825.831095911626, 4860.635452341018, 4729.43246810385, 4790.473047964651, 4813.253945638432, 4822.445418776745, 4878.975605121952, 4774.976125119352, 4795.95204047969, 4797.064572408347, 4832.589229915356, 4881.194602890625, 4918.655694101379, 4921.384826896638, 4928.605711542997, 4943.901121977612, 4981.900361992667, 4604.58558729716, 4766.713997160182, 4767.928481072856, 4774.307725379731, 4837.564619184291], "value": 4576.424538049467, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [547.808267106513, 548.7448336523516, 550.609067562031, 558.9133684278938, 562.6708375600274, 36880.81559592202, 38848.61151388489, 38917.80541097294, 41628.37557436641, 41738.373924391155, 38955.41566876499, 39009.60990390095, 39457.0, 39468.21063578727, 39482.40776388349, 7342.743003994848, 7478.813029674247, 7480.214577469369, 7483.0, 7485.6631451584535, 7574.053243344582, 7693.115291741466, 7707.730229441971, 7719.65261563231, 7732.536047837119, 7650.923490765077, 7718.614069296541, 7730.5361678299205, 7742.380609551223, 7753.224677532258, 7698.307152356315, 7699.26856948589, 7716.035495563055, 7746.535207887517, 7765.728199513046, 7678.961605191994, 7764.417668674872, 7767.145613982458, 7768.922310776877, 7769.72805951789, 37441.06397340061, 37651.87044388673, 37883.62116378829, 37931.43102853459, 37954.24091518182, 2929.633795775528, 2961.333699917523, 2971.5691224772286, 3021.546767984798, 3034.620672415948, 27522.76147573364, 28296.292592685142, 28651.283717907012, 28749.425011499654, 29203.56194657082, 3257.1857035741064, 3275.148461400029, 3280.0979730574045, 3298.818564978925, 3318.2699806042874, 236484.0, 237379.0, 237812.0, 237852.0, 238728.0, 48230.75884620625, 48449.5155048445, 48511.0, 48545.51454485481, 48591.51408485941, 32192.517112242866, 33011.504827427605, 33169.66830331714, 34823.65176348255, 51758.482415175364, 13853.099548529313, 14029.158250505003, 14031.22828244436, 14054.437822486985, 14058.437662493387, 9453.952730236304, 9582.137607615348, 9647.517624118766, 9671.661491847892, 9695.51522423876, 17351.913240433718, 17367.56581085483, 17386.913065434845, 17419.825801742074, 17490.562735931704, 6626.7017984190825, 6885.449164066963, 6978.720851165998, 7041.084658994298, 7088.503804733619, 777.4208214879837, 917.9489484540164, 922.2299380017761, 955.4362925873768, 1034.5086084110137, 26746.465070698476, 27486.862565687043, 27576.724232757817, 27624.0, 27971.30071748182, 945.6784693204344, 952.2857856607544, 953.814006268771, 963.2246041936309, 996.2229461020415, 13661.248631325174, 14543.854561454462, 14665.560033198808, 14808.777868331983, 14919.40322387114, 358171.0, 362527.0, 363079.0, 363429.0, 364190.0, 47733.76133119312, 48202.0, 48266.75866620715, 48291.75854120707, 48380.0, 1147.2198904744687, 1153.6020073074776, 1170.584442522909, 1170.7073231692077, 1171.5021116025655, 25326.74673253244, 26282.73717262803, 27228.455430891667, 27533.0, 27609.723902760714, 40954.79522602368, 41145.17709645731, 41152.58847411607, 41241.1751764957, 41248.587514124476, 309524.0, 311011.0, 312034.0, 312127.0, 313045.0, 24434.755652443248, 24718.752812471645, 24745.381365466008, 24787.13245036415, 24824.875875620506, 38923.69148622399, 39782.2043559133, 39938.0, 40587.391189131595, 41221.79389103036, 33997.830010850776, 34149.31701366008, 34157.487637685954, 34600.30799383948, 34694.0, 34414.82792586021, 34478.48282275718, 34697.826510867286, 35208.64791352054, 35346.64653353434, 33864.66135338615, 34222.14444638805, 34246.828765856015, 34441.0, 35181.648183517835, 29991.400171996873, 32194.19514512156, 32425.35149297048, 32515.0, 32520.0, 24054.39864003414, 24565.0, 24783.87608061948, 24833.751662483144, 25334.239972800107], "value": 16811.37905379567, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [360.5673192169397, 364.7756629672751, 369.64698712729336, 378.4512456937441, 387.46916724088004, 15518.922405387972, 15611.609709757262, 17009.829901700996, 17094.572635684115, 17135.228914698837, 13559.389827457764, 15740.527784166494, 16573.0, 16677.66644667106, 16977.49067527973, 4900.852974410774, 5075.4163271223915, 5082.720450375228, 5084.644074914758, 5102.948970510284, 4939.382577177853, 5015.07221164084, 5015.247712843066, 5015.749212539358, 5022.17134172861, 5018.272350509174, 5309.0, 5315.441878602749, 5387.676739395649, 5395.487428694268, 5141.331626888518, 5349.277847490597, 5374.0, 5379.757910894019, 5392.083345831198, 5096.592272618182, 5115.769790359443, 5129.051125541787, 5131.384233891957, 5134.717590532519, 11816.940915295454, 11883.346415947119, 11984.0, 12035.338056406894, 12164.695882602917, 1754.929802807886, 1806.8554515638718, 1820.8543316534644, 1888.7544619199552, 2240.9103635854544, 8527.573621318908, 8738.388312818106, 9117.5441227939, 9223.216026637743, 9329.486878221694, 1687.873409494293, 1733.9479815605553, 1763.7618921445696, 1781.4477511971331, 1783.2242974306089, 67942.3205767941, 69289.65355173242, 69787.65106174488, 70487.64756176186, 70800.0, 8670.393072484865, 8843.955780221058, 8967.282617390592, 9396.154346108882, 9606.0, 12753.872461275454, 12755.107142500121, 12757.42591583381, 12777.0, 12777.361131943366, 6405.711742971577, 6456.354364563506, 6631.204255489372, 6784.59292442455, 6824.965875170592, 6266.404691554295, 6307.810765676945, 6451.806445806634, 6458.515611329169, 6517.478601711851, 4014.538328092307, 4033.253848038122, 4094.6928980326593, 4137.8758637240935, 4222.3244280914905, 1683.8737094717944, 1707.9146042697816, 1733.3846484498074, 1744.4156207670574, 1774.2636805725479, 781.3046388713935, 834.4159088637978, 854.7521218846589, 881.3169793410159, 904.213334399077, 15946.122963237134, 16620.418285359956, 16895.662086758195, 17294.827051729575, 17661.205245763973, 810.4529442626322, 842.3429724814654, 844.1642773654038, 848.3552500099969, 891.6656253904786, 7579.090509138829, 9323.673671421468, 9675.854862177073, 9725.854112188323, 9827.262955278382, 337497.0, 343976.0, 344336.0, 347353.0, 351042.0, 71717.28282717211, 73053.26946730571, 73211.0, 73993.0, 77891.0, 823.7693445835175, 841.8821365008904, 868.2749903830246, 883.65095787165, 890.5369208011798, 14937.178455185069, 15027.398904044154, 15194.772078419053, 15460.84539154639, 15470.690586188439, 32752.508712368857, 34424.655753442144, 36009.639903601674, 36121.63878361183, 36345.81827090848, 48428.0, 50042.24936625881, 50319.74840125776, 50523.0, 50697.0, 7418.554886706815, 7556.433267504849, 7606.20134885829, 7693.1922148173635, 7784.338331241908, 10310.690679279634, 12006.819897701367, 12020.278783273032, 12024.519019239133, 12063.939680301542, 4949.82675606352, 5244.816431424882, 9006.909930900607, 10518.789624207626, 10626.574937002433, 10175.0, 10278.33190842593, 10324.174066074545, 10347.741306467398, 10453.634122805664, 10362.689119326433, 10454.947725261325, 10497.895021049691, 10516.947415262875, 10710.143188545053, 6419.0, 6758.0, 6853.897191542031, 7676.0, 7900.802479938047, 9752.902470975483, 10552.947235263775, 10773.19201059908, 10812.837807433052, 10945.562177512811], "value": 7782.488866709752, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1332.900032497563, 1370.9108907920986, 1417.7022825206707, 1602.8958117722354, 1627.5198816349175, 6885.242623311432, 7103.289671032894, 7211.891821622673, 7784.987951566289, 7805.60971951403, 7152.642367881611, 7436.6653500592465, 7436.962815185916, 7580.6209689515445, 7640.350570201525, 2135.113927719998, 2166.2851259084464, 2168.5229249565073, 2173.2285038811233, 2174.4889950861534, 2122.4481634774993, 2159.3090211132367, 2180.967285490719, 2191.7589065202815, 2194.221051526709, 2142.3787101740477, 2159.773223811501, 2167.501474660829, 2167.967480487786, 2200.0759680934048, 2066.225165562914, 2085.937421877346, 2086.9478263043393, 2092.163134746106, 2092.9790702093087, 2087.2590230468118, 2142.1966762464076, 2146.034284571949, 2151.7310336207975, 2153.7846215378495, 7613.42899282556, 7617.504862183996, 7680.961595191989, 7688.269614386624, 7696.6536505857375, 4458.487273963503, 4519.231730605772, 4592.5177856324935, 4594.701344412602, 4604.1252162089095, 2716.361655011071, 2804.775617950559, 2811.704770999036, 2851.0591504803574, 2873.123697272331, 908.6910450446813, 953.2516974175239, 954.370115723619, 972.9173020293283, 980.0052946259552, 10293.691189264335, 10313.948430257875, 10328.896711032869, 10374.066334029974, 10413.219008574311, 3931.4495970564144, 4135.8966025848995, 4238.046439551138, 4258.190943720683, 4304.5910638489295, 8860.069692682304, 8906.242969347548, 8906.688265910661, 8910.153535414125, 8927.732168034969, 4604.562566556172, 4607.170709272363, 4608.9308660370125, 4641.048585040058, 4661.300804879227, 3662.2675464907124, 3786.1859700164596, 4101.7744024078365, 4245.129748401632, 4245.893852653647, 3486.0, 3486.320167567351, 3641.308151451216, 3668.816559172084, 3670.100825297819, 1202.6933132051272, 1205.6744678936639, 1211.0674780419204, 1218.3177420644467, 1222.1994593541244, 739.4084732214208, 769.4767558060572, 770.6724642027116, 771.714465647714, 802.4022103532906, 5079.187330027178, 5122.846314610568, 5175.301334319894, 5361.490658387447, 5547.696291371524, 816.8407160603745, 830.6760363458257, 851.29342645604, 878.6792820620427, 920.8075542172768, 3424.0, 3495.143689795965, 3517.82410879455, 3588.69496092835, 3590.2998915211806, 9064.909350906539, 9442.197413219816, 9477.715668529956, 9502.144806967406, 9522.714318570454, 9419.623215071457, 9420.105090016437, 9427.481488518197, 9444.811103777885, 9485.430874147572, 6992.650367481708, 7003.54476958996, 7070.328318809705, 7125.287471252833, 7163.31948464895, 4845.830395936126, 4990.575801056805, 5013.774380152829, 5022.1211288023715, 5177.663451875616, 8394.580270986304, 8469.74590762278, 8479.703210387606, 8541.743747687578, 8590.570471476527, 8521.446106003337, 8585.570721463777, 8601.612927418157, 8683.913160868311, 8748.431351962103, 5992.490638295793, 6164.198654175108, 6343.143675603919, 6490.350964903472, 6496.447801936888, 6448.774292899726, 6622.271550129578, 6658.83352916181, 6770.424513916175, 6773.288804675438, 6394.23269207698, 6419.967900160469, 6487.54587178893, 6557.245916719447, 6706.832329191614, 6319.146915166578, 6394.584352017104, 6448.967755161194, 6456.12842266288, 6484.675766211765, 5844.912326315024, 5940.940590594038, 5945.227120474311, 5949.3455719871645, 5951.018082016419, 4187.309093999456, 4212.7472351659, 4294.205571969195, 4312.296642826129, 4315.978420107879, 4266.87199384019, 4364.105358401456, 4379.912401752011, 4380.7152535085115, 4480.61914737251], "value": 4023.321836920824, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [288.6665900884479, 300.35873410269073, 305.73095675805286, 306.9800462969908, 308.57878995171586, 4808.663393562452, 4886.584640305574, 4926.8768280793, 4995.325631039804, 5009.398872135344, 4707.4586422561415, 4816.63875209359, 4858.659893807435, 4860.392450943632, 4884.853454396374, 3878.4957955465893, 3883.533975922886, 3894.240623078501, 3897.6881849451975, 3905.941410878839, 3752.2233547175165, 3817.8854634361014, 3885.4560361549406, 3886.7667939923554, 3890.6498415142632, 3828.5979972102946, 3837.34765089934, 3842.865499707511, 3844.5578758442857, 3845.1732877431346, 3818.503594532721, 3821.2548553032307, 3821.7706937583835, 3823.1589050408866, 3842.405401758267, 3365.6297807241185, 3368.3936891359544, 3370.3259348130473, 3377.510261012164, 3385.5611365169702, 4715.316279139505, 4806.182948898696, 4822.951770482286, 4871.147549178915, 4875.098106850244, 4338.175746608134, 4349.390365780538, 4351.0, 4355.432044463991, 4372.322290045048, 5175.767090480937, 5186.92219616706, 5193.221016847466, 5193.454687257859, 5200.765965531522, 3591.586967498745, 3677.8161091945562, 3682.705383569335, 3694.833732482045, 3699.611540788232, 5389.757460914268, 5433.483819037225, 5434.619576629638, 5445.292112025451, 5458.042918883817, 3698.1494256320657, 3708.2212735325343, 3750.643688849555, 3751.718621103428, 3753.9061523462133, 3762.6049264827348, 3769.660730534265, 3769.8680546180753, 3776.3013842438686, 3790.65884070435, 4195.643370313496, 4271.594198551133, 4301.3117901135665, 4306.590873866978, 4309.353596960481, 5141.665791723601, 5156.896862062813, 5161.329027226511, 5162.870928226825, 5197.155009971481, 1236.8701286364983, 1264.5700461843016, 1273.3251376770327, 1301.3168086754529, 1315.4869600855675, 848.5545088828414, 867.3841572483482, 868.2576397180446, 873.4453621950107, 876.991230087691, 4562.361269422284, 4566.748828814447, 4581.152486789954, 4587.839425620087, 4588.587027167504, 5163.638545301868, 5319.0957537218765, 5330.466953304638, 5331.493508116722, 5339.932013597296, 4538.364628951949, 4553.180427523078, 4575.565321294472, 4577.908441831145, 4581.198290299251, 2482.3049546127113, 2498.850068995866, 2507.285423654286, 2509.3475696319088, 2511.899524019019, 4417.425734654475, 4417.9396944733035, 4437.356583295404, 4456.799444025027, 4473.955260447354, 4475.955917108229, 4706.835260765857, 4724.622030237641, 4735.644826638016, 4756.405449318835, 4327.459067616611, 4397.307036790859, 4403.471583410012, 4405.625521830681, 4418.182636212311, 4602.010567727954, 4617.007343421181, 4631.397918270603, 4633.590532922929, 4652.999210339586, 4272.23099842019, 4486.887827804331, 4523.660725445539, 4578.008566273682, 4583.03756211186, 4379.715318504287, 4407.713498622579, 4425.845095421645, 4459.331100334975, 4483.601273318759, 3400.9457068308902, 3409.931801363909, 3410.4543273076188, 3423.195549046022, 3427.3830710472353, 4499.392582001388, 4665.766711664471, 4714.646401519831, 4717.551832575899, 4727.1963766160525, 4538.432695913011, 4547.704399214041, 4561.540307101695, 4562.61217796491, 4579.450465944108, 4485.196752029981, 4487.3973095603815, 4500.819967201406, 4508.80516663078, 4534.659900507541, 4428.468583769968, 4489.34904438848, 4752.809887604595, 4883.559349991832, 5046.495350464924, 5230.424653288059, 5230.895382092413, 5234.633575649668, 5238.76425560843, 5239.214117882349, 5085.618578606545, 5088.389393272831, 5088.898222035465, 5097.974510127426, 5100.668456550312], "value": 3730.2470130575807, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [600.6876424259385, 670.3631550027475, 673.2089794491471, 691.1533371619767, 692.3581958896204, 12937.935310323459, 13773.311334433276, 13803.930980345085, 13967.65080872976, 14741.705165896676, 12645.936770316159, 12689.555865544708, 12857.292848893307, 12943.352832358369, 12960.54638087667, 745.3105877063708, 764.7438108233748, 766.5515673331108, 766.5668897073164, 767.2519293688665, 612.3111499562991, 629.3297638015522, 736.5875109938424, 755.6675062972274, 761.9085709714842, 817.199144838059, 817.4849844597925, 819.7868554175927, 820.1675299570941, 822.4078663362369, 815.8572249856252, 816.1593558634556, 817.1950628630794, 818.3780326951501, 818.423011776696, 599.868029033612, 599.9250093738283, 600.0788789208588, 600.1447936690199, 600.7326739600879, 11976.341301228429, 12014.63956081319, 12030.157888947777, 12041.157119001673, 12052.758944821055, 11837.0, 12134.757304853942, 12317.260964342167, 12491.250524968531, 12721.745565088646, 9985.850212246822, 10020.14828739558, 10027.74930626733, 10094.9495252524, 10164.94917525415, 3109.5491153782573, 3140.0, 3153.385089907469, 3163.14595059335, 3232.69289417505, 50328.0, 62513.0, 63010.684946575435, 63024.0, 63891.680541596994, 9112.772180695536, 9358.745284891855, 9528.714138575853, 9543.761405964906, 9609.279304052225, 17224.913875430793, 17394.391196308068, 17399.217035233192, 17519.824801752075, 17586.296548138187, 6213.130161777355, 6349.14286571307, 6361.4592759615025, 6412.422881940648, 6570.211574611078, 3576.3204991051616, 3585.46218067292, 3597.226596281812, 3611.2777444511203, 3627.165751877083, 2780.749732524042, 2891.421715656877, 2909.24359666484, 2912.1409184290687, 2912.475754364235, 494.70812220789185, 498.8852563910248, 506.7668872318701, 509.71455984648713, 515.5540457504306, 433.38892162051354, 479.8344571122957, 492.8151943021367, 498.62603047714214, 499.5029945204545, 7588.734394296283, 7684.807879803049, 7798.25916537928, 8054.436189466681, 8147.959260203779, 517.2516892902042, 539.8002738986601, 571.2031715756497, 579.7101449275362, 590.344717363726, 3523.9647603523636, 3797.1266608679607, 3805.5243094613174, 3869.1487872667967, 3935.0752573145287, 88011.0, 91142.0, 92724.53637731768, 92806.0, 92979.5351023254, 46876.5312346879, 48794.75602621964, 76137.61931190308, 80800.59599701964, 82207.17792822115, 58820.41179588235, 70527.0, 72591.0, 72612.63693681498, 73031.63484182651, 6986.126734158231, 7070.893936591058, 7084.220735718964, 7204.0994875640545, 7346.77959661211, 41278.79360603298, 42391.15217695567, 43327.566724332355, 43377.56622433862, 43968.34047489226, 57217.427825721206, 57825.0, 61857.69071154615, 62674.6866265684, 62861.37138628555, 8996.59515321799, 9228.215601673934, 9259.629614815332, 9300.674476393293, 9417.529123543933, 9594.904050959402, 9639.807203855744, 9791.216702663913, 9793.069658382443, 9872.950635246778, 8966.148215919477, 9039.0, 9047.366684332033, 9062.184403403726, 9142.131497507726, 9208.815823683623, 9247.306452015991, 9371.237524751577, 9447.433154010781, 9648.276379271723, 8895.733128005912, 8943.418677785923, 9060.90939090627, 9076.137766912132, 9211.76970575741, 8470.957645211734, 8795.25240354577, 8796.692115775917, 8856.911430885608, 9333.626654933727, 8024.759257222293, 8546.2308392245, 8584.385974498175, 9082.772930676785, 9242.0], "value": 6044.066690349928, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1559.9, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 114.0, 117.0, 117.0, 116.0, 113.0, 117.0, 115.0, 114.0, 115.0], "value": 114.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 126.0, 126.0, 172.0, 127.0, 124.0, 125.0, 125.0, 157.0, 124.0], "value": 135.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 170.0, 164.0, 162.0, 166.0, 165.0, 165.0, 163.0, 162.0, 164.0], "value": 164.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 76.0, 77.0, 112.0, 79.0, 93.0, 77.0, 76.0, 106.0, 77.0], "value": 85.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 82.0, 111.0, 84.0, 83.0, 85.0, 101.0, 84.0, 85.0, 103.0], "value": 90.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 118.0, 119.0, 118.0, 118.0, 118.0, 119.0, 118.0, 118.0, 118.0], "value": 118.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 148.0, 149.0, 149.0, 147.0, 148.0, 148.0, 149.0, 148.0, 147.0], "value": 148.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 97.0, 96.0, 97.0, 96.0, 96.0, 96.0, 96.0, 96.0, 95.0], "value": 96.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 65.0, 65.0, 66.0, 65.0, 67.0, 67.0, 65.0, 65.0, 71.0], "value": 66.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 67.0, 67.0, 66.0, 66.0, 67.0, 67.0, 67.0, 68.0], "value": 66.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 83.0, 84.0, 82.0, 82.0, 83.0, 83.0, 82.0, 83.0], "value": 83.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 136.0, 147.0, 138.0, 138.0, 145.0, 136.0, 148.0, 175.0, 139.0], "value": 144.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [181.0, 178.0, 176.0, 178.0, 181.0, 172.0, 177.0, 176.0, 173.0, 175.0], "value": 176.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 70.0, 70.0, 68.0, 68.0, 68.0, 69.0, 68.0, 69.0, 80.0], "value": 70.1, "unit": "score"}]}]} 22:10:57 INFO - Return code: 0 22:10:58 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:10:58 ERROR - # TBPL SUCCESS # 22:10:58 INFO - Running post-action listener: _resource_record_post_action 22:10:58 INFO - [mozharness: 2016-09-27 05:10:58.019973Z] Finished run-tests step (success) 22:10:58 INFO - Running post-run listener: _resource_record_post_run 22:10:58 INFO - Total resource usage - Wall time: 1200s; CPU: 13.0%; Read bytes: 0; Write bytes: 399904768; Read time: 0; Write time: 345004 22:10:58 INFO - TinderboxPrint: CPU usage
13.5% 22:10:58 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 22:10:58 INFO - TinderboxPrint: I/O write bytes / time
399,904,768 / 345,004 22:10:58 INFO - TinderboxPrint: CPU idle
8,280.7 (86.5%) 22:10:58 INFO - TinderboxPrint: CPU user
1,226.0 (12.8%) 22:10:58 INFO - TinderboxPrint: Swap in / out
0 / 0 22:10:58 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 110493696; Read time: 0; Write time: 78152 22:10:58 INFO - run-tests - Wall time: 1185s; CPU: 13.0%; Read bytes: 0; Write bytes: 289411072; Read time: 0; Write time: 266852 22:10:58 INFO - Running post-run listener: _upload_blobber_files 22:10:58 INFO - Blob upload gear active. 22:10:58 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:10:58 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 22:10:58 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'] 22:10:58 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 22:10:59 INFO - (blobuploader) - INFO - Open directory for files ... 22:10:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 22:10:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:10:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:10:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 22:10:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:10:59 INFO - (blobuploader) - INFO - Done attempting. 22:10:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 22:10:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:10:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:11:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 22:11:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:11:00 INFO - (blobuploader) - INFO - Done attempting. 22:11:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:11:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:11:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:11:04 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:11:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:11:04 INFO - (blobuploader) - INFO - Done attempting. 22:11:04 INFO - (blobuploader) - INFO - Iteration through files over. 22:11:04 INFO - Return code: 0 22:11:04 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:11:04 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:11:04 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/ecc19e28596cd449164ba9445f131d3e12ab0a8bc4b7013111dd089a5e109d80db8d556e87c8c53b3dbb49bb38da7a9a098ad5525477559660e752e1dbaeb297", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/5c75dc0e0774737e9d75bd0e03ddacc7b20ff2c3a1e5a5ec456d04e4ccb5e4751d19835860cf24e01aed1d03883076e45da1365bd8b8fbf04e349eda352d876d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/6bb42efc46dec4f4e5ed8d0d752a7b5462c62d8e61c766e3d37ca84c8632622bf56d1902c02bc891d0e146fca59bcac1738564d9a31f933f1018c4bae9f2bac4"} 22:11:04 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:11:04 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:11:04 INFO - Contents: 22:11:04 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/ecc19e28596cd449164ba9445f131d3e12ab0a8bc4b7013111dd089a5e109d80db8d556e87c8c53b3dbb49bb38da7a9a098ad5525477559660e752e1dbaeb297", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/5c75dc0e0774737e9d75bd0e03ddacc7b20ff2c3a1e5a5ec456d04e4ccb5e4751d19835860cf24e01aed1d03883076e45da1365bd8b8fbf04e349eda352d876d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/6bb42efc46dec4f4e5ed8d0d752a7b5462c62d8e61c766e3d37ca84c8632622bf56d1902c02bc891d0e146fca59bcac1738564d9a31f933f1018c4bae9f2bac4"} 22:11:04 INFO - Running post-run listener: copy_logs_to_upload_dir 22:11:04 INFO - Copying logs to upload dir... 22:11:04 INFO - mkdir: /builds/slave/test/build/upload/logs 22:11:04 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1243.636314 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 43 secs) (at 2016-09-26 22:11:02.812507) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-26 22:11:02.817084) ========= 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=920470bf432669ad7e3a5387000001ca-1474945679.223815-740405567 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/ecc19e28596cd449164ba9445f131d3e12ab0a8bc4b7013111dd089a5e109d80db8d556e87c8c53b3dbb49bb38da7a9a098ad5525477559660e752e1dbaeb297", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/5c75dc0e0774737e9d75bd0e03ddacc7b20ff2c3a1e5a5ec456d04e4ccb5e4751d19835860cf24e01aed1d03883076e45da1365bd8b8fbf04e349eda352d876d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/6bb42efc46dec4f4e5ed8d0d752a7b5462c62d8e61c766e3d37ca84c8632622bf56d1902c02bc891d0e146fca59bcac1738564d9a31f933f1018c4bae9f2bac4"} build_url:https://queue.taskcluster.net/v1/task/GkGR1c4-TUiBQh_LwqpQSw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011365 build_url: 'https://queue.taskcluster.net/v1/task/GkGR1c4-TUiBQh_LwqpQSw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/ecc19e28596cd449164ba9445f131d3e12ab0a8bc4b7013111dd089a5e109d80db8d556e87c8c53b3dbb49bb38da7a9a098ad5525477559660e752e1dbaeb297", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/5c75dc0e0774737e9d75bd0e03ddacc7b20ff2c3a1e5a5ec456d04e4ccb5e4751d19835860cf24e01aed1d03883076e45da1365bd8b8fbf04e349eda352d876d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/6bb42efc46dec4f4e5ed8d0d752a7b5462c62d8e61c766e3d37ca84c8632622bf56d1902c02bc891d0e146fca59bcac1738564d9a31f933f1018c4bae9f2bac4"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-26 22:11:02.845353) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-26 22:11:02.845703) ========= 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=920470bf432669ad7e3a5387000001ca-1474945679.223815-740405567 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004377 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-26 22:11:02.926435) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-26 22:11:02.929795) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-26 22:11:02.930099) ========= ========= Total master_lag: 0.20 =========