builder: autoland_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-032 starttime: 1470695964.29 results: success (0) buildid: 20160808135726 builduid: 96d5b165200a41dbb2639bc91c6b7057 revision: d1274a312b6b21d58c94357c2cccf78fb13af9c2 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-08 15:39:24.288400) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-08 15:39:24.288917) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-08 15:39:24.289251) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-08 15:39:24.304456) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-08 15:39:24.304852) ========= 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=745a4373a2a16df560f216e000000264-1470695867.787586-1429825616 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-08 15:39:24-- 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.3M=0s 2016-08-08 15:39:24 (46.3 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.140172 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-08 15:39:24.458950) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-08 15:39:24.459338) ========= 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=745a4373a2a16df560f216e000000264-1470695867.787586-1429825616 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019362 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-08 15:39:24.509781) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-08 15:39:24.510136) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev d1274a312b6b21d58c94357c2cccf78fb13af9c2 --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 d1274a312b6b21d58c94357c2cccf78fb13af9c2 --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=745a4373a2a16df560f216e000000264-1470695867.787586-1429825616 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-08 15:39:24,977 truncating revision to first 12 chars 2016-08-08 15:39:24,977 Setting DEBUG logging. 2016-08-08 15:39:24,977 attempt 1/10 2016-08-08 15:39:24,977 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/d1274a312b6b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-08 15:39:25,877 unpacking tar archive at: autoland-d1274a312b6b/testing/mozharness/ program finished with exit code 0 elapsedTime=1.243409 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-08 15:39:25.768302) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-08 15:39:25.768620) ========= script_repo_revision: d1274a312b6b21d58c94357c2cccf78fb13af9c2 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-08 15:39:25.769015) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-08 15:39:25.769303) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-08 15:39:25.791180) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 28 secs) (at 2016-08-08 15:39:25.791496) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Autoland-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=745a4373a2a16df560f216e000000264-1470695867.787586-1429825616 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:39:26 INFO - MultiFileLogger online at 20160808 15:39:26 in /builds/slave/test 15:39:26 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland-Non-PGO 15:39:26 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:39:26 INFO - {'append_to_log': False, 15:39:26 INFO - 'base_work_dir': '/builds/slave/test', 15:39:26 INFO - 'blob_upload_branch': 'Autoland-Non-PGO', 15:39:26 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:39:26 INFO - 'branch': 'Autoland-Non-PGO', 15:39:26 INFO - 'buildbot_json_path': 'buildprops.json', 15:39:26 INFO - 'config_files': ('talos/linux_config.py',), 15:39:26 INFO - 'default_actions': ('clobber', 15:39:26 INFO - 'read-buildbot-config', 15:39:26 INFO - 'download-and-extract', 15:39:26 INFO - 'populate-webroot', 15:39:26 INFO - 'create-virtualenv', 15:39:26 INFO - 'install', 15:39:26 INFO - 'run-tests'), 15:39:26 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:39:26 INFO - 'download_minidump_stackwalk': True, 15:39:26 INFO - 'download_symbols': 'ondemand', 15:39:26 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:39:26 INFO - 'tooltool.py': '/tools/tooltool.py', 15:39:26 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:39:26 INFO - '/tools/misc-python/virtualenv.py')}, 15:39:26 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:39:26 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:39:26 INFO - 'installer_path': 'installer.exe', 15:39:26 INFO - 'log_level': 'info', 15:39:26 INFO - 'log_name': 'talos', 15:39:26 INFO - 'log_to_console': True, 15:39:26 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:39:26 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:39:26 INFO - 'opt_config_files': (), 15:39:26 INFO - 'pip_index': False, 15:39:26 INFO - 'sps_profile': False, 15:39:26 INFO - 'sps_profile_interval': 0, 15:39:26 INFO - 'suite': 'dromaeojs', 15:39:26 INFO - 'system_bits': '32', 15:39:26 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:39:26 INFO - 'title': 'talos-linux64-ix-032', 15:39:26 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:39:26 INFO - 'use_talos_json': True, 15:39:26 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:39:26 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:39:26 INFO - 'work_dir': 'build'} 15:39:26 INFO - [mozharness: 2016-08-08 22:39:26.356949Z] Running clobber step. 15:39:26 INFO - Running pre-action listener: _resource_record_pre_action 15:39:26 INFO - Running main action method: clobber 15:39:26 INFO - rmtree: /builds/slave/test/build 15:39:26 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:39:26 INFO - Running post-action listener: _resource_record_post_action 15:39:26 INFO - [mozharness: 2016-08-08 22:39:26.822900Z] Finished clobber step (success) 15:39:26 INFO - [mozharness: 2016-08-08 22:39:26.823001Z] Running read-buildbot-config step. 15:39:26 INFO - Running pre-action listener: _resource_record_pre_action 15:39:26 INFO - Running main action method: read_buildbot_config 15:39:26 INFO - Using buildbot properties: 15:39:26 INFO - { 15:39:26 INFO - "project": "", 15:39:26 INFO - "product": "firefox", 15:39:26 INFO - "script_repo_revision": "production", 15:39:26 INFO - "builddir": "autoland_ubuntu64_hw_test-dromaeojs", 15:39:26 INFO - "repository": "", 15:39:26 INFO - "buildername": "Ubuntu HW 12.04 x64 autoland talos dromaeojs", 15:39:26 INFO - "buildid": "20160808135726", 15:39:26 INFO - "pgo_build": "False", 15:39:26 INFO - "basedir": "/builds/slave/test", 15:39:26 INFO - "buildnumber": 199, 15:39:26 INFO - "slavename": "talos-linux64-ix-032", 15:39:26 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 15:39:26 INFO - "platform": "ubuntu64_hw", 15:39:26 INFO - "scheduler": "tests-autoland-linux64-talos", 15:39:26 INFO - "branch": "autoland", 15:39:26 INFO - "repo_path": "integration/autoland", 15:39:26 INFO - "revision": "d1274a312b6b21d58c94357c2cccf78fb13af9c2", 15:39:26 INFO - "stage_platform": "linux64", 15:39:26 INFO - "builduid": "96d5b165200a41dbb2639bc91c6b7057", 15:39:26 INFO - "slavebuilddir": "test" 15:39:26 INFO - } 15:39:26 INFO - Found installer url https://queue.taskcluster.net/v1/task/dqtt3hmnRH-W9Yl1puYrUA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 15:39:26 INFO - Running post-action listener: _resource_record_post_action 15:39:26 INFO - [mozharness: 2016-08-08 22:39:26.825421Z] Finished read-buildbot-config step (success) 15:39:26 INFO - [mozharness: 2016-08-08 22:39:26.825529Z] Running download-and-extract step. 15:39:26 INFO - Running pre-action listener: _resource_record_pre_action 15:39:26 INFO - Running main action method: download_and_extract 15:39:26 INFO - mkdir: /builds/slave/test/build/tests 15:39:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:39:26 INFO - trying https://queue.taskcluster.net/v1/task/dqtt3hmnRH-W9Yl1puYrUA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 15:39:26 INFO - Downloading https://queue.taskcluster.net/v1/task/dqtt3hmnRH-W9Yl1puYrUA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 15:39:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dqtt3hmnRH-W9Yl1puYrUA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 15:39:27 INFO - Downloaded 1361 bytes. 15:39:27 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 15:39:27 INFO - Using the following test package requirements: 15:39:27 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 15:39:27 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 15:39:27 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 15:39:27 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 15:39:27 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 15:39:27 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 15:39:27 INFO - u'jsshell-linux-x86_64.zip'], 15:39:27 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 15:39:27 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 15:39:27 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 15:39:27 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 15:39:27 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 15:39:27 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 15:39:27 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 15:39:27 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 15:39:27 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 15:39:27 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 15:39:27 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 15:39:27 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 15:39:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:39:27 INFO - trying https://queue.taskcluster.net/v1/task/dqtt3hmnRH-W9Yl1puYrUA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 15:39:27 INFO - Downloading https://queue.taskcluster.net/v1/task/dqtt3hmnRH-W9Yl1puYrUA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 15:39:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dqtt3hmnRH-W9Yl1puYrUA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 15:39:28 INFO - Downloaded 20258190 bytes. 15:39:28 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:39:28 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 15:39:29 INFO - Return code: 0 15:39:29 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 15:39:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:39:29 INFO - trying https://queue.taskcluster.net/v1/task/dqtt3hmnRH-W9Yl1puYrUA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 15:39:29 INFO - Downloading https://queue.taskcluster.net/v1/task/dqtt3hmnRH-W9Yl1puYrUA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 15:39:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dqtt3hmnRH-W9Yl1puYrUA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 15:39:30 INFO - Downloaded 20258190 bytes. 15:39:30 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:39:30 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 15:39:31 INFO - Return code: 0 15:39:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:39:31 INFO - trying https://queue.taskcluster.net/v1/task/dqtt3hmnRH-W9Yl1puYrUA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 15:39:31 INFO - Downloading https://queue.taskcluster.net/v1/task/dqtt3hmnRH-W9Yl1puYrUA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 15:39:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dqtt3hmnRH-W9Yl1puYrUA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 15:39:32 INFO - Downloaded 14413723 bytes. 15:39:32 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:39:32 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 15:39:32 INFO - Return code: 0 15:39:32 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')]} 15:39:32 INFO - trying https://queue.taskcluster.net/v1/task/dqtt3hmnRH-W9Yl1puYrUA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 15:39:32 INFO - Downloading https://queue.taskcluster.net/v1/task/dqtt3hmnRH-W9Yl1puYrUA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 15:39:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dqtt3hmnRH-W9Yl1puYrUA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 15:39:51 WARNING - URL Error: https://queue.taskcluster.net/v1/task/dqtt3hmnRH-W9Yl1puYrUA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 15:39:51 INFO - Running command: ['nslookup', 'queue.taskcluster.net'] 15:39:51 INFO - Copy/paste: nslookup queue.taskcluster.net 15:39:51 INFO - Server: 10.26.75.40 15:39:51 INFO - Address: 10.26.75.40#53 15:39:51 INFO - queue.taskcluster.net canonical name = toyama-73636.herokussl.com. 15:39:51 INFO - toyama-73636.herokussl.com canonical name = elb081832-1105536650.us-east-1.elb.amazonaws.com. 15:39:51 INFO - Name: elb081832-1105536650.us-east-1.elb.amazonaws.com 15:39:51 INFO - Address: 54.235.199.202 15:39:51 INFO - Name: elb081832-1105536650.us-east-1.elb.amazonaws.com 15:39:51 INFO - Address: 23.21.158.67 15:39:51 INFO - Name: elb081832-1105536650.us-east-1.elb.amazonaws.com 15:39:51 INFO - Address: 23.23.159.234 15:39:51 INFO - Return code: 0 15:39:51 INFO - retry: attempt #1 caught exception: 15:39:51 INFO - retry: Failed, sleeping 30 seconds before retrying 15:40:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dqtt3hmnRH-W9Yl1puYrUA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #2 15:40:24 INFO - Downloaded 56138746 bytes. 15:40:24 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/dqtt3hmnRH-W9Yl1puYrUA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 15:40:24 INFO - mkdir: /builds/slave/test/properties 15:40:24 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:40:24 INFO - Writing to file /builds/slave/test/properties/build_url 15:40:24 INFO - Contents: 15:40:24 INFO - build_url:https://queue.taskcluster.net/v1/task/dqtt3hmnRH-W9Yl1puYrUA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 15:40:24 INFO - Running post-action listener: _resource_record_post_action 15:40:24 INFO - Running post-action listener: set_extra_try_arguments 15:40:24 INFO - [mozharness: 2016-08-08 22:40:24.902052Z] Finished download-and-extract step (success) 15:40:24 INFO - [mozharness: 2016-08-08 22:40:24.902286Z] Running populate-webroot step. 15:40:24 INFO - Running pre-action listener: _resource_record_pre_action 15:40:24 INFO - Running main action method: populate_webroot 15:40:24 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:40:24 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:40:24 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:40:24 INFO - '--tppagecycles', 15:40:24 INFO - '7'], 15:40:24 INFO - 'tests': ['tsvgm']}}, 15:40:24 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:40:24 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:40:24 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:40:24 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:40:24 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:40:24 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:40:24 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:40:24 INFO - 'talos_options': ['--disable-e10s'], 15:40:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:40:24 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:40:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:40:24 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:40:24 INFO - 'talos_options': ['--disable-e10s'], 15:40:24 INFO - 'tests': ['damp', 'tps']}, 15:40:24 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:40:24 INFO - 'tests': ['damp', 'tps']}, 15:40:24 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:40:24 INFO - 'tests': ['dromaeo_dom']}, 15:40:24 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:40:24 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:40:24 INFO - 'tests': ['basic_compositor_video']}, 15:40:24 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:40:24 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:40:24 INFO - 'tests': ['a11yr', 15:40:24 INFO - 'ts_paint', 15:40:24 INFO - 'tpaint', 15:40:24 INFO - 'sessionrestore', 15:40:24 INFO - 'sessionrestore_no_auto_restore', 15:40:24 INFO - 'tabpaint']}, 15:40:24 INFO - 'other-e10s': {'tests': ['a11yr', 15:40:24 INFO - 'ts_paint', 15:40:24 INFO - 'tpaint', 15:40:24 INFO - 'sessionrestore', 15:40:24 INFO - 'sessionrestore_no_auto_restore', 15:40:24 INFO - 'tabpaint']}, 15:40:24 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:40:24 INFO - 'ts_paint', 15:40:24 INFO - 'tpaint', 15:40:24 INFO - 'sessionrestore', 15:40:24 INFO - 'sessionrestore_no_auto_restore', 15:40:24 INFO - 'tabpaint']}, 15:40:24 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:40:24 INFO - 'ts_paint', 15:40:24 INFO - 'tpaint', 15:40:24 INFO - 'sessionrestore', 15:40:24 INFO - 'sessionrestore_no_auto_restore', 15:40:24 INFO - 'tabpaint']}, 15:40:24 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:40:24 INFO - 'tests': ['a11yr', 15:40:24 INFO - 'ts_paint', 15:40:24 INFO - 'tpaint', 15:40:24 INFO - 'sessionrestore', 15:40:24 INFO - 'sessionrestore_no_auto_restore', 15:40:24 INFO - 'tabpaint']}, 15:40:24 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:40:24 INFO - 'tests': ['a11yr', 15:40:24 INFO - 'ts_paint', 15:40:24 INFO - 'tpaint', 15:40:24 INFO - 'sessionrestore', 15:40:24 INFO - 'sessionrestore_no_auto_restore', 15:40:24 INFO - 'tabpaint']}, 15:40:24 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:40:24 INFO - 'tests': ['tsvgx', 15:40:24 INFO - 'tsvgr_opacity', 15:40:24 INFO - 'tart', 15:40:24 INFO - 'tscrollx', 15:40:24 INFO - 'cart']}, 15:40:24 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:40:24 INFO - 'tsvgr_opacity', 15:40:24 INFO - 'tart', 15:40:24 INFO - 'tscrollx', 15:40:24 INFO - 'cart']}, 15:40:24 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:40:24 INFO - 'talos_options': ['--disable-e10s'], 15:40:24 INFO - 'tests': ['tp5o']}, 15:40:24 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:40:24 INFO - 'tests': ['tp5o']}, 15:40:24 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:40:24 INFO - 'talos_options': ['--disable-e10s', 15:40:24 INFO - '--xperf_path', 15:40:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:40:24 INFO - 'tests': ['tp5n']}, 15:40:24 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:40:24 INFO - 'talos_options': ['--xperf_path', 15:40:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:40:24 INFO - 'tests': ['tp5n']}}, 15:40:24 INFO - 'talos.zip': {'path': '', 15:40:24 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:40:24 INFO - Running post-action listener: _resource_record_post_action 15:40:24 INFO - [mozharness: 2016-08-08 22:40:24.917758Z] Finished populate-webroot step (success) 15:40:24 INFO - [mozharness: 2016-08-08 22:40:24.917846Z] Running create-virtualenv step. 15:40:24 INFO - Running pre-action listener: _resource_record_pre_action 15:40:24 INFO - Running main action method: create_virtualenv 15:40:24 INFO - Creating virtualenv /builds/slave/test/build/venv 15:40:24 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 15:40:24 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:40:25 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:40:25 INFO - Using real prefix '/usr' 15:40:25 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:40:25 INFO - Installing distribute.............................................................................................................................................................................................done. 15:40:27 INFO - Installing pip.................done. 15:40:27 INFO - Return code: 0 15:40:27 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:40:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:40:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:40:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:40:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:40:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40:27 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 0x28cb030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a2e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b8c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b8a3b0>, '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 0x2b8b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b8b740>, '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': '745a4373a2a16df560f216e000000264-1470695867.787586-1429825616', '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 15:40:27 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 15:40:27 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 15:40:27 INFO - Using env: {'DISPLAY': ':0', 15:40:27 INFO - 'HOME': '/home/cltbld', 15:40:27 INFO - 'LANG': 'en_US.UTF-8', 15:40:27 INFO - 'LANGUAGE': 'en_US:en', 15:40:27 INFO - 'LOGNAME': 'cltbld', 15:40:27 INFO - 'MAIL': '/var/mail/cltbld', 15:40:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:40:27 INFO - 'MOZ_NO_REMOTE': '1', 15:40:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:40:27 INFO - 'NO_EM_RESTART': '1', 15:40:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:40:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:40:27 INFO - 'PWD': '/builds/slave/test', 15:40:27 INFO - 'SHELL': '/bin/bash', 15:40:27 INFO - 'SHLVL': '1', 15:40:27 INFO - 'TERM': 'linux', 15:40:27 INFO - 'TMOUT': '86400', 15:40:27 INFO - 'USER': 'cltbld', 15:40:27 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1470695867.787586-1429825616', 15:40:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:40:27 INFO - '_': '/tools/buildbot/bin/python'} 15:40:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:40:27 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 15:40:27 INFO - Cleaning up... 15:40:27 INFO - Return code: 0 15:40:27 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:40:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:40:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:40:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:40:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:40:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40:27 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 0x28cb030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a2e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b8c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b8a3b0>, '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 0x2b8b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b8b740>, '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': '745a4373a2a16df560f216e000000264-1470695867.787586-1429825616', '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 15:40:27 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 15:40:27 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 15:40:27 INFO - Using env: {'DISPLAY': ':0', 15:40:27 INFO - 'HOME': '/home/cltbld', 15:40:27 INFO - 'LANG': 'en_US.UTF-8', 15:40:27 INFO - 'LANGUAGE': 'en_US:en', 15:40:27 INFO - 'LOGNAME': 'cltbld', 15:40:27 INFO - 'MAIL': '/var/mail/cltbld', 15:40:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:40:27 INFO - 'MOZ_NO_REMOTE': '1', 15:40:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:40:27 INFO - 'NO_EM_RESTART': '1', 15:40:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:40:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:40:27 INFO - 'PWD': '/builds/slave/test', 15:40:27 INFO - 'SHELL': '/bin/bash', 15:40:27 INFO - 'SHLVL': '1', 15:40:27 INFO - 'TERM': 'linux', 15:40:27 INFO - 'TMOUT': '86400', 15:40:27 INFO - 'USER': 'cltbld', 15:40:27 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1470695867.787586-1429825616', 15:40:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:40:27 INFO - '_': '/tools/buildbot/bin/python'} 15:40:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:40:27 INFO - Downloading/unpacking psutil>=3.1.1 15:40:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:40:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:40:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:40:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:40:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:40:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:40:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:40:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:40:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:40:29 INFO - Installing collected packages: psutil 15:40:29 INFO - Running setup.py install for psutil 15:40:29 INFO - building 'psutil._psutil_linux' extension 15:40:29 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 15:40:30 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 15:40:30 INFO - building 'psutil._psutil_posix' extension 15:40:30 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 15:40:30 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 15:40:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:40:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:40:30 INFO - Successfully installed psutil 15:40:30 INFO - Cleaning up... 15:40:30 INFO - Return code: 0 15:40:30 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:40:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:40:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:40:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:40:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:40:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40:30 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 0x28cb030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a2e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b8c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b8a3b0>, '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 0x2b8b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b8b740>, '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': '745a4373a2a16df560f216e000000264-1470695867.787586-1429825616', '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 15:40:30 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 15:40:30 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 15:40:30 INFO - Using env: {'DISPLAY': ':0', 15:40:30 INFO - 'HOME': '/home/cltbld', 15:40:30 INFO - 'LANG': 'en_US.UTF-8', 15:40:30 INFO - 'LANGUAGE': 'en_US:en', 15:40:30 INFO - 'LOGNAME': 'cltbld', 15:40:30 INFO - 'MAIL': '/var/mail/cltbld', 15:40:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:40:30 INFO - 'MOZ_NO_REMOTE': '1', 15:40:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:40:30 INFO - 'NO_EM_RESTART': '1', 15:40:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:40:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:40:30 INFO - 'PWD': '/builds/slave/test', 15:40:30 INFO - 'SHELL': '/bin/bash', 15:40:30 INFO - 'SHLVL': '1', 15:40:30 INFO - 'TERM': 'linux', 15:40:30 INFO - 'TMOUT': '86400', 15:40:30 INFO - 'USER': 'cltbld', 15:40:30 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1470695867.787586-1429825616', 15:40:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:40:30 INFO - '_': '/tools/buildbot/bin/python'} 15:40:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:40:30 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:40:30 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 15:40:30 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 15:40:30 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 15:40:30 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 15:40:30 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 15:40:30 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 15:40:32 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:40:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:40:32 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:40:32 INFO - Installing collected packages: mozsystemmonitor 15:40:32 INFO - Running setup.py install for mozsystemmonitor 15:40:32 INFO - Successfully installed mozsystemmonitor 15:40:32 INFO - Cleaning up... 15:40:32 INFO - Return code: 0 15:40:32 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:40:32 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')]} 15:40:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:40:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40:32 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')]} 15:40:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:40:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40:32 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 0x28cb030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a2e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b8c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b8a3b0>, '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 0x2b8b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b8b740>, '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': '745a4373a2a16df560f216e000000264-1470695867.787586-1429825616', '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 15:40:32 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 15:40:32 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 15:40:32 INFO - Using env: {'DISPLAY': ':0', 15:40:32 INFO - 'HOME': '/home/cltbld', 15:40:32 INFO - 'LANG': 'en_US.UTF-8', 15:40:32 INFO - 'LANGUAGE': 'en_US:en', 15:40:32 INFO - 'LOGNAME': 'cltbld', 15:40:32 INFO - 'MAIL': '/var/mail/cltbld', 15:40:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:40:32 INFO - 'MOZ_NO_REMOTE': '1', 15:40:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:40:32 INFO - 'NO_EM_RESTART': '1', 15:40:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:40:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:40:32 INFO - 'PWD': '/builds/slave/test', 15:40:32 INFO - 'SHELL': '/bin/bash', 15:40:32 INFO - 'SHLVL': '1', 15:40:32 INFO - 'TERM': 'linux', 15:40:32 INFO - 'TMOUT': '86400', 15:40:32 INFO - 'USER': 'cltbld', 15:40:32 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1470695867.787586-1429825616', 15:40:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:40:32 INFO - '_': '/tools/buildbot/bin/python'} 15:40:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:40:32 INFO - Downloading/unpacking jsonschema==2.5.1 15:40:32 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 15:40:32 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 15:40:32 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 15:40:32 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 15:40:32 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 15:40:32 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 15:40:34 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:40:34 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:40:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:40:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:40:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:40:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:40:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:40:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:40:35 INFO - Downloading functools32-3.2.3-2.tar.gz 15:40:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:40:35 INFO - warning: no files found matching '*.txt' 15:40:35 INFO - no previously-included directories found matching 'build' 15:40:35 INFO - no previously-included directories found matching 'dist' 15:40:35 INFO - no previously-included directories found matching '.git*' 15:40:35 INFO - Installing collected packages: jsonschema, functools32 15:40:35 INFO - Running setup.py install for functools32 15:40:35 INFO - warning: no files found matching '*.txt' 15:40:35 INFO - no previously-included directories found matching 'build' 15:40:35 INFO - no previously-included directories found matching 'dist' 15:40:35 INFO - no previously-included directories found matching '.git*' 15:40:35 INFO - Successfully installed jsonschema functools32 15:40:35 INFO - Cleaning up... 15:40:35 INFO - Return code: 0 15:40:35 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:40:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:40:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:40:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:40:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:40:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40:35 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 0x28cb030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a2e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b8c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b8a3b0>, '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 0x2b8b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b8b740>, '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': '745a4373a2a16df560f216e000000264-1470695867.787586-1429825616', '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 15:40:35 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 15:40:35 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 15:40:35 INFO - Using env: {'DISPLAY': ':0', 15:40:35 INFO - 'HOME': '/home/cltbld', 15:40:35 INFO - 'LANG': 'en_US.UTF-8', 15:40:35 INFO - 'LANGUAGE': 'en_US:en', 15:40:35 INFO - 'LOGNAME': 'cltbld', 15:40:35 INFO - 'MAIL': '/var/mail/cltbld', 15:40:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:40:35 INFO - 'MOZ_NO_REMOTE': '1', 15:40:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:40:35 INFO - 'NO_EM_RESTART': '1', 15:40:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:40:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:40:35 INFO - 'PWD': '/builds/slave/test', 15:40:35 INFO - 'SHELL': '/bin/bash', 15:40:35 INFO - 'SHLVL': '1', 15:40:35 INFO - 'TERM': 'linux', 15:40:35 INFO - 'TMOUT': '86400', 15:40:35 INFO - 'USER': 'cltbld', 15:40:35 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1470695867.787586-1429825616', 15:40:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:40:35 INFO - '_': '/tools/buildbot/bin/python'} 15:40:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:40:35 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:40:35 INFO - Cleaning up... 15:40:35 INFO - Return code: 0 15:40:35 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:40:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:40:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:40:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:40:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:40:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40:35 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 0x28cb030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a2e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b8c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b8a3b0>, '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 0x2b8b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b8b740>, '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': '745a4373a2a16df560f216e000000264-1470695867.787586-1429825616', '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 15:40:35 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 15:40:35 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 15:40:35 INFO - Using env: {'DISPLAY': ':0', 15:40:35 INFO - 'HOME': '/home/cltbld', 15:40:35 INFO - 'LANG': 'en_US.UTF-8', 15:40:35 INFO - 'LANGUAGE': 'en_US:en', 15:40:35 INFO - 'LOGNAME': 'cltbld', 15:40:35 INFO - 'MAIL': '/var/mail/cltbld', 15:40:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:40:35 INFO - 'MOZ_NO_REMOTE': '1', 15:40:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:40:35 INFO - 'NO_EM_RESTART': '1', 15:40:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:40:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:40:35 INFO - 'PWD': '/builds/slave/test', 15:40:35 INFO - 'SHELL': '/bin/bash', 15:40:35 INFO - 'SHLVL': '1', 15:40:35 INFO - 'TERM': 'linux', 15:40:35 INFO - 'TMOUT': '86400', 15:40:35 INFO - 'USER': 'cltbld', 15:40:35 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1470695867.787586-1429825616', 15:40:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:40:35 INFO - '_': '/tools/buildbot/bin/python'} 15:40:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:40:35 INFO - Downloading/unpacking blobuploader==1.2.4 15:40:35 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 15:40:35 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 15:40:35 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 15:40:35 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 15:40:35 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 15:40:35 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 15:40:37 INFO - Downloading blobuploader-1.2.4.tar.gz 15:40:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:40:37 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:40:37 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 15:40:37 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 15:40:37 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 15:40:37 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 15:40:37 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 15:40:37 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 15:40:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:40:38 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:40: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 15:40: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 15:40: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 15:40: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 15:40: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 15:40: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 15:40:38 INFO - Downloading docopt-0.6.1.tar.gz 15:40:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:40:38 INFO - Installing collected packages: blobuploader, requests, docopt 15:40:38 INFO - Running setup.py install for blobuploader 15:40:38 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:40:38 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:40:38 INFO - Running setup.py install for requests 15:40:38 INFO - Running setup.py install for docopt 15:40:38 INFO - Successfully installed blobuploader requests docopt 15:40:38 INFO - Cleaning up... 15:40:39 INFO - Return code: 0 15:40:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:40:39 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')]} 15:40:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:40:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40:39 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')]} 15:40:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:40:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40:39 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 0x28cb030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a2e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b8c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b8a3b0>, '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 0x2b8b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b8b740>, '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': '745a4373a2a16df560f216e000000264-1470695867.787586-1429825616', '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 15:40:39 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 15:40:39 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 15:40:39 INFO - Using env: {'DISPLAY': ':0', 15:40:39 INFO - 'HOME': '/home/cltbld', 15:40:39 INFO - 'LANG': 'en_US.UTF-8', 15:40:39 INFO - 'LANGUAGE': 'en_US:en', 15:40:39 INFO - 'LOGNAME': 'cltbld', 15:40:39 INFO - 'MAIL': '/var/mail/cltbld', 15:40:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:40:39 INFO - 'MOZ_NO_REMOTE': '1', 15:40:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:40:39 INFO - 'NO_EM_RESTART': '1', 15:40:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:40:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:40:39 INFO - 'PWD': '/builds/slave/test', 15:40:39 INFO - 'SHELL': '/bin/bash', 15:40:39 INFO - 'SHLVL': '1', 15:40:39 INFO - 'TERM': 'linux', 15:40:39 INFO - 'TMOUT': '86400', 15:40:39 INFO - 'USER': 'cltbld', 15:40:39 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1470695867.787586-1429825616', 15:40:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:40:39 INFO - '_': '/tools/buildbot/bin/python'} 15:40:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:40:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:40:39 INFO - Running setup.py (path:/tmp/pip-hHe5P9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:40:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:40:39 INFO - Running setup.py (path:/tmp/pip-XFdFM0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:40:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:40:39 INFO - Running setup.py (path:/tmp/pip-d0sL0i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:40:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:40:39 INFO - Running setup.py (path:/tmp/pip-B4Dw4v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:40:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:40:39 INFO - Running setup.py (path:/tmp/pip-WD1siX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:40:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:40:39 INFO - Running setup.py (path:/tmp/pip-678ZWV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:40:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:40:39 INFO - Running setup.py (path:/tmp/pip-vC4YTf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:40:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:40:39 INFO - Running setup.py (path:/tmp/pip-XHhCx_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:40:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:40:39 INFO - Running setup.py (path:/tmp/pip-aYAAyO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:40:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:40:39 INFO - Running setup.py (path:/tmp/pip-269F7A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:40:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:40:39 INFO - Running setup.py (path:/tmp/pip-hEJer_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:40:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:40:40 INFO - Running setup.py (path:/tmp/pip-2JbsMv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:40:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:40:40 INFO - Running setup.py (path:/tmp/pip-zvhMrZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:40:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:40:40 INFO - Running setup.py (path:/tmp/pip-Ygl1ql-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:40:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:40:40 INFO - Running setup.py (path:/tmp/pip-1nHT7s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:40:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:40:40 INFO - Running setup.py (path:/tmp/pip-tKmW5D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:40:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:40:40 INFO - Running setup.py (path:/tmp/pip-nT1TYB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:40:40 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:40:40 INFO - Running setup.py install for manifestparser 15:40:40 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:40:40 INFO - Running setup.py install for mozcrash 15:40:40 INFO - Running setup.py install for mozdebug 15:40:40 INFO - Running setup.py install for mozdevice 15:40:40 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:40:40 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:40:40 INFO - Running setup.py install for mozfile 15:40:41 INFO - Running setup.py install for mozhttpd 15:40:41 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:40:41 INFO - Running setup.py install for mozinfo 15:40:41 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:40:41 INFO - Running setup.py install for mozInstall 15:40:41 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:40:41 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:40:41 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:40:41 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:40:41 INFO - Running setup.py install for mozleak 15:40:41 INFO - Running setup.py install for mozlog 15:40:41 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:40:41 INFO - Running setup.py install for moznetwork 15:40:41 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:40:41 INFO - Running setup.py install for mozprocess 15:40:41 INFO - Running setup.py install for mozprofile 15:40:41 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:40:41 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:40:41 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:40:41 INFO - Running setup.py install for mozrunner 15:40:41 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:40:41 INFO - Running setup.py install for mozscreenshot 15:40:42 INFO - Running setup.py install for moztest 15:40:42 INFO - Running setup.py install for mozversion 15:40:42 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:40:42 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:40:42 INFO - Cleaning up... 15:40:42 INFO - Return code: 0 15:40:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:40:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:40:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:40:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:40:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:40:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40:42 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 0x28cb030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a2e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b8c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b8a3b0>, '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 0x2b8b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b8b740>, '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': '745a4373a2a16df560f216e000000264-1470695867.787586-1429825616', '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 15:40:42 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 15:40:42 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 15:40:42 INFO - Using env: {'DISPLAY': ':0', 15:40:42 INFO - 'HOME': '/home/cltbld', 15:40:42 INFO - 'LANG': 'en_US.UTF-8', 15:40:42 INFO - 'LANGUAGE': 'en_US:en', 15:40:42 INFO - 'LOGNAME': 'cltbld', 15:40:42 INFO - 'MAIL': '/var/mail/cltbld', 15:40:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:40:42 INFO - 'MOZ_NO_REMOTE': '1', 15:40:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:40:42 INFO - 'NO_EM_RESTART': '1', 15:40:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:40:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:40:42 INFO - 'PWD': '/builds/slave/test', 15:40:42 INFO - 'SHELL': '/bin/bash', 15:40:42 INFO - 'SHLVL': '1', 15:40:42 INFO - 'TERM': 'linux', 15:40:42 INFO - 'TMOUT': '86400', 15:40:42 INFO - 'USER': 'cltbld', 15:40:42 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1470695867.787586-1429825616', 15:40:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:40:42 INFO - '_': '/tools/buildbot/bin/python'} 15:40:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:40:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:40:42 INFO - Running setup.py (path:/tmp/pip-ymifNl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:40:42 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)) 15:40:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:40:42 INFO - Running setup.py (path:/tmp/pip-5ZT46Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:40:42 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)) 15:40:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:40:42 INFO - Running setup.py (path:/tmp/pip-GPq0_p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:40:42 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)) 15:40:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:40:42 INFO - Running setup.py (path:/tmp/pip-HkZjeC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:40:42 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)) 15:40:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:40:42 INFO - Running setup.py (path:/tmp/pip-nktIrV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:40:42 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)) 15:40:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:40:42 INFO - Running setup.py (path:/tmp/pip-UIgRS3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:40:42 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)) 15:40:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:40:42 INFO - Running setup.py (path:/tmp/pip-BIbnvY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:40:42 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)) 15:40:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:40:43 INFO - Running setup.py (path:/tmp/pip-SdZQvF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:40:43 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)) 15:40:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:40:43 INFO - Running setup.py (path:/tmp/pip-X9XL6g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:40:43 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)) 15:40:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:40:43 INFO - Running setup.py (path:/tmp/pip-GRuGL7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:40:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:40:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:40:43 INFO - Running setup.py (path:/tmp/pip-lUCguK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:40:43 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)) 15:40:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:40:43 INFO - Running setup.py (path:/tmp/pip-kesnbZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:40:43 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)) 15:40:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:40:43 INFO - Running setup.py (path:/tmp/pip-nccX5P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:40:43 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)) 15:40:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:40:43 INFO - Running setup.py (path:/tmp/pip-sJWQOw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:40:43 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)) 15:40:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:40:43 INFO - Running setup.py (path:/tmp/pip-RCB9An-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:40:43 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)) 15:40:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:40:43 INFO - Running setup.py (path:/tmp/pip-5sYqVy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:40:43 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)) 15:40:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:40:43 INFO - Running setup.py (path:/tmp/pip-lCSI4d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:40:43 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)) 15:40:43 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)) 15:40:43 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)) 15:40:43 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)) 15:40:43 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)) 15:40:43 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)) 15:40:43 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:40: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 15:40: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 15:40: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 15:40: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 15:40: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 15:40: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 15:40:45 INFO - Downloading blessings-1.6.tar.gz 15:40:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:40:45 INFO - Installing collected packages: blessings 15:40:45 INFO - Running setup.py install for blessings 15:40:45 INFO - Successfully installed blessings 15:40:45 INFO - Cleaning up... 15:40:45 INFO - Return code: 0 15:40:45 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:40:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:40:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:40:46 INFO - Reading from file tmpfile_stdout 15:40:46 INFO - Current package versions: 15:40:46 INFO - argparse == 1.2.1 15:40:46 INFO - blessings == 1.6 15:40:46 INFO - blobuploader == 1.2.4 15:40:46 INFO - docopt == 0.6.1 15:40:46 INFO - functools32 == 3.2.3-2 15:40:46 INFO - jsonschema == 2.5.1 15:40:46 INFO - manifestparser == 1.1 15:40:46 INFO - mozInstall == 1.12 15:40:46 INFO - mozcrash == 0.17 15:40:46 INFO - mozdebug == 0.1 15:40:46 INFO - mozdevice == 0.48 15:40:46 INFO - mozfile == 1.2 15:40:46 INFO - mozhttpd == 0.7 15:40:46 INFO - mozinfo == 0.9 15:40:46 INFO - mozleak == 0.1 15:40:46 INFO - mozlog == 3.3 15:40:46 INFO - moznetwork == 0.27 15:40:46 INFO - mozprocess == 0.23 15:40:46 INFO - mozprofile == 0.28 15:40:46 INFO - mozrunner == 6.12 15:40:46 INFO - mozscreenshot == 0.1 15:40:46 INFO - mozsystemmonitor == 0.3 15:40:46 INFO - moztest == 0.7 15:40:46 INFO - mozversion == 1.4 15:40:46 INFO - psutil == 3.1.1 15:40:46 INFO - requests == 1.2.3 15:40:46 INFO - wsgiref == 0.1.2 15:40:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:40: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')]} 15:40:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:40:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40: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')]} 15:40:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:40:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40:46 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 0x28cb030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a2e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b8c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b8a3b0>, '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 0x2b8b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b8b740>, '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': '745a4373a2a16df560f216e000000264-1470695867.787586-1429825616', '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 15:40:46 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 15:40:46 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 15:40:46 INFO - Using env: {'DISPLAY': ':0', 15:40:46 INFO - 'HOME': '/home/cltbld', 15:40:46 INFO - 'LANG': 'en_US.UTF-8', 15:40:46 INFO - 'LANGUAGE': 'en_US:en', 15:40:46 INFO - 'LOGNAME': 'cltbld', 15:40:46 INFO - 'MAIL': '/var/mail/cltbld', 15:40:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:40:46 INFO - 'MOZ_NO_REMOTE': '1', 15:40:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:40:46 INFO - 'NO_EM_RESTART': '1', 15:40:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:40:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:40:46 INFO - 'PWD': '/builds/slave/test', 15:40:46 INFO - 'SHELL': '/bin/bash', 15:40:46 INFO - 'SHLVL': '1', 15:40:46 INFO - 'TERM': 'linux', 15:40:46 INFO - 'TMOUT': '86400', 15:40:46 INFO - 'USER': 'cltbld', 15:40:46 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1470695867.787586-1429825616', 15:40:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:40:46 INFO - '_': '/tools/buildbot/bin/python'} 15:40:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:40:46 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)) 15:40:46 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)) 15:40:46 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)) 15:40:46 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)) 15:40:46 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)) 15:40:46 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)) 15:40:46 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)) 15:40:46 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)) 15:40:46 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)) 15:40:46 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)) 15:40:46 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)) 15:40:46 INFO - Cleaning up... 15:40:46 INFO - Return code: 0 15:40:46 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:40: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')]} 15:40:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:40:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40: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')]} 15:40:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:40:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28cb030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a2e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b8c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b8a3b0>, '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 0x2b8b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b8b740>, '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': '745a4373a2a16df560f216e000000264-1470695867.787586-1429825616', '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 15:40: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', 'jsonschema'] in /builds/slave/test/build 15:40: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 jsonschema 15:40:46 INFO - Using env: {'DISPLAY': ':0', 15:40:46 INFO - 'HOME': '/home/cltbld', 15:40:46 INFO - 'LANG': 'en_US.UTF-8', 15:40:46 INFO - 'LANGUAGE': 'en_US:en', 15:40:46 INFO - 'LOGNAME': 'cltbld', 15:40:46 INFO - 'MAIL': '/var/mail/cltbld', 15:40:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:40:46 INFO - 'MOZ_NO_REMOTE': '1', 15:40:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:40:46 INFO - 'NO_EM_RESTART': '1', 15:40:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:40:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:40:46 INFO - 'PWD': '/builds/slave/test', 15:40:46 INFO - 'SHELL': '/bin/bash', 15:40:46 INFO - 'SHLVL': '1', 15:40:46 INFO - 'TERM': 'linux', 15:40:46 INFO - 'TMOUT': '86400', 15:40:46 INFO - 'USER': 'cltbld', 15:40:46 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1470695867.787586-1429825616', 15:40:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:40:46 INFO - '_': '/tools/buildbot/bin/python'} 15:40:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:40:46 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:40:46 INFO - Cleaning up... 15:40:46 INFO - Return code: 0 15:40:46 INFO - Running post-action listener: _resource_record_post_action 15:40:46 INFO - Running post-action listener: _start_resource_monitoring 15:40:46 INFO - Starting resource monitoring. 15:40:46 INFO - [mozharness: 2016-08-08 22:40:46.534101Z] Finished create-virtualenv step (success) 15:40:46 INFO - [mozharness: 2016-08-08 22:40:46.534589Z] Running install step. 15:40:46 INFO - Running pre-action listener: _resource_record_pre_action 15:40:46 INFO - Running main action method: install 15:40:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:40:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:40:46 INFO - Reading from file tmpfile_stdout 15:40:46 INFO - Detecting whether we're running mozinstall >=1.0... 15:40:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:40:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:40:46 INFO - Reading from file tmpfile_stdout 15:40:46 INFO - Output received: 15:40:46 INFO - Usage: mozinstall [options] installer 15:40:46 INFO - Options: 15:40:46 INFO - -h, --help show this help message and exit 15:40:46 INFO - -d DEST, --destination=DEST 15:40:46 INFO - Directory to install application into. [default: 15:40:46 INFO - "/builds/slave/test"] 15:40:46 INFO - --app=APP Application being installed. [default: firefox] 15:40:46 INFO - mkdir: /builds/slave/test/build/application 15:40:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 15:40:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 15:41:03 INFO - Reading from file tmpfile_stdout 15:41:03 INFO - Output received: 15:41:03 INFO - /builds/slave/test/build/application/firefox/firefox 15:41:03 INFO - Running post-action listener: _resource_record_post_action 15:41:03 INFO - [mozharness: 2016-08-08 22:41:03.008653Z] Finished install step (success) 15:41:03 INFO - [mozharness: 2016-08-08 22:41:03.008902Z] Running run-tests step. 15:41:03 INFO - Running pre-action listener: _resource_record_pre_action 15:41:03 INFO - Running main action method: run_tests 15:41:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:41:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:41:03 INFO - Python 2.7.3 15:41:03 INFO - Return code: 0 15:41:03 INFO - grabbing minidump binary from tooltool 15:41:03 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')]} 15:41:03 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 0x2b8a3b0>, '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 0x2b8b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b8b740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:41:03 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 15:41:03 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 15:41:03 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 15:41:03 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:41:03 INFO - Return code: 0 15:41:03 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 15:41:03 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:41:03 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:41:03 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 15:41:03 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:41:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/dqtt3hmnRH-W9Yl1puYrUA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-032', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 15:41:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/dqtt3hmnRH-W9Yl1puYrUA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-032 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 15:41:03 INFO - Using env: {'DISPLAY': ':0', 15:41:03 INFO - 'HOME': '/home/cltbld', 15:41:03 INFO - 'LANG': 'en_US.UTF-8', 15:41:03 INFO - 'LANGUAGE': 'en_US:en', 15:41:03 INFO - 'LOGNAME': 'cltbld', 15:41:03 INFO - 'MAIL': '/var/mail/cltbld', 15:41:03 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:41:03 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:41:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:41:03 INFO - 'MOZ_NO_REMOTE': '1', 15:41:03 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:41:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:41:03 INFO - 'NO_EM_RESTART': '1', 15:41:03 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:41:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:41:03 INFO - 'PWD': '/builds/slave/test', 15:41:03 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:41:03 INFO - 'SHELL': '/bin/bash', 15:41:03 INFO - 'SHLVL': '1', 15:41:03 INFO - 'TERM': 'linux', 15:41:03 INFO - 'TMOUT': '86400', 15:41:03 INFO - 'USER': 'cltbld', 15:41:03 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1470695867.787586-1429825616', 15:41:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:41:03 INFO - '_': '/tools/buildbot/bin/python'} 15:41:03 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/dqtt3hmnRH-W9Yl1puYrUA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-032', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 15:41:03 INFO - mozversion application_buildid: 20160808135726 15:41:03 INFO - mozversion application_changeset: d1274a312b6b21d58c94357c2cccf78fb13af9c2 15:41:03 INFO - mozversion application_display_name: Nightly 15:41:03 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:41:03 INFO - mozversion application_name: Firefox 15:41:03 INFO - mozversion application_remotingname: firefox 15:41:03 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 15:41:03 INFO - mozversion application_vendor: Mozilla 15:41:03 INFO - mozversion application_version: 51.0a1 15:41:03 INFO - mozversion platform_buildid: 20160808135726 15:41:03 INFO - mozversion platform_changeset: d1274a312b6b21d58c94357c2cccf78fb13af9c2 15:41:03 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 15:41:03 INFO - mozversion platform_version: 51.0a1 15:41:03 INFO - using testdate: 1470696063 15:41:03 INFO - actual date: 1470696063 15:41:03 INFO - starting webserver on 'localhost:45810' 15:41:03 INFO - SUITE-START | Running 2 tests 15:41:03 INFO - TEST-START | dromaeo_css 15:41:03 INFO - operating with platform_type : linux_ 15:41:03 INFO - Initialising browser for dromaeo_css test... 15:41:03 INFO - TEST-INFO | started process 23971 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpfNtVSg/profile http://localhost:45810/getInfo.html) 15:41:08 INFO - PROCESS | 23971 | __metrics Screen width/height:1600/1200 15:41:08 INFO - PROCESS | 23971 | colorDepth:24 15:41:08 INFO - PROCESS | 23971 | Browser inner width/height: 1024/697 15:41:08 INFO - PROCESS | 23971 | __metrics 15:41:09 INFO - PROCESS | 23971 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 15:41:09 INFO - PROCESS | 23971 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:41:10 INFO - TEST-INFO | 23971: exit 0 15:41:10 INFO - Browser initialized. 15:41:10 INFO - Running cycle 1/1 for dromaeo_css test... 15:41:10 INFO - TEST-INFO | started process 24050 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpfNtVSg/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:41:22 INFO - PROCESS | 24050 | RSS: Main: 158437376 15:41:22 INFO - PROCESS | 24050 | 15:42:12 INFO - PROCESS | 24050 | 1470696132994 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] 15:44:26 INFO - PROCESS | 24050 | Cycle 1(1): loaded http://localhost:45810/tests/dromaeo/cssquery-dojo.html (next: http://localhost:45810/tests/dromaeo/cssquery-ext.html) 15:44:27 INFO - PROCESS | 24050 | RSS: Main: 278028288 15:44:27 INFO - PROCESS | 24050 | 15:47:30 INFO - PROCESS | 24050 | Cycle 1(1): loaded http://localhost:45810/tests/dromaeo/cssquery-ext.html (next: http://localhost:45810/tests/dromaeo/cssquery-jquery.html) 15:47:30 INFO - PROCESS | 24050 | RSS: Main: 252452864 15:47:30 INFO - PROCESS | 24050 | 15:50:33 INFO - PROCESS | 24050 | Cycle 1(1): loaded http://localhost:45810/tests/dromaeo/cssquery-jquery.html (next: http://localhost:45810/tests/dromaeo/cssquery-mootools.html) 15:50:33 INFO - PROCESS | 24050 | RSS: Main: 223928320 15:50:33 INFO - PROCESS | 24050 | 15:53:35 INFO - PROCESS | 24050 | Cycle 1(1): loaded http://localhost:45810/tests/dromaeo/cssquery-mootools.html (next: http://localhost:45810/tests/dromaeo/cssquery-prototype.html) 15:53:36 INFO - PROCESS | 24050 | RSS: Main: 225710080 15:53:36 INFO - PROCESS | 24050 | 15:56:39 INFO - PROCESS | 24050 | Cycle 1(1): loaded http://localhost:45810/tests/dromaeo/cssquery-prototype.html (next: http://localhost:45810/tests/dromaeo/cssquery-yui.html) 15:56:40 INFO - PROCESS | 24050 | RSS: Main: 191152128 15:56:40 INFO - PROCESS | 24050 | 15:59:43 INFO - PROCESS | 24050 | Cycle 1(1): loaded http://localhost:45810/tests/dromaeo/cssquery-yui.html (next: http://localhost:45810/tests/dromaeo/cssquery-dojo.html) 15:59:43 INFO - PROCESS | 24050 | RSS: Main: 280973312 15:59:43 INFO - PROCESS | 24050 | 15:59:43 INFO - PROCESS | 24050 | __start_tp_report 15:59:43 INFO - PROCESS | 24050 | _x_x_mozilla_page_load 15:59:43 INFO - PROCESS | 24050 | _x_x_mozilla_page_load_details 15:59:43 INFO - PROCESS | 24050 | |i|pagename|runs| 15:59:43 INFO - PROCESS | 24050 | |0;dojo.html;2607.11358138233;2624.6719160104985;2647.7352264773517;2647.9073232436867;2662.960055599165;4571.9314210286875;4620.353150558916;4741.359916411284;4768.427788665356;4819.469858315582;4615.146197953381;4619.237825758746;4669.603083737887;4682.695624784386;4737.218358970775;4283.7215580987295;4332.740035597859;4353.978230108861;4384.4300240968705;4403.031333106712;4356.411884395614;4361.978190109045;4384.079343337903;4394.450693663292;4444.177827101997;4506.053728716974;4509.1883460977015;4525.592696657301;4546.1589605922845;4547.590716835484;4228.133232687292;4287.63555097814;4290.613844753956;4378.124375124956;4435.8225670973125;4255.063885945118;4271.209826182167;4294.291441912081;4345.84789532368;4356.43366362374;4702.553257440538;4706.10583989041;4707.693999890031;4750.548697873696;4754.144254034272;4795.304680821297;5012.147934851084;5034.949650503521;5049.64652474327;5099.08216521026;4940.580050695695;4978.800847966077;5000.949990500121;5048.621353398473;5103.56619687327;4670.322803193518;4709.105269998758;4744.098621261949;4754.120487709784;4758.333833263346;4982.3522942018035;5091.363579552556;5222.164453687392;5223.268742376071;5229.241759944786;4939.90120197594;5019.799208031638;5033.270175824485;5035.622328325317;5039.697618142924;5043.420006699276;5063.544281014726;5068.163752980717;5093.516115968977;5117.692938423632;4831.251156070814;4869.342638743724;5029.673071250357;5042.621803364762;5085.059264036166;4938.456769755324;4997.875053123701;5008.273800298936;5074.974625126851;5077.339945807095;4353.455818022747;4359.106383191438;4360.956390436118;4396.846110386121;4403.713758605681;3795.411711184771;3835.9424608630893;3844.9807750961627;3846;3858.266929283427;4162.875113746592;4303.63419109373;4346.891327716832;4358.259095953696;4370.038591509863;5100.770465329069;5101.591872650179;5106.183010718267;5106.489351064938;5137.614678899097;4364.759938203366;4370.38814565961;4372.693911426233;4399.38408622793;4416.028473735837;4466.0844526871915;4551.158035763395;4593;4597.0346227291975;4613.284940834176;4774.379330686988;4796.400449943757;4798.208295631322;4837;4842.201036829025;5079.466656001066;5114.923276150935;5125.871853203551;5126.923096153635;5130.333056702605;4584.977075114603;4680.485146633936;4714.952850471589;4721.976390118028;4839.201531747363;4954.851354459372;4965.925511117264;4970.403551573834;4971.353724015855;5007.499250074963;5017.272495488207;5029.64792464524;5030.0694371541385;5057.6459647824295;5064.240363945438;4639.466461356988;4771.475137734916;4890.975545122394;4951.529604687549;4953.108440480748;4944.28307895346;4945.208766597327;4957.330760347307;4963.751812409438;5000.499950004971;4923.876903077452;4945.455999839943;4956.281339205867;4968.950310496849;4969.353983982204;4763;4825.879353016203;4849.151398505177;4887.315775791321;4888.486708895514;4790.377250957354;4801.135795556694;4807.4471435785335;4856.660033797601;4869.513048695102 15:59:43 INFO - PROCESS | 24050 | |1;ext.html;558.6480517274117;563.833669067625;566.119683891549;572.1275055540301;575.0597772641729;26346;38606.80696596517;39753.60246397535;39832;43415;39697;40024.19951600966;40039.39940900889;40100;40119.79940100295;7661.348785353238;7800.921990780104;7806.102298235719;7807.297343239135;7811.06267247931;7625.123110842241;7726.497777644463;7727.922720772776;7729.188435214306;7739.806504837396;7709.768706938801;7725.922740772605;7733.955915951359;7739.7678069658;7750.922490775077;7686.846263074735;7691.269329413697;7730.304272615492;7737.961310193441;7746.845063098763;7274.127104747464;7288.234735352765;7309.159446663595;7334.596597187153;7355.227701091415;36929.815350923345;37628.62371376278;37634.43548346777;37659.43510847339;37697.62302376996;2516.836405633628;2552.629868669051;2640.115561286971;2793.916182514528;2793.9441211175867;28146.43707125846;28203.15390538287;28586.428271434663;28653.570196447068;28943.421131577463;3030.8939187128344;3106.922326941822;3109.673484284163;3111.24563954209;3115.7005118438424;255804;256760;257009;257850;258356;49199.31005174626;49733;49807;49884;49930.50069499259;32427.837860810545;53250;53389.46610533845;53404;53457.7327113362;13307.534236301683;14161.645958851112;14203.727403615372;14208.07647502909;14222.2177780221;10535.736606584744;10803.729906752395;10811;10816.35101893873;10820.945895270474;17566;17581.472555823344;17583.73624395635;17731;17766.46700598984;6352.552159558052;6419;6574.3754343337305;6583.44040756541;6624.900626490607;777.9494332868345;835.8620827563384;844.3203221406703;918.5177781664679;941.1717688434134;26151.476970460484;26403.603945940828;26969.73030269672;27079.1876243713;27086.458270834868;848.3637272045966;924.3021518753299;944.5843828715343;946.3233787841726;999.7600575861741;13389.263590502422;14071.788923166161;14183.114960911675;14322.283885805877;14342.641433964234;296443;299848;301906;302384;303016;46292.768536157106;46701.5329846704;46790;46809.999999999316;46820.06359872784;1163.7672465506932;1171.262104873924;1173.7124404520775;1175;1177.264209868832;25055.624165637164;25794.226173214833;26153.215403537924;26171.869140654177;26453.603195952463;39383;39396.40905386479;39761.80119099386;39762.801185993885;39772;264808;266727;267031;267259;267347;24202.515949680557;24373.26880193597;24653.753462465145;24685.753142468344;24730.381740456633;39296.214075717755;39815.402768959066;40159.99600010023;40300;40362.798186008884;32457.837710811295;33116.33767324688;33591.83204083964;33903.03278729546;34120.65879341175;33530.49704254487;33539.4969075469;33794.831025844716;34424.48363274603;35335.29329413346;29922.70077299199;30019.099427017223;30085.548716769703;30283.848580757836;34096.82951585226;28266.85866570736;32359.838200808845;32911.177220569676;33010.66989330172;33172.336553269284;24476.91946184523;24822;24921.376965576004;25154.74845251524;25324.746752532978 15:59:43 INFO - PROCESS | 24050 | |2;jquery.html;340.17677221124876;352.20401891724697;361.5264004154557;369.3517876127396;387.87200223926106;12940.158889672177;17129.48611541654;17627.383041593548;18255.72616410755;19204.9039754801;15547.53357399277;15602.297896594651;15867.206639668027;15874.444394446196;15992.680146397093;5029.346184995946;5031.698098114116;5156.226566015109;5195.610329225305;5199.272101905736;3764.6047165047685;5133.743312834362;5202.687838729669;5212.973935130338;5701.657900525961;5933.673647949361;6034.969825150868;6055.5761096723245;6056.364081771417;6058.727357268934;5809.3609702932645;5879.088741245114;5915.260592425947;5930.05119180929;6029.216201893748;5173.870653233662;5178.6116041297055;5179.7410129493755;5181.326427564431;5182.844514664528;20957.47606309839;21561.67657485139;21656.566868662696;21695.566088678137;21760.564788704294;1874.6531891600098;1938.0503553258943;2120.703101565782;2168.111074459464;2382.594958856981;13585.388657510435;13675.179489230579;13846.515371961934;13884.23636699981;14558.272086395744;1777.4223377402395;1798.5323815807853;1801.6306657135256;1827.7532533107992;1881.9435416937513;68870.65564672195;69767;69913.3008669912;70327.64836175786;71401.64299178522;9478.478683672327;9484.715458536255;9697.321187516805;9920.603175873028;9972.30193886421;13161.47354105825;13240.53658121961;13282;13307.866921330857;13309;7154.499185056996;7453.478256521991;7501.324880760758;7548.509346892434;7612.581308028;7048.189458212269;7155.642217889084;7281.490295679251;7308.96345518269;7324.890126648104;6047.909281360783;6176.166217560572;6251.28110267316;6302.842428939313;6463.287228884349;1712.5547357687092;1719.9532556258014;1729.9740503892451;1767.22545603404;1779.741937419067;830.3855147191035;832.0597724571317;876.0932434929945;927.8793756811706;929.6327950459531;16855.66288674244;18020.36928707489;18931.43205703831;18974.14616342268;18996.62006759857;855.2644725536034;872.9519876406858;896.7489103051156;915.3272344826547;923.6720964057797;9110.49892255919;10348.172146228284;10357.326773759682;10381.9098994606;10735.677929662123;382812;389578;390146;424133.3102578671;424458;49385;79237;79685.60157199176;80858.59570702226;81309;874.2044739287345;883.7878909061778;908.7137551671335;908.8727578139066;914.4650379527918;15769.95265429713;15909.284082216096;16254.593635159215;16856.241469134162;17012.149392530573;38525;38547.79604753434;38730.41904371493;39605.60394396019;39702.20595588014;52580.47419525756;53019;53247.46752532425;53324.46675533195;54937;7139.286071392819;7169.243535333877;7176.677049532888;7339.926600733925;7359.963200183965;9347.626094956126;12088;12120.636380908587;12264.693382665504;12357.32034738098;9651.372660777028;9845.212383009486;10026.398416095057;10205.285630005828;10285.228607854591;10220.131288840234;10445.425501597181;10700.678979630624;10755.085817705583;11081.224314297922;9308.162265396148;9816.65641702537;9869.98965315557;10020.949895250476;10108.191344692259;6787;7245.927540724525;7264.382527485223;7339.816504587428;7495.812604684927;8015.358771298165;9707.6116955321;9739.756506087404;9799.85300220483;9817.803643927224 15:59:43 INFO - PROCESS | 24050 | |3;mootools.html;1196.4675719304912;1261.949522019119;1318.5055604148445;1343.4156142078195;1652.958676033099;6332.905006424908;7339.522931009487;7598.696052157921;7610.88583671244;7841.451098423113;7647.808804779883;7715.035620547432;7716.884246736304;7718.614069296541;7725.652345644446;2147.8818664973383;2175.2169219081197;2175.749788774295;2178.1505212966954;2178.2593918067855;2099.6745504446835;2143.7749036351115;2163.5348400093976;2163.8593491423007;2164.8701077935375;2066.173530587762;2081.4172031831113;2083.760367557735;2087.3946555498965;2115.9471013224716;2088;2091.1008266445424;2092.9058192381226;2093.288281984125;2093.4347726114015;2023.1300540767463;2051.825594824442;2052.7331446911953;2055.856090073695;2056.167252262836;7573.697052117908;7600.467967242295;7661.425393095485;7715.6913723451;7827.217278272185;4127.236461254677;4310.849120280806;4317.956820431787;4327.848525301631;4328.7835608219775;2672.50558646651;2773.1264651634874;2814.057290807582;2831.476176907279;2840.4745122152467;885.3979294080023;886.3308202307281;897.5153417154729;898.8112496375769;909.6725178935578;10347.482625868752;10431.634892778717;10451.320664156881;10471.528781204865;10472.424016679079;3646.3254297954963;3791.7724936503346;3963.365861462152;3999.3800960851113;4005.598040685795;8296.917030829734;8307.875381869277;8328.33373330132;8342.290905273;8344.207300306462;4297.978510107429;4298.699091063658;4300.548442413564;4302.7633480158265;4302.89242768927;3328.86684532616;3822.48396466479;3911.4328422378594;3996.0409501719378;4009.8195581198916;3170.920726981844;3235.4823228283362;3297.686719761619;3338.76628635998;3344;979.1383582447401;986.3933680786265;990.405756546081;993.0317940008434;1000.5847573257163;616.901295792671;644.9841499638089;658.3876994395258;668.4051194436955;701.225146213437;4736.100140973273;4867.683600565952;4903.41159060915;4905.3132561441425;5181.4559471255725;394.5521832719884;395.36543847125523;406.8250652219574;407.8959865234346;409.57404299528156;3166.145140811968;3426.7087297579983;3519.1729943463265;3534.964650353515;3565.4879943255874;9500.762480938032;9641.951790241004;9772.951135244279;9780.608775649036;9782.755431114138;9688.12806847387;9701.805963880683;9702.223822094216;9715.562799674031;9749.463779492055;7162.355388015104;7330.120385553768;7343.3390994810725;7346.1551921528635;7347;4983.227599722049;5117.1045067113855;5162.457941916195;5163.948360516347;5364.919526207032;8913.465192088495;8928.464292142493;8941.105889411267;8950.597223125105;9050.818983620158;8842.245510768016;9030.096990300917;9030.322725795722;9081.818363632823;9188.127127922837;6602.7689030883685;6644.734210631714;6708.966455167693;6712.597244165366;6774.93225067743;7181.533200341961;7240.674169662272;7244.4566657501955;7253.891191632024;7255.56466612005;6362.363763623601;6362.586431881913;6367.140436041261;6511.967440162769;6560.081588577507;6379.649119298483;6389.361063893573;6424.421802037839;6430;6435.163428754232;5957.344692083954;6051.304100028553;6057.121717350871;6063.878722425438;6071.51427885777;4072.9185416292103;4165;4218.409422680766;4236.25865473547;4346.261135606891;4293.635041021548;4332.133573285292;4381.956180438282;4504.77476126199;4626.514216007406 15:59:43 INFO - PROCESS | 24050 | |4;prototype.html;279.86146857305647;284.0526842978666;292.1425615817577;293.1732514309647;294.74651799452465;4647.7443740594335;4813.614910807136;4814.422269327685;4866.221404575264;4874.85375438737;4601.884952876179;4714.339992401058;4728.645351598627;4737.431508219019;4755.381800365947;3774.6411291934883;3785.05069889006;3794.7153963452715;3795.582485926546;3812.694984401255;3582.3730847101783;3609.458581212826;3769.868054618089;3804.6005169457226;3810.1998580298173;3777.376732839078;3784.2242340320167;3798.5631652359916;3801.980990095046;3811.2949104415634;3780.9621903781162;3781.45168950502;3796.734228603999;3797.259534390781;3797.810109494514;3360.7647464677484;3360.9831950840335;3364.7812892161933;3376.6960973512255;3387.932241355159;4488.483824360208;4640.651951103647;4646.163690535702;4693.859184224479;4728.952710472886;4287.935680964788;4291.6566674666265;4297.742135471882;4299.570042995707;4300.505441874193;4982;5002.874928126826;5006.4743201964;5010.219328272807;5011.749412529395;3387.4241378965635;3494.405950988338;3538.380783362901;3540.2211513466996;3554.626764189749;5227.60792940531;5250.159974404115;5257.947420525784;5261.4475480074425;5268.446813084647;3558.946615800765;3572.6070132285427;3588.6052534221217;3590.8743193988084;3594.8741794037082;3433.570803649544;3605.531280933514;3627.1294889226397;3639.745217834779;3640.508531348234;4137.600191888698;4142.570813069487;4142.937855932163;4152;4167.166566686675;4985.426675932242;4997.100521906092;4999.825006124841;4999.925001124986;5007.899842003139;1175.2830773228325;1218.7014181525406;1248.5392091253254;1254.1659796235401;1271.4278574641264;834.3325339728193;847.6651722569674;854.6154230596256;862.5083702289635;868.1362044765373;4365.628921541705;4389.714668546534;4392.758398288061;4396.318570621559;4402.889927751768;5066.240063990431;5073;5088.592912566985;5096.324429674451;5106.285120083191;4368.453943257093;4381.261711430356;4385.004822805616;4387.188370151468;4389.209942210369;2325.8023068039406;2386.3795413192347;2416.395901024744;2420.9031638734605;2427.00492797952;4287.277578737524;4294.935575966363;4298.130313313719;4310.685241001462;4311.827526898889;4620.620380504043;4627.722336659811;4635.304704294383;4652.743759185001;4654.627629789608;4064.695147863922;4306.20335237982;4330.718503297276;4342.741774277421;4345.435093437833;4499.77501124949;4514.390557274726;4518.615917647036;4578.954210457986;4584.312353147055;4394.956050439582;4481;4525.117602067565;4531.388262584509;4532.456105267389;4015.257177422186;4153.958460415357;4274.786260686891;4345.304751239786;4392.8591057394115;3272.869085236564;3292.489664102068;3310.1724568857785;3310.6854848789326;3329.900102996914;4540.728595993127;4558.77206139685;4574.245249533789;4579.633629309714;4596.264597664358;4810.374651295309;4822.469528351809;4895;4924.63065270099;4937.481564435826;4930.753462326941;4972.179590367694;5009.273655319886;5016.849494515171;5022.221555658879;4772.7610645784525;4952.678075925053;4956.479569645136;4961.355023846877;4980.825671101494;4911.263310503453;4963.404391473046;4971.776270067928;4979.435590670735;4989.55094041538;4904.828331008398;4912.870039890917;4926.9306891106535;4931.778069986787;4956.801727930842 15:59:43 INFO - PROCESS | 24050 | |5;yui.html;564.8503146666131;598.2850493660076;652.5790864892144;657.516725206973;730.466759265736;12711.491540338387;13361;13370.465181392732;13379.063465557414;13391.397387117579;13685.521006764766;13962.511312104078;13966.79049814251;13985.79021314681;13988.230647314394;615.5783288447428;735.0774777654036;737.5832654550189;758.3932853717032;761.733393312339;759.9734009309675;760.4714723267332;762.4662736084736;762.4662736084736;763.6181909045478;813.5037627047496;814.1410811593767;814.1614137438419;815.3517953227199;816.4692949582789;649.5680372552247;775.2789905387987;794.0233512779314;795.586295126531;809.7692157735047;586.9823905282848;587.0196771391786;587.2923127631237;587.3245767367519;587.4624718382674;13452.798208026887;13548.86451135486;13565.864341356559;13585.59243222695;13591.116577422436;12493;12707.745845083047;12905.35473226335;13142.802857957138;13435;11374.658760237206;11811.586594469152;11826.290422574673;12070.517179312817;12221.633350999484;4111.465509483748;4140.358244472111;4227.196832601796;4238.7880605969885;4265.083007153446;68280;68341.65829170823;68356.31643683549;68384.65807670979;68426.65786671084;9722.671470443343;9864.457454839909;10079.445630490394;10084.596616135272;10307.742306442398;19413.51466213356;19418.320358787376;19508;19527.60944781125;19613.901930490258;6672;6761.628110453874;6828.283030281848;6848.212455567574;6917.204521479994;3634.7637403568683;3641.126129728846;3661.176235347026;3686.4286035664513;3737.364648009845;2529.1148098165863;2594.2347007632416;2603.4142317978303;2604.270804174796;2604.9869750651123;472.9101470720552;479.0443066083186;480.526681218999;486.26088345714317;490.77914938277917;467.78949472737406;476.68300580114527;481.7037521923992;485.39568237544484;514.629466783915;6824.727010919508;6845.657717114224;7030.050943122716;7064.92935070653;7269.7455589054125;478.2921276510784;480.5626879539602;484.72128526097634;489.5422779700991;497.87304237419227;3188.9362212755614;3348.765586408928;3427.640097789746;3609.4946707460977;3681.226942342138;103463.48268258761;104645.47677261564;105201.47399263107;106250;106949;87481;88896.5555172233;89313;90648.5467572671;93451.53274233585;49459.50540494621;70528.64735676392;78505.60747196342;80741.19258807336;81353.77969330465;6782.152230971129;6925.272846351262;6966.233714291323;6978.337057979484;7111.82220444493;41576.58423415848;46390.76804615955;46512.8371790708;47395;49721.25418118659;70123.64938175277;71424;74095;74102;76067;8056.274935255855;8105;8440;8441.577921103797;8630.525321107149;9616.567254473706;9770.413775173512;9822;9938.950305248427;9991.550380232762;7870.763877083697;9871.506424678593;9881.851772223567;10727.356358618508;10905.618303359344;10784.838227426439;10894.400807955639;11083.612073577386;11970.640880773592;11973.880261197624;10437.895621043692;11285.37930413835;11689.766204675689;12029.879701202875;12349.19730217533;9155.633774648939;9503.952480237554;9565.282603804882;9619.5671194795;9628.71113866557;8974.596143173703;9486.573104210465;9539;9619.27855410861;9641.42151470914 15:59:43 INFO - PROCESS | 24050 | __end_tp_report 15:59:43 INFO - PROCESS | 24050 | __start_cc_report 15:59:43 INFO - PROCESS | 24050 | _x_x_mozilla_cycle_collect,1188 15:59:43 INFO - PROCESS | 24050 | __end_cc_report 15:59:43 INFO - PROCESS | 24050 | __startTimestamp1470697183350__endTimestamp 15:59:43 INFO - PROCESS | 24050 | 15:59:43 INFO - PROCESS | 24050 | ------- Summary: start ------- 15:59:43 INFO - PROCESS | 24050 | Number of tests: 6 15:59:43 INFO - PROCESS | 24050 | 15:59:43 INFO - PROCESS | 24050 | [#0] dojo.html Cycles:165 Average:4674.19 Median:4790.38 stddev:479.44 (10.0%) stddev-sans-first:452.65 15:59:43 INFO - PROCESS | 24050 | Values: 2607.1 2624.7 2647.7 2647.9 2663.0 4571.9 4620.4 4741.4 4768.4 4819.5 4615.1 4619.2 4669.6 4682.7 4737.2 4283.7 4332.7 4354.0 4384.4 4403.0 4356.4 4362.0 4384.1 4394.5 4444.2 4506.1 4509.2 4525.6 4546.2 4547.6 4228.1 4287.6 4290.6 4378.1 4435.8 4255.1 4271.2 4294.3 4345.8 4356.4 4702.6 4706.1 4707.7 4750.5 4754.1 4795.3 5012.1 5034.9 5049.6 5099.1 4940.6 4978.8 5000.9 5048.6 5103.6 4670.3 4709.1 4744.1 4754.1 4758.3 4982.4 5091.4 5222.2 5223.3 5229.2 4939.9 5019.8 5033.3 5035.6 5039.7 5043.4 5063.5 5068.2 5093.5 5117.7 4831.3 4869.3 5029.7 5042.6 5085.1 4938.5 4997.9 5008.3 5075.0 5077.3 4353.5 4359.1 4361.0 4396.8 4403.7 3795.4 3835.9 3845.0 3846.0 3858.3 4162.9 4303.6 4346.9 4358.3 4370.0 5100.8 5101.6 5106.2 5106.5 5137.6 4364.8 4370.4 4372.7 4399.4 4416.0 4466.1 4551.2 4593.0 4597.0 4613.3 4774.4 4796.4 4798.2 4837.0 4842.2 5079.5 5114.9 5125.9 5126.9 5130.3 4585.0 4680.5 4715.0 4722.0 4839.2 4954.9 4965.9 4970.4 4971.4 5007.5 5017.3 5029.6 5030.1 5057.6 5064.2 4639.5 4771.5 4891.0 4951.5 4953.1 4944.3 4945.2 4957.3 4963.8 5000.5 4923.9 4945.5 4956.3 4969.0 4969.4 4763.0 4825.9 4849.2 4887.3 4888.5 4790.4 4801.1 4807.4 4856.7 4869.5 15:59:43 INFO - PROCESS | 24050 | 15:59:43 INFO - PROCESS | 24050 | [#1] ext.html Cycles:180 Average:42676.46 Median:33693.33 stddev:72026.42 (213.8%) stddev-sans-first:72159.05 15:59:43 INFO - PROCESS | 24050 | Values: 558.6 563.8 566.1 572.1 575.1 26346.0 38606.8 39753.6 39832.0 43415.0 39697.0 40024.2 40039.4 40100.0 40119.8 7661.3 7800.9 7806.1 7807.3 7811.1 7625.1 7726.5 7727.9 7729.2 7739.8 7709.8 7725.9 7734.0 7739.8 7750.9 7686.8 7691.3 7730.3 7738.0 7746.8 7274.1 7288.2 7309.2 7334.6 7355.2 36929.8 37628.6 37634.4 37659.4 37697.6 2516.8 2552.6 2640.1 2793.9 2793.9 28146.4 28203.2 28586.4 28653.6 28943.4 3030.9 3106.9 3109.7 3111.2 3115.7 255804.0 256760.0 257009.0 257850.0 258356.0 49199.3 49733.0 49807.0 49884.0 49930.5 32427.8 53250.0 53389.5 53404.0 53457.7 13307.5 14161.6 14203.7 14208.1 14222.2 10535.7 10803.7 10811.0 10816.4 10820.9 17566.0 17581.5 17583.7 17731.0 17766.5 6352.6 6419.0 6574.4 6583.4 6624.9 777.9 835.9 844.3 918.5 941.2 26151.5 26403.6 26969.7 27079.2 27086.5 848.4 924.3 944.6 946.3 999.8 13389.3 14071.8 14183.1 14322.3 14342.6 296443.0 299848.0 301906.0 302384.0 303016.0 46292.8 46701.5 46790.0 46810.0 46820.1 1163.8 1171.3 1173.7 1175.0 1177.3 25055.6 25794.2 26153.2 26171.9 26453.6 39383.0 39396.4 39761.8 39762.8 39772.0 264808.0 266727.0 267031.0 267259.0 267347.0 24202.5 24373.3 24653.8 24685.8 24730.4 39296.2 39815.4 40160.0 40300.0 40362.8 32457.8 33116.3 33591.8 33903.0 34120.7 33530.5 33539.5 33794.8 34424.5 35335.3 29922.7 30019.1 30085.5 30283.8 34096.8 28266.9 32359.8 32911.2 33010.7 33172.3 24476.9 24822.0 24921.4 25154.7 25324.7 15:59:43 INFO - PROCESS | 24050 | 15:59:43 INFO - PROCESS | 24050 | [#2] jquery.html Cycles:180 Average:24938.39 Median:5179.18 stddev:66388.87 (1281.8%) stddev-sans-first:66549.42 15:59:43 INFO - PROCESS | 24050 | Values: 340.2 352.2 361.5 369.4 387.9 12940.2 17129.5 17627.4 18255.7 19204.9 15547.5 15602.3 15867.2 15874.4 15992.7 5029.3 5031.7 5156.2 5195.6 5199.3 3764.6 5133.7 5202.7 5213.0 5701.7 5933.7 6035.0 6055.6 6056.4 6058.7 5809.4 5879.1 5915.3 5930.1 6029.2 5173.9 5178.6 5179.7 5181.3 5182.8 20957.5 21561.7 21656.6 21695.6 21760.6 1874.7 1938.1 2120.7 2168.1 2382.6 13585.4 13675.2 13846.5 13884.2 14558.3 1777.4 1798.5 1801.6 1827.8 1881.9 68870.7 69767.0 69913.3 70327.6 71401.6 9478.5 9484.7 9697.3 9920.6 9972.3 13161.5 13240.5 13282.0 13307.9 13309.0 7154.5 7453.5 7501.3 7548.5 7612.6 7048.2 7155.6 7281.5 7309.0 7324.9 6047.9 6176.2 6251.3 6302.8 6463.3 1712.6 1720.0 1730.0 1767.2 1779.7 830.4 832.1 876.1 927.9 929.6 16855.7 18020.4 18931.4 18974.1 18996.6 855.3 873.0 896.7 915.3 923.7 9110.5 10348.2 10357.3 10381.9 10735.7 382812.0 389578.0 390146.0 424133.3 424458.0 49385.0 79237.0 79685.6 80858.6 81309.0 874.2 883.8 908.7 908.9 914.5 15770.0 15909.3 16254.6 16856.2 17012.1 38525.0 38547.8 38730.4 39605.6 39702.2 52580.5 53019.0 53247.5 53324.5 54937.0 7139.3 7169.2 7176.7 7339.9 7360.0 9347.6 12088.0 12120.6 12264.7 12357.3 9651.4 9845.2 10026.4 10205.3 10285.2 10220.1 10445.4 10700.7 10755.1 11081.2 9308.2 9816.7 9870.0 10020.9 10108.2 6787.0 7245.9 7264.4 7339.8 7495.8 8015.4 9707.6 9739.8 9799.9 9817.8 15:59:43 INFO - PROCESS | 24050 | 15:59:43 INFO - PROCESS | 24050 | [#3] mootools.html Cycles:180 Average:4953.89 Median:5050.17 stddev:2887.31 (57.2%) stddev-sans-first:2881.60 15:59:43 INFO - PROCESS | 24050 | Values: 1196.5 1261.9 1318.5 1343.4 1653.0 6332.9 7339.5 7598.7 7610.9 7841.5 7647.8 7715.0 7716.9 7718.6 7725.7 2147.9 2175.2 2175.7 2178.2 2178.3 2099.7 2143.8 2163.5 2163.9 2164.9 2066.2 2081.4 2083.8 2087.4 2115.9 2088.0 2091.1 2092.9 2093.3 2093.4 2023.1 2051.8 2052.7 2055.9 2056.2 7573.7 7600.5 7661.4 7715.7 7827.2 4127.2 4310.8 4318.0 4327.8 4328.8 2672.5 2773.1 2814.1 2831.5 2840.5 885.4 886.3 897.5 898.8 909.7 10347.5 10431.6 10451.3 10471.5 10472.4 3646.3 3791.8 3963.4 3999.4 4005.6 8296.9 8307.9 8328.3 8342.3 8344.2 4298.0 4298.7 4300.5 4302.8 4302.9 3328.9 3822.5 3911.4 3996.0 4009.8 3170.9 3235.5 3297.7 3338.8 3344.0 979.1 986.4 990.4 993.0 1000.6 616.9 645.0 658.4 668.4 701.2 4736.1 4867.7 4903.4 4905.3 5181.5 394.6 395.4 406.8 407.9 409.6 3166.1 3426.7 3519.2 3535.0 3565.5 9500.8 9642.0 9773.0 9780.6 9782.8 9688.1 9701.8 9702.2 9715.6 9749.5 7162.4 7330.1 7343.3 7346.2 7347.0 4983.2 5117.1 5162.5 5163.9 5364.9 8913.5 8928.5 8941.1 8950.6 9050.8 8842.2 9030.1 9030.3 9081.8 9188.1 6602.8 6644.7 6709.0 6712.6 6774.9 7181.5 7240.7 7244.5 7253.9 7255.6 6362.4 6362.6 6367.1 6512.0 6560.1 6379.6 6389.4 6424.4 6430.0 6435.2 5957.3 6051.3 6057.1 6063.9 6071.5 4072.9 4165.0 4218.4 4236.3 4346.3 4293.6 4332.1 4382.0 4504.8 4626.5 15:59:43 INFO - PROCESS | 24050 | 15:59:43 INFO - PROCESS | 24050 | [#4] prototype.html Cycles:180 Average:4018.21 Median:4391.24 stddev:1161.00 (26.4%) stddev-sans-first:1129.84 15:59:43 INFO - PROCESS | 24050 | Values: 279.9 284.1 292.1 293.2 294.7 4647.7 4813.6 4814.4 4866.2 4874.9 4601.9 4714.3 4728.6 4737.4 4755.4 3774.6 3785.1 3794.7 3795.6 3812.7 3582.4 3609.5 3769.9 3804.6 3810.2 3777.4 3784.2 3798.6 3802.0 3811.3 3781.0 3781.5 3796.7 3797.3 3797.8 3360.8 3361.0 3364.8 3376.7 3387.9 4488.5 4640.7 4646.2 4693.9 4729.0 4287.9 4291.7 4297.7 4299.6 4300.5 4982.0 5002.9 5006.5 5010.2 5011.7 3387.4 3494.4 3538.4 3540.2 3554.6 5227.6 5250.2 5257.9 5261.4 5268.4 3558.9 3572.6 3588.6 3590.9 3594.9 3433.6 3605.5 3627.1 3639.7 3640.5 4137.6 4142.6 4142.9 4152.0 4167.2 4985.4 4997.1 4999.8 4999.9 5007.9 1175.3 1218.7 1248.5 1254.2 1271.4 834.3 847.7 854.6 862.5 868.1 4365.6 4389.7 4392.8 4396.3 4402.9 5066.2 5073.0 5088.6 5096.3 5106.3 4368.5 4381.3 4385.0 4387.2 4389.2 2325.8 2386.4 2416.4 2420.9 2427.0 4287.3 4294.9 4298.1 4310.7 4311.8 4620.6 4627.7 4635.3 4652.7 4654.6 4064.7 4306.2 4330.7 4342.7 4345.4 4499.8 4514.4 4518.6 4579.0 4584.3 4395.0 4481.0 4525.1 4531.4 4532.5 4015.3 4154.0 4274.8 4345.3 4392.9 3272.9 3292.5 3310.2 3310.7 3329.9 4540.7 4558.8 4574.2 4579.6 4596.3 4810.4 4822.5 4895.0 4924.6 4937.5 4930.8 4972.2 5009.3 5016.8 5022.2 4772.8 4952.7 4956.5 4961.4 4980.8 4911.3 4963.4 4971.8 4979.4 4989.6 4904.8 4912.9 4926.9 4931.8 4956.8 15:59:43 INFO - PROCESS | 24050 | 15:59:43 INFO - PROCESS | 24050 | [#5] yui.html Cycles:180 Average:18347.49 Median:539.74 stddev:27381.11 (5073.0%) stddev-sans-first:27425.37 15:59:43 INFO - PROCESS | 24050 | Values: 564.9 598.3 652.6 657.5 730.5 12711.5 13361.0 13370.5 13379.1 13391.4 13685.5 13962.5 13966.8 13985.8 13988.2 615.6 735.1 737.6 758.4 761.7 760.0 760.5 762.5 762.5 763.6 813.5 814.1 814.2 815.4 816.5 649.6 775.3 794.0 795.6 809.8 587.0 587.0 587.3 587.3 587.5 13452.8 13548.9 13565.9 13585.6 13591.1 12493.0 12707.7 12905.4 13142.8 13435.0 11374.7 11811.6 11826.3 12070.5 12221.6 4111.5 4140.4 4227.2 4238.8 4265.1 68280.0 68341.7 68356.3 68384.7 68426.7 9722.7 9864.5 10079.4 10084.6 10307.7 19413.5 19418.3 19508.0 19527.6 19613.9 6672.0 6761.6 6828.3 6848.2 6917.2 3634.8 3641.1 3661.2 3686.4 3737.4 2529.1 2594.2 2603.4 2604.3 2605.0 472.9 479.0 480.5 486.3 490.8 467.8 476.7 481.7 485.4 514.6 6824.7 6845.7 7030.1 7064.9 7269.7 478.3 480.6 484.7 489.5 497.9 3188.9 3348.8 3427.6 3609.5 3681.2 103463.5 104645.5 105201.5 106250.0 106949.0 87481.0 88896.6 89313.0 90648.5 93451.5 49459.5 70528.6 78505.6 80741.2 81353.8 6782.2 6925.3 6966.2 6978.3 7111.8 41576.6 46390.8 46512.8 47395.0 49721.3 70123.6 71424.0 74095.0 74102.0 76067.0 8056.3 8105.0 8440.0 8441.6 8630.5 9616.6 9770.4 9822.0 9939.0 9991.6 7870.8 9871.5 9881.9 10727.4 10905.6 10784.8 10894.4 11083.6 11970.6 11973.9 10437.9 11285.4 11689.8 12029.9 12349.2 9155.6 9504.0 9565.3 9619.6 9628.7 8974.6 9486.6 9539.0 9619.3 9641.4 15:59:43 INFO - PROCESS | 24050 | -------- Summary: end -------- 15:59:43 INFO - PROCESS | 24050 | 15:59:44 INFO - TEST-INFO | 24050: exit 0 15:59:44 INFO - TEST-OK | dromaeo_css | took 1120952ms 15:59:44 INFO - TEST-START | kraken 15:59:44 INFO - operating with platform_type : linux_ 15:59:44 INFO - Initialising browser for kraken test... 15:59:44 INFO - TEST-INFO | started process 24240 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZ5GYhT/profile http://localhost:45810/getInfo.html) 15:59:48 INFO - PROCESS | 24240 | __metrics Screen width/height:1600/1200 15:59:48 INFO - PROCESS | 24240 | colorDepth:24 15:59:48 INFO - PROCESS | 24240 | Browser inner width/height: 1024/697 15:59:48 INFO - PROCESS | 24240 | __metrics 15:59:49 INFO - PROCESS | 24240 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 15:59:49 INFO - PROCESS | 24240 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:59:50 INFO - TEST-INFO | 24240: exit 0 15:59:50 INFO - Browser initialized. 15:59:50 INFO - Running cycle 1/1 for kraken test... 15:59:50 INFO - TEST-INFO | started process 24323 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZ5GYhT/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:00:02 INFO - PROCESS | 24323 | RSS: Main: 158072832 16:00:02 INFO - PROCESS | 24323 | 16:00:05 INFO - PROCESS | 24323 | Cycle 1(1): loaded http://localhost:45810/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:45810/tests/kraken/driver.html?testName=audio-beat-detection) 16:00:06 INFO - PROCESS | 24323 | RSS: Main: 214188032 16:00:06 INFO - PROCESS | 24323 | 16:00:09 INFO - PROCESS | 24323 | Cycle 1(1): loaded http://localhost:45810/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:45810/tests/kraken/driver.html?testName=audio-dft) 16:00:09 INFO - PROCESS | 24323 | RSS: Main: 192155648 16:00:09 INFO - PROCESS | 24323 | 16:00:13 INFO - PROCESS | 24323 | Cycle 1(1): loaded http://localhost:45810/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:45810/tests/kraken/driver.html?testName=audio-fft) 16:00:13 INFO - PROCESS | 24323 | RSS: Main: 273326080 16:00:13 INFO - PROCESS | 24323 | 16:00:15 INFO - PROCESS | 24323 | Cycle 1(1): loaded http://localhost:45810/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:45810/tests/kraken/driver.html?testName=audio-oscillator) 16:00:16 INFO - PROCESS | 24323 | RSS: Main: 180559872 16:00:16 INFO - PROCESS | 24323 | 16:00:18 INFO - PROCESS | 24323 | Cycle 1(1): loaded http://localhost:45810/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:45810/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:00:19 INFO - PROCESS | 24323 | RSS: Main: 185106432 16:00:19 INFO - PROCESS | 24323 | 16:00:22 INFO - PROCESS | 24323 | Cycle 1(1): loaded http://localhost:45810/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:45810/tests/kraken/driver.html?testName=imaging-darkroom) 16:00:23 INFO - PROCESS | 24323 | RSS: Main: 221380608 16:00:23 INFO - PROCESS | 24323 | 16:00:26 INFO - PROCESS | 24323 | Cycle 1(1): loaded http://localhost:45810/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:45810/tests/kraken/driver.html?testName=imaging-desaturate) 16:00:27 INFO - PROCESS | 24323 | RSS: Main: 216186880 16:00:27 INFO - PROCESS | 24323 | 16:00:30 INFO - PROCESS | 24323 | Cycle 1(1): loaded http://localhost:45810/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:45810/tests/kraken/driver.html?testName=json-parse-financial) 16:00:30 INFO - PROCESS | 24323 | RSS: Main: 218669056 16:00:30 INFO - PROCESS | 24323 | 16:00:32 INFO - PROCESS | 24323 | Cycle 1(1): loaded http://localhost:45810/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:45810/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:00:33 INFO - PROCESS | 24323 | RSS: Main: 184119296 16:00:33 INFO - PROCESS | 24323 | 16:00:35 INFO - PROCESS | 24323 | Cycle 1(1): loaded http://localhost:45810/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:45810/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:00:35 INFO - PROCESS | 24323 | RSS: Main: 191328256 16:00:35 INFO - PROCESS | 24323 | 16:00:37 INFO - PROCESS | 24323 | Cycle 1(1): loaded http://localhost:45810/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:45810/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:00:38 INFO - PROCESS | 24323 | RSS: Main: 194703360 16:00:38 INFO - PROCESS | 24323 | 16:00:41 INFO - PROCESS | 24323 | Cycle 1(1): loaded http://localhost:45810/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:45810/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:00:42 INFO - PROCESS | 24323 | RSS: Main: 188432384 16:00:42 INFO - PROCESS | 24323 | 16:00:44 INFO - PROCESS | 24323 | Cycle 1(1): loaded http://localhost:45810/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:45810/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:00:45 INFO - PROCESS | 24323 | RSS: Main: 192925696 16:00:45 INFO - PROCESS | 24323 | 16:00:47 INFO - PROCESS | 24323 | Cycle 1(1): loaded http://localhost:45810/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:45810/tests/kraken/driver.html?testName=ai-astar) 16:00:47 INFO - PROCESS | 24323 | RSS: Main: 243511296 16:00:47 INFO - PROCESS | 24323 | 16:00:47 INFO - PROCESS | 24323 | __start_tp_report 16:00:47 INFO - PROCESS | 24323 | _x_x_mozilla_page_load 16:00:47 INFO - PROCESS | 24323 | _x_x_mozilla_page_load_details 16:00:47 INFO - PROCESS | 24323 | |i|pagename|runs| 16:00:47 INFO - PROCESS | 24323 | |0;ai-astar;108;116;113;113;117;115;114;115;115;113 16:00:47 INFO - PROCESS | 24323 | |1;audio-beat-detection;151;120;122;202;122;123;152;125;184;122 16:00:47 INFO - PROCESS | 24323 | |2;audio-dft;162;162;162;162;162;161;161;161;161;161 16:00:47 INFO - PROCESS | 24323 | |3;audio-fft;77;76;76;112;76;77;75;76;126;76 16:00:47 INFO - PROCESS | 24323 | |4;audio-oscillator;84;86;134;84;85;85;116;84;84;133 16:00:47 INFO - PROCESS | 24323 | |5;imaging-gaussian-blur;116;117;116;116;116;117;118;117;117;117 16:00:47 INFO - PROCESS | 24323 | |6;imaging-darkroom;147;147;146;146;147;146;209;146;146;146 16:00:47 INFO - PROCESS | 24323 | |7;imaging-desaturate;95;95;95;95;95;95;95;94;95;95 16:00:47 INFO - PROCESS | 24323 | |8;json-parse-financial;63;64;64;63;64;62;63;63;61;64 16:00:47 INFO - PROCESS | 24323 | |9;json-stringify-tinderbox;66;65;64;65;66;65;65;66;65;65 16:00:47 INFO - PROCESS | 24323 | |10;stanford-crypto-aes;78;79;79;78;79;83;80;78;80;78 16:00:47 INFO - PROCESS | 24323 | |11;stanford-crypto-ccm;134;150;129;131;128;128;129;138;159;130 16:00:47 INFO - PROCESS | 24323 | |12;stanford-crypto-pbkdf2;167;162;163;163;161;169;166;163;164;163 16:00:47 INFO - PROCESS | 24323 | |13;stanford-crypto-sha256-iterative;70;65;67;66;70;67;65;70;65;65 16:00:47 INFO - PROCESS | 24323 | __end_tp_report 16:00:47 INFO - PROCESS | 24323 | __start_cc_report 16:00:47 INFO - PROCESS | 24323 | _x_x_mozilla_cycle_collect,1950 16:00:47 INFO - PROCESS | 24323 | __end_cc_report 16:00:47 INFO - PROCESS | 24323 | __startTimestamp1470697247483__endTimestamp 16:00:47 INFO - PROCESS | 24323 | 16:00:47 INFO - PROCESS | 24323 | ------- Summary: start ------- 16:00:47 INFO - PROCESS | 24323 | Number of tests: 14 16:00:47 INFO - PROCESS | 24323 | 16:00:47 INFO - PROCESS | 24323 | [#0] ai-astar Cycles:10 Average:113.90 Median:115.00 stddev:2.47 (2.1%) stddev-sans-first:1.42 16:00:47 INFO - PROCESS | 24323 | Values: 108.0 116.0 113.0 113.0 117.0 115.0 114.0 115.0 115.0 113.0 16:00:47 INFO - PROCESS | 24323 | 16:00:47 INFO - PROCESS | 24323 | [#1] audio-beat-detection Cycles:10 Average:142.30 Median:138.00 stddev:29.59 (21.4%) stddev-sans-first:31.21 16:00:47 INFO - PROCESS | 24323 | Values: 151.0 120.0 122.0 202.0 122.0 123.0 152.0 125.0 184.0 122.0 16:00:47 INFO - PROCESS | 24323 | 16:00:47 INFO - PROCESS | 24323 | [#2] audio-dft Cycles:10 Average:161.50 Median:162.00 stddev:0.53 (0.3%) stddev-sans-first:0.53 16:00:47 INFO - PROCESS | 24323 | Values: 162.0 162.0 162.0 162.0 162.0 161.0 161.0 161.0 161.0 161.0 16:00:47 INFO - PROCESS | 24323 | 16:00:47 INFO - PROCESS | 24323 | [#3] audio-fft Cycles:10 Average:84.70 Median:76.00 stddev:18.39 (24.2%) stddev-sans-first:19.29 16:00:47 INFO - PROCESS | 24323 | Values: 77.0 76.0 76.0 112.0 76.0 77.0 75.0 76.0 126.0 76.0 16:00:47 INFO - PROCESS | 24323 | 16:00:47 INFO - PROCESS | 24323 | [#4] audio-oscillator Cycles:10 Average:97.50 Median:84.00 stddev:21.37 (25.4%) stddev-sans-first:22.10 16:00:47 INFO - PROCESS | 24323 | Values: 84.0 86.0 134.0 84.0 85.0 85.0 116.0 84.0 84.0 133.0 16:00:47 INFO - PROCESS | 24323 | 16:00:47 INFO - PROCESS | 24323 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.67 (0.6%) stddev-sans-first:0.67 16:00:47 INFO - PROCESS | 24323 | Values: 116.0 117.0 116.0 116.0 116.0 117.0 118.0 117.0 117.0 117.0 16:00:47 INFO - PROCESS | 24323 | 16:00:47 INFO - PROCESS | 24323 | [#6] imaging-darkroom Cycles:10 Average:152.60 Median:146.50 stddev:19.82 (13.5%) stddev-sans-first:20.92 16:00:47 INFO - PROCESS | 24323 | Values: 147.0 147.0 146.0 146.0 147.0 146.0 209.0 146.0 146.0 146.0 16:00:47 INFO - PROCESS | 24323 | 16:00:47 INFO - PROCESS | 24323 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 16:00:47 INFO - PROCESS | 24323 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 95.0 94.0 95.0 95.0 16:00:47 INFO - PROCESS | 24323 | 16:00:47 INFO - PROCESS | 24323 | [#8] json-parse-financial Cycles:10 Average:63.10 Median:63.50 stddev:0.99 (1.6%) stddev-sans-first:1.05 16:00:47 INFO - PROCESS | 24323 | Values: 63.0 64.0 64.0 63.0 64.0 62.0 63.0 63.0 61.0 64.0 16:00:47 INFO - PROCESS | 24323 | 16:00:47 INFO - PROCESS | 24323 | [#9] json-stringify-tinderbox Cycles:10 Average:65.20 Median:65.00 stddev:0.63 (1.0%) stddev-sans-first:0.60 16:00:47 INFO - PROCESS | 24323 | Values: 66.0 65.0 64.0 65.0 66.0 65.0 65.0 66.0 65.0 65.0 16:00:47 INFO - PROCESS | 24323 | 16:00:47 INFO - PROCESS | 24323 | [#10] stanford-crypto-aes Cycles:10 Average:79.20 Median:79.00 stddev:1.55 (2.0%) stddev-sans-first:1.58 16:00:47 INFO - PROCESS | 24323 | Values: 78.0 79.0 79.0 78.0 79.0 83.0 80.0 78.0 80.0 78.0 16:00:47 INFO - PROCESS | 24323 | 16:00:47 INFO - PROCESS | 24323 | [#11] stanford-crypto-ccm Cycles:10 Average:135.60 Median:132.50 stddev:10.64 (8.0%) stddev-sans-first:11.27 16:00:47 INFO - PROCESS | 24323 | Values: 134.0 150.0 129.0 131.0 128.0 128.0 129.0 138.0 159.0 130.0 16:00:47 INFO - PROCESS | 24323 | 16:00:47 INFO - PROCESS | 24323 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:164.10 Median:163.50 stddev:2.47 (1.5%) stddev-sans-first:2.39 16:00:47 INFO - PROCESS | 24323 | Values: 167.0 162.0 163.0 163.0 161.0 169.0 166.0 163.0 164.0 163.0 16:00:47 INFO - PROCESS | 24323 | 16:00:47 INFO - PROCESS | 24323 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.00 Median:67.00 stddev:2.21 (3.3%) stddev-sans-first:2.06 16:00:47 INFO - PROCESS | 24323 | Values: 70.0 65.0 67.0 66.0 70.0 67.0 65.0 70.0 65.0 65.0 16:00:47 INFO - PROCESS | 24323 | -------- Summary: end -------- 16:00:47 INFO - PROCESS | 24323 | 16:00:48 INFO - TEST-INFO | 24323: exit 0 16:00:48 INFO - TEST-OK | kraken | took 64426ms 16:00:48 INFO - SUITE-END | took 1185s 16:00:49 INFO - Completed test suite (00:19:45) 16:00:49 INFO - javascript benchmark 16:00:49 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2607.11358138233, 2624.6719160104985, 2647.7352264773517, 2647.9073232436867, 2662.960055599165, 4571.9314210286875, 4620.353150558916, 4741.359916411284, 4768.427788665356, 4819.469858315582, 4615.146197953381, 4619.237825758746, 4669.603083737887, 4682.695624784386, 4737.218358970775, 4283.7215580987295, 4332.740035597859, 4353.978230108861, 4384.4300240968705, 4403.031333106712, 4356.411884395614, 4361.978190109045, 4384.079343337903, 4394.450693663292, 4444.177827101997, 4506.053728716974, 4509.1883460977015, 4525.592696657301, 4546.1589605922845, 4547.590716835484, 4228.133232687292, 4287.63555097814, 4290.613844753956, 4378.124375124956, 4435.8225670973125, 4255.063885945118, 4271.209826182167, 4294.291441912081, 4345.84789532368, 4356.43366362374, 4702.553257440538, 4706.10583989041, 4707.693999890031, 4750.548697873696, 4754.144254034272, 4795.304680821297, 5012.147934851084, 5034.949650503521, 5049.64652474327, 5099.08216521026, 4940.580050695695, 4978.800847966077, 5000.949990500121, 5048.621353398473, 5103.56619687327, 4670.322803193518, 4709.105269998758, 4744.098621261949, 4754.120487709784, 4758.333833263346, 4982.3522942018035, 5091.363579552556, 5222.164453687392, 5223.268742376071, 5229.241759944786, 4939.90120197594, 5019.799208031638, 5033.270175824485, 5035.622328325317, 5039.697618142924, 5043.420006699276, 5063.544281014726, 5068.163752980717, 5093.516115968977, 5117.692938423632, 4831.251156070814, 4869.342638743724, 5029.673071250357, 5042.621803364762, 5085.059264036166, 4938.456769755324, 4997.875053123701, 5008.273800298936, 5074.974625126851, 5077.339945807095, 4353.455818022747, 4359.106383191438, 4360.956390436118, 4396.846110386121, 4403.713758605681, 3795.411711184771, 3835.9424608630893, 3844.9807750961627, 3846.0, 3858.266929283427, 4162.875113746592, 4303.63419109373, 4346.891327716832, 4358.259095953696, 4370.038591509863, 5100.770465329069, 5101.591872650179, 5106.183010718267, 5106.489351064938, 5137.614678899097, 4364.759938203366, 4370.38814565961, 4372.693911426233, 4399.38408622793, 4416.028473735837, 4466.0844526871915, 4551.158035763395, 4593.0, 4597.0346227291975, 4613.284940834176, 4774.379330686988, 4796.400449943757, 4798.208295631322, 4837.0, 4842.201036829025, 5079.466656001066, 5114.923276150935, 5125.871853203551, 5126.923096153635, 5130.333056702605, 4584.977075114603, 4680.485146633936, 4714.952850471589, 4721.976390118028, 4839.201531747363, 4954.851354459372, 4965.925511117264, 4970.403551573834, 4971.353724015855, 5007.499250074963, 5017.272495488207, 5029.64792464524, 5030.0694371541385, 5057.6459647824295, 5064.240363945438, 4639.466461356988, 4771.475137734916, 4890.975545122394, 4951.529604687549, 4953.108440480748, 4944.28307895346, 4945.208766597327, 4957.330760347307, 4963.751812409438, 5000.499950004971, 4923.876903077452, 4945.455999839943, 4956.281339205867, 4968.950310496849, 4969.353983982204, 4763.0, 4825.879353016203, 4849.151398505177, 4887.315775791321, 4888.486708895514, 4790.377250957354, 4801.135795556694, 4807.4471435785335, 4856.660033797601, 4869.513048695102], "value": 4644.138975516149, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [558.6480517274117, 563.833669067625, 566.119683891549, 572.1275055540301, 575.0597772641729, 26346.0, 38606.80696596517, 39753.60246397535, 39832.0, 43415.0, 39697.0, 40024.19951600966, 40039.39940900889, 40100.0, 40119.79940100295, 7661.348785353238, 7800.921990780104, 7806.102298235719, 7807.297343239135, 7811.06267247931, 7625.123110842241, 7726.497777644463, 7727.922720772776, 7729.188435214306, 7739.806504837396, 7709.768706938801, 7725.922740772605, 7733.955915951359, 7739.7678069658, 7750.922490775077, 7686.846263074735, 7691.269329413697, 7730.304272615492, 7737.961310193441, 7746.845063098763, 7274.127104747464, 7288.234735352765, 7309.159446663595, 7334.596597187153, 7355.227701091415, 36929.815350923345, 37628.62371376278, 37634.43548346777, 37659.43510847339, 37697.62302376996, 2516.836405633628, 2552.629868669051, 2640.115561286971, 2793.916182514528, 2793.9441211175867, 28146.43707125846, 28203.15390538287, 28586.428271434663, 28653.570196447068, 28943.421131577463, 3030.8939187128344, 3106.922326941822, 3109.673484284163, 3111.24563954209, 3115.7005118438424, 255804.0, 256760.0, 257009.0, 257850.0, 258356.0, 49199.31005174626, 49733.0, 49807.0, 49884.0, 49930.50069499259, 32427.837860810545, 53250.0, 53389.46610533845, 53404.0, 53457.7327113362, 13307.534236301683, 14161.645958851112, 14203.727403615372, 14208.07647502909, 14222.2177780221, 10535.736606584744, 10803.729906752395, 10811.0, 10816.35101893873, 10820.945895270474, 17566.0, 17581.472555823344, 17583.73624395635, 17731.0, 17766.46700598984, 6352.552159558052, 6419.0, 6574.3754343337305, 6583.44040756541, 6624.900626490607, 777.9494332868345, 835.8620827563384, 844.3203221406703, 918.5177781664679, 941.1717688434134, 26151.476970460484, 26403.603945940828, 26969.73030269672, 27079.1876243713, 27086.458270834868, 848.3637272045966, 924.3021518753299, 944.5843828715343, 946.3233787841726, 999.7600575861741, 13389.263590502422, 14071.788923166161, 14183.114960911675, 14322.283885805877, 14342.641433964234, 296443.0, 299848.0, 301906.0, 302384.0, 303016.0, 46292.768536157106, 46701.5329846704, 46790.0, 46809.999999999316, 46820.06359872784, 1163.7672465506932, 1171.262104873924, 1173.7124404520775, 1175.0, 1177.264209868832, 25055.624165637164, 25794.226173214833, 26153.215403537924, 26171.869140654177, 26453.603195952463, 39383.0, 39396.40905386479, 39761.80119099386, 39762.801185993885, 39772.0, 264808.0, 266727.0, 267031.0, 267259.0, 267347.0, 24202.515949680557, 24373.26880193597, 24653.753462465145, 24685.753142468344, 24730.381740456633, 39296.214075717755, 39815.402768959066, 40159.99600010023, 40300.0, 40362.798186008884, 32457.837710811295, 33116.33767324688, 33591.83204083964, 33903.03278729546, 34120.65879341175, 33530.49704254487, 33539.4969075469, 33794.831025844716, 34424.48363274603, 35335.29329413346, 29922.70077299199, 30019.099427017223, 30085.548716769703, 30283.848580757836, 34096.82951585226, 28266.85866570736, 32359.838200808845, 32911.177220569676, 33010.66989330172, 33172.336553269284, 24476.91946184523, 24822.0, 24921.376965576004, 25154.74845251524, 25324.746752532978], "value": 16582.52904133852, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [340.17677221124876, 352.20401891724697, 361.5264004154557, 369.3517876127396, 387.87200223926106, 12940.158889672177, 17129.48611541654, 17627.383041593548, 18255.72616410755, 19204.9039754801, 15547.53357399277, 15602.297896594651, 15867.206639668027, 15874.444394446196, 15992.680146397093, 5029.346184995946, 5031.698098114116, 5156.226566015109, 5195.610329225305, 5199.272101905736, 3764.6047165047685, 5133.743312834362, 5202.687838729669, 5212.973935130338, 5701.657900525961, 5933.673647949361, 6034.969825150868, 6055.5761096723245, 6056.364081771417, 6058.727357268934, 5809.3609702932645, 5879.088741245114, 5915.260592425947, 5930.05119180929, 6029.216201893748, 5173.870653233662, 5178.6116041297055, 5179.7410129493755, 5181.326427564431, 5182.844514664528, 20957.47606309839, 21561.67657485139, 21656.566868662696, 21695.566088678137, 21760.564788704294, 1874.6531891600098, 1938.0503553258943, 2120.703101565782, 2168.111074459464, 2382.594958856981, 13585.388657510435, 13675.179489230579, 13846.515371961934, 13884.23636699981, 14558.272086395744, 1777.4223377402395, 1798.5323815807853, 1801.6306657135256, 1827.7532533107992, 1881.9435416937513, 68870.65564672195, 69767.0, 69913.3008669912, 70327.64836175786, 71401.64299178522, 9478.478683672327, 9484.715458536255, 9697.321187516805, 9920.603175873028, 9972.30193886421, 13161.47354105825, 13240.53658121961, 13282.0, 13307.866921330857, 13309.0, 7154.499185056996, 7453.478256521991, 7501.324880760758, 7548.509346892434, 7612.581308028, 7048.189458212269, 7155.642217889084, 7281.490295679251, 7308.96345518269, 7324.890126648104, 6047.909281360783, 6176.166217560572, 6251.28110267316, 6302.842428939313, 6463.287228884349, 1712.5547357687092, 1719.9532556258014, 1729.9740503892451, 1767.22545603404, 1779.741937419067, 830.3855147191035, 832.0597724571317, 876.0932434929945, 927.8793756811706, 929.6327950459531, 16855.66288674244, 18020.36928707489, 18931.43205703831, 18974.14616342268, 18996.62006759857, 855.2644725536034, 872.9519876406858, 896.7489103051156, 915.3272344826547, 923.6720964057797, 9110.49892255919, 10348.172146228284, 10357.326773759682, 10381.9098994606, 10735.677929662123, 382812.0, 389578.0, 390146.0, 424133.3102578671, 424458.0, 49385.0, 79237.0, 79685.60157199176, 80858.59570702226, 81309.0, 874.2044739287345, 883.7878909061778, 908.7137551671335, 908.8727578139066, 914.4650379527918, 15769.95265429713, 15909.284082216096, 16254.593635159215, 16856.241469134162, 17012.149392530573, 38525.0, 38547.79604753434, 38730.41904371493, 39605.60394396019, 39702.20595588014, 52580.47419525756, 53019.0, 53247.46752532425, 53324.46675533195, 54937.0, 7139.286071392819, 7169.243535333877, 7176.677049532888, 7339.926600733925, 7359.963200183965, 9347.626094956126, 12088.0, 12120.636380908587, 12264.693382665504, 12357.32034738098, 9651.372660777028, 9845.212383009486, 10026.398416095057, 10205.285630005828, 10285.228607854591, 10220.131288840234, 10445.425501597181, 10700.678979630624, 10755.085817705583, 11081.224314297922, 9308.162265396148, 9816.65641702537, 9869.98965315557, 10020.949895250476, 10108.191344692259, 6787.0, 7245.927540724525, 7264.382527485223, 7339.816504587428, 7495.812604684927, 8015.358771298165, 9707.6116955321, 9739.756506087404, 9799.85300220483, 9817.803643927224], "value": 8441.4380479154, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1196.4675719304912, 1261.949522019119, 1318.5055604148445, 1343.4156142078195, 1652.958676033099, 6332.905006424908, 7339.522931009487, 7598.696052157921, 7610.88583671244, 7841.451098423113, 7647.808804779883, 7715.035620547432, 7716.884246736304, 7718.614069296541, 7725.652345644446, 2147.8818664973383, 2175.2169219081197, 2175.749788774295, 2178.1505212966954, 2178.2593918067855, 2099.6745504446835, 2143.7749036351115, 2163.5348400093976, 2163.8593491423007, 2164.8701077935375, 2066.173530587762, 2081.4172031831113, 2083.760367557735, 2087.3946555498965, 2115.9471013224716, 2088.0, 2091.1008266445424, 2092.9058192381226, 2093.288281984125, 2093.4347726114015, 2023.1300540767463, 2051.825594824442, 2052.7331446911953, 2055.856090073695, 2056.167252262836, 7573.697052117908, 7600.467967242295, 7661.425393095485, 7715.6913723451, 7827.217278272185, 4127.236461254677, 4310.849120280806, 4317.956820431787, 4327.848525301631, 4328.7835608219775, 2672.50558646651, 2773.1264651634874, 2814.057290807582, 2831.476176907279, 2840.4745122152467, 885.3979294080023, 886.3308202307281, 897.5153417154729, 898.8112496375769, 909.6725178935578, 10347.482625868752, 10431.634892778717, 10451.320664156881, 10471.528781204865, 10472.424016679079, 3646.3254297954963, 3791.7724936503346, 3963.365861462152, 3999.3800960851113, 4005.598040685795, 8296.917030829734, 8307.875381869277, 8328.33373330132, 8342.290905273, 8344.207300306462, 4297.978510107429, 4298.699091063658, 4300.548442413564, 4302.7633480158265, 4302.89242768927, 3328.86684532616, 3822.48396466479, 3911.4328422378594, 3996.0409501719378, 4009.8195581198916, 3170.920726981844, 3235.4823228283362, 3297.686719761619, 3338.76628635998, 3344.0, 979.1383582447401, 986.3933680786265, 990.405756546081, 993.0317940008434, 1000.5847573257163, 616.901295792671, 644.9841499638089, 658.3876994395258, 668.4051194436955, 701.225146213437, 4736.100140973273, 4867.683600565952, 4903.41159060915, 4905.3132561441425, 5181.4559471255725, 394.5521832719884, 395.36543847125523, 406.8250652219574, 407.8959865234346, 409.57404299528156, 3166.145140811968, 3426.7087297579983, 3519.1729943463265, 3534.964650353515, 3565.4879943255874, 9500.762480938032, 9641.951790241004, 9772.951135244279, 9780.608775649036, 9782.755431114138, 9688.12806847387, 9701.805963880683, 9702.223822094216, 9715.562799674031, 9749.463779492055, 7162.355388015104, 7330.120385553768, 7343.3390994810725, 7346.1551921528635, 7347.0, 4983.227599722049, 5117.1045067113855, 5162.457941916195, 5163.948360516347, 5364.919526207032, 8913.465192088495, 8928.464292142493, 8941.105889411267, 8950.597223125105, 9050.818983620158, 8842.245510768016, 9030.096990300917, 9030.322725795722, 9081.818363632823, 9188.127127922837, 6602.7689030883685, 6644.734210631714, 6708.966455167693, 6712.597244165366, 6774.93225067743, 7181.533200341961, 7240.674169662272, 7244.4566657501955, 7253.891191632024, 7255.56466612005, 6362.363763623601, 6362.586431881913, 6367.140436041261, 6511.967440162769, 6560.081588577507, 6379.649119298483, 6389.361063893573, 6424.421802037839, 6430.0, 6435.163428754232, 5957.344692083954, 6051.304100028553, 6057.121717350871, 6063.878722425438, 6071.51427885777, 4072.9185416292103, 4165.0, 4218.409422680766, 4236.25865473547, 4346.261135606891, 4293.635041021548, 4332.133573285292, 4381.956180438282, 4504.77476126199, 4626.514216007406], "value": 3864.619541002783, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [279.86146857305647, 284.0526842978666, 292.1425615817577, 293.1732514309647, 294.74651799452465, 4647.7443740594335, 4813.614910807136, 4814.422269327685, 4866.221404575264, 4874.85375438737, 4601.884952876179, 4714.339992401058, 4728.645351598627, 4737.431508219019, 4755.381800365947, 3774.6411291934883, 3785.05069889006, 3794.7153963452715, 3795.582485926546, 3812.694984401255, 3582.3730847101783, 3609.458581212826, 3769.868054618089, 3804.6005169457226, 3810.1998580298173, 3777.376732839078, 3784.2242340320167, 3798.5631652359916, 3801.980990095046, 3811.2949104415634, 3780.9621903781162, 3781.45168950502, 3796.734228603999, 3797.259534390781, 3797.810109494514, 3360.7647464677484, 3360.9831950840335, 3364.7812892161933, 3376.6960973512255, 3387.932241355159, 4488.483824360208, 4640.651951103647, 4646.163690535702, 4693.859184224479, 4728.952710472886, 4287.935680964788, 4291.6566674666265, 4297.742135471882, 4299.570042995707, 4300.505441874193, 4982.0, 5002.874928126826, 5006.4743201964, 5010.219328272807, 5011.749412529395, 3387.4241378965635, 3494.405950988338, 3538.380783362901, 3540.2211513466996, 3554.626764189749, 5227.60792940531, 5250.159974404115, 5257.947420525784, 5261.4475480074425, 5268.446813084647, 3558.946615800765, 3572.6070132285427, 3588.6052534221217, 3590.8743193988084, 3594.8741794037082, 3433.570803649544, 3605.531280933514, 3627.1294889226397, 3639.745217834779, 3640.508531348234, 4137.600191888698, 4142.570813069487, 4142.937855932163, 4152.0, 4167.166566686675, 4985.426675932242, 4997.100521906092, 4999.825006124841, 4999.925001124986, 5007.899842003139, 1175.2830773228325, 1218.7014181525406, 1248.5392091253254, 1254.1659796235401, 1271.4278574641264, 834.3325339728193, 847.6651722569674, 854.6154230596256, 862.5083702289635, 868.1362044765373, 4365.628921541705, 4389.714668546534, 4392.758398288061, 4396.318570621559, 4402.889927751768, 5066.240063990431, 5073.0, 5088.592912566985, 5096.324429674451, 5106.285120083191, 4368.453943257093, 4381.261711430356, 4385.004822805616, 4387.188370151468, 4389.209942210369, 2325.8023068039406, 2386.3795413192347, 2416.395901024744, 2420.9031638734605, 2427.00492797952, 4287.277578737524, 4294.935575966363, 4298.130313313719, 4310.685241001462, 4311.827526898889, 4620.620380504043, 4627.722336659811, 4635.304704294383, 4652.743759185001, 4654.627629789608, 4064.695147863922, 4306.20335237982, 4330.718503297276, 4342.741774277421, 4345.435093437833, 4499.77501124949, 4514.390557274726, 4518.615917647036, 4578.954210457986, 4584.312353147055, 4394.956050439582, 4481.0, 4525.117602067565, 4531.388262584509, 4532.456105267389, 4015.257177422186, 4153.958460415357, 4274.786260686891, 4345.304751239786, 4392.8591057394115, 3272.869085236564, 3292.489664102068, 3310.1724568857785, 3310.6854848789326, 3329.900102996914, 4540.728595993127, 4558.77206139685, 4574.245249533789, 4579.633629309714, 4596.264597664358, 4810.374651295309, 4822.469528351809, 4895.0, 4924.63065270099, 4937.481564435826, 4930.753462326941, 4972.179590367694, 5009.273655319886, 5016.849494515171, 5022.221555658879, 4772.7610645784525, 4952.678075925053, 4956.479569645136, 4961.355023846877, 4980.825671101494, 4911.263310503453, 4963.404391473046, 4971.776270067928, 4979.435590670735, 4989.55094041538, 4904.828331008398, 4912.870039890917, 4926.9306891106535, 4931.778069986787, 4956.801727930842], "value": 3650.793152645121, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [564.8503146666131, 598.2850493660076, 652.5790864892144, 657.516725206973, 730.466759265736, 12711.491540338387, 13361.0, 13370.465181392732, 13379.063465557414, 13391.397387117579, 13685.521006764766, 13962.511312104078, 13966.79049814251, 13985.79021314681, 13988.230647314394, 615.5783288447428, 735.0774777654036, 737.5832654550189, 758.3932853717032, 761.733393312339, 759.9734009309675, 760.4714723267332, 762.4662736084736, 762.4662736084736, 763.6181909045478, 813.5037627047496, 814.1410811593767, 814.1614137438419, 815.3517953227199, 816.4692949582789, 649.5680372552247, 775.2789905387987, 794.0233512779314, 795.586295126531, 809.7692157735047, 586.9823905282848, 587.0196771391786, 587.2923127631237, 587.3245767367519, 587.4624718382674, 13452.798208026887, 13548.86451135486, 13565.864341356559, 13585.59243222695, 13591.116577422436, 12493.0, 12707.745845083047, 12905.35473226335, 13142.802857957138, 13435.0, 11374.658760237206, 11811.586594469152, 11826.290422574673, 12070.517179312817, 12221.633350999484, 4111.465509483748, 4140.358244472111, 4227.196832601796, 4238.7880605969885, 4265.083007153446, 68280.0, 68341.65829170823, 68356.31643683549, 68384.65807670979, 68426.65786671084, 9722.671470443343, 9864.457454839909, 10079.445630490394, 10084.596616135272, 10307.742306442398, 19413.51466213356, 19418.320358787376, 19508.0, 19527.60944781125, 19613.901930490258, 6672.0, 6761.628110453874, 6828.283030281848, 6848.212455567574, 6917.204521479994, 3634.7637403568683, 3641.126129728846, 3661.176235347026, 3686.4286035664513, 3737.364648009845, 2529.1148098165863, 2594.2347007632416, 2603.4142317978303, 2604.270804174796, 2604.9869750651123, 472.9101470720552, 479.0443066083186, 480.526681218999, 486.26088345714317, 490.77914938277917, 467.78949472737406, 476.68300580114527, 481.7037521923992, 485.39568237544484, 514.629466783915, 6824.727010919508, 6845.657717114224, 7030.050943122716, 7064.92935070653, 7269.7455589054125, 478.2921276510784, 480.5626879539602, 484.72128526097634, 489.5422779700991, 497.87304237419227, 3188.9362212755614, 3348.765586408928, 3427.640097789746, 3609.4946707460977, 3681.226942342138, 103463.48268258761, 104645.47677261564, 105201.47399263107, 106250.0, 106949.0, 87481.0, 88896.5555172233, 89313.0, 90648.5467572671, 93451.53274233585, 49459.50540494621, 70528.64735676392, 78505.60747196342, 80741.19258807336, 81353.77969330465, 6782.152230971129, 6925.272846351262, 6966.233714291323, 6978.337057979484, 7111.82220444493, 41576.58423415848, 46390.76804615955, 46512.8371790708, 47395.0, 49721.25418118659, 70123.64938175277, 71424.0, 74095.0, 74102.0, 76067.0, 8056.274935255855, 8105.0, 8440.0, 8441.577921103797, 8630.525321107149, 9616.567254473706, 9770.413775173512, 9822.0, 9938.950305248427, 9991.550380232762, 7870.763877083697, 9871.506424678593, 9881.851772223567, 10727.356358618508, 10905.618303359344, 10784.838227426439, 10894.400807955639, 11083.612073577386, 11970.640880773592, 11973.880261197624, 10437.895621043692, 11285.37930413835, 11689.766204675689, 12029.879701202875, 12349.19730217533, 9155.633774648939, 9503.952480237554, 9565.282603804882, 9619.5671194795, 9628.71113866557, 8974.596143173703, 9486.573104210465, 9539.0, 9619.27855410861, 9641.42151470914], "value": 6342.336463840914, "unit": "score"}], "name": "dromaeo_css", "value": 6224.837236619028, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 116.0, 113.0, 113.0, 117.0, 115.0, 114.0, 115.0, 115.0, 113.0], "value": 113.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 120.0, 122.0, 202.0, 122.0, 123.0, 152.0, 125.0, 184.0, 122.0], "value": 142.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 162.0, 162.0, 162.0, 162.0, 161.0, 161.0, 161.0, 161.0, 161.0], "value": 161.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 76.0, 76.0, 112.0, 76.0, 77.0, 75.0, 76.0, 126.0, 76.0], "value": 84.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 86.0, 134.0, 84.0, 85.0, 85.0, 116.0, 84.0, 84.0, 133.0], "value": 97.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 116.0, 116.0, 116.0, 117.0, 118.0, 117.0, 117.0, 117.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 146.0, 146.0, 147.0, 146.0, 209.0, 146.0, 146.0, 146.0], "value": 152.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 94.0, 95.0, 95.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 64.0, 64.0, 63.0, 64.0, 62.0, 63.0, 63.0, 61.0, 64.0], "value": 63.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 64.0, 65.0, 66.0, 65.0, 65.0, 66.0, 65.0, 65.0], "value": 65.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 79.0, 79.0, 78.0, 79.0, 83.0, 80.0, 78.0, 80.0, 78.0], "value": 79.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 150.0, 129.0, 131.0, 128.0, 128.0, 129.0, 138.0, 159.0, 130.0], "value": 135.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 162.0, 163.0, 163.0, 161.0, 169.0, 166.0, 163.0, 164.0, 163.0], "value": 164.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 65.0, 67.0, 66.0, 70.0, 67.0, 65.0, 70.0, 65.0, 65.0], "value": 67.0, "unit": "score"}], "name": "kraken", "value": 1538.3, "alertThreshold": 2.0}]} 16:00:49 INFO - Return code: 0 16:00:49 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:00:49 INFO - # TBPL SUCCESS # 16:00:49 INFO - Running post-action listener: _resource_record_post_action 16:00:49 INFO - [mozharness: 2016-08-08 23:00:49.292708Z] Finished run-tests step (success) 16:00:49 INFO - Running post-run listener: _resource_record_post_run 16:00:49 INFO - Total resource usage - Wall time: 1202s; CPU: 13.0%; Read bytes: 0; Write bytes: 305860608; Read time: 0; Write time: 480044 16:00:49 INFO - TinderboxPrint: CPU usage
13.1% 16:00:49 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 16:00:49 INFO - TinderboxPrint: I/O write bytes / time
305,860,608 / 480,044 16:00:49 INFO - TinderboxPrint: CPU idle
8,340.4 (86.9%) 16:00:49 INFO - TinderboxPrint: CPU user
1,204.1 (12.5%) 16:00:49 INFO - TinderboxPrint: Swap in / out
0 / 0 16:00:49 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 13348864; Read time: 0; Write time: 15104 16:00:49 INFO - run-tests - Wall time: 1186s; CPU: 13.0%; Read bytes: 0; Write bytes: 291618816; Read time: 0; Write time: 464840 16:00:49 INFO - Running post-run listener: _upload_blobber_files 16:00:49 INFO - Blob upload gear active. 16:00:49 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:00:49 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 16:00:49 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'] 16:00:49 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 16:00:50 INFO - (blobuploader) - INFO - Open directory for files ... 16:00:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:00:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:00:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:00:53 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:00:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:00:53 INFO - (blobuploader) - INFO - Done attempting. 16:00:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:00:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:00:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:00:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:00:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:00:54 INFO - (blobuploader) - INFO - Done attempting. 16:00:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:00:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:00:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:00:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:00:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:00:54 INFO - (blobuploader) - INFO - Done attempting. 16:00:54 INFO - (blobuploader) - INFO - Iteration through files over. 16:00:54 INFO - Return code: 0 16:00:54 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:00:54 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:00:54 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/04f8d8a032198634859189e2ca094e08ebbe73cfa509d7cedaa84ea82b1b9a3602eb07b9f5bd26ae0753bbcb651373b6383bad2d1defa75ef923b4b086c2bc54", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/657df29b7354a78e95fd484bcddf043853a08364a64232cf9dc764f57ee49b3d372d118ac602122f0987b66e347e22163aabbc9965fc3054026af5cdea36c7c3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/442beec6c00e786c4c16e7ae868bae71e376803f99f3fa62dc42fa72ad4ff435599db37339c54152c0b23ae0ef877dbf6a8c6912cb311965721d06ab64b95309"} 16:00:54 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:00:54 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:00:54 INFO - Contents: 16:00:54 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/04f8d8a032198634859189e2ca094e08ebbe73cfa509d7cedaa84ea82b1b9a3602eb07b9f5bd26ae0753bbcb651373b6383bad2d1defa75ef923b4b086c2bc54", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/657df29b7354a78e95fd484bcddf043853a08364a64232cf9dc764f57ee49b3d372d118ac602122f0987b66e347e22163aabbc9965fc3054026af5cdea36c7c3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/442beec6c00e786c4c16e7ae868bae71e376803f99f3fa62dc42fa72ad4ff435599db37339c54152c0b23ae0ef877dbf6a8c6912cb311965721d06ab64b95309"} 16:00:54 INFO - Running post-run listener: copy_logs_to_upload_dir 16:00:54 INFO - Copying logs to upload dir... 16:00:54 INFO - mkdir: /builds/slave/test/build/upload/logs 16:00:54 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1288.771760 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 28 secs) (at 2016-08-08 16:00:54.603763) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-08 16:00:54.607744) ========= 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=745a4373a2a16df560f216e000000264-1470695867.787586-1429825616 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/04f8d8a032198634859189e2ca094e08ebbe73cfa509d7cedaa84ea82b1b9a3602eb07b9f5bd26ae0753bbcb651373b6383bad2d1defa75ef923b4b086c2bc54", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/657df29b7354a78e95fd484bcddf043853a08364a64232cf9dc764f57ee49b3d372d118ac602122f0987b66e347e22163aabbc9965fc3054026af5cdea36c7c3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/442beec6c00e786c4c16e7ae868bae71e376803f99f3fa62dc42fa72ad4ff435599db37339c54152c0b23ae0ef877dbf6a8c6912cb311965721d06ab64b95309"} build_url:https://queue.taskcluster.net/v1/task/dqtt3hmnRH-W9Yl1puYrUA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011327 build_url: 'https://queue.taskcluster.net/v1/task/dqtt3hmnRH-W9Yl1puYrUA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/04f8d8a032198634859189e2ca094e08ebbe73cfa509d7cedaa84ea82b1b9a3602eb07b9f5bd26ae0753bbcb651373b6383bad2d1defa75ef923b4b086c2bc54", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/657df29b7354a78e95fd484bcddf043853a08364a64232cf9dc764f57ee49b3d372d118ac602122f0987b66e347e22163aabbc9965fc3054026af5cdea36c7c3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/442beec6c00e786c4c16e7ae868bae71e376803f99f3fa62dc42fa72ad4ff435599db37339c54152c0b23ae0ef877dbf6a8c6912cb311965721d06ab64b95309"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-08 16:00:54.637481) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-08 16:00:54.637865) ========= 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=745a4373a2a16df560f216e000000264-1470695867.787586-1429825616 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004446 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-08 16:00:54.723363) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-08 16:00:54.723733) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-08 16:00:54.724067) ========= ========= Total master_lag: 0.20 =========