builder: autoland_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-058 starttime: 1469152793.75 results: success (0) buildid: 20160721180915 builduid: a4d1c730b7114f0db9e699c3474a948b revision: 3ab20077e16df40c9e4a61860c69f7b1eec024da ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-21 18:59:53.752549) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-21 18:59:53.752895) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-21 18:59:53.753163) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-21 18:59:53.796127) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 18:59:53.796403) ========= 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=0e2a256762fed810e2f0f810000001b3-1469151937.637665-1236963751 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-21 18:59:53-- 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% 114M=0s 2016-07-21 18:59:53 (114 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.138164 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 18:59:53.950746) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 18:59:53.951019) ========= 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=0e2a256762fed810e2f0f810000001b3-1469151937.637665-1236963751 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017471 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 18:59:54.009952) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 18:59:54.010250) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 3ab20077e16df40c9e4a61860c69f7b1eec024da --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 3ab20077e16df40c9e4a61860c69f7b1eec024da --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=0e2a256762fed810e2f0f810000001b3-1469151937.637665-1236963751 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-21 18:59:54,115 truncating revision to first 12 chars 2016-07-21 18:59:54,115 Setting DEBUG logging. 2016-07-21 18:59:54,115 attempt 1/10 2016-07-21 18:59:54,115 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/3ab20077e16d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-21 18:59:54,476 unpacking tar archive at: autoland-3ab20077e16d/testing/mozharness/ program finished with exit code 0 elapsedTime=0.702802 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 18:59:54.731182) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-21 18:59:54.731502) ========= script_repo_revision: 3ab20077e16df40c9e4a61860c69f7b1eec024da ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-21 18:59:54.731895) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-21 18:59:54.732159) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-21 18:59:54.741840) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2016-07-21 18:59:54.742103) ========= /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=0e2a256762fed810e2f0f810000001b3-1469151937.637665-1236963751 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:59:54 INFO - MultiFileLogger online at 20160721 18:59:54 in /builds/slave/test 18:59:54 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 18:59:54 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:59:54 INFO - {'append_to_log': False, 18:59:54 INFO - 'base_work_dir': '/builds/slave/test', 18:59:54 INFO - 'blob_upload_branch': 'Autoland-Non-PGO', 18:59:54 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:59:54 INFO - 'branch': 'Autoland-Non-PGO', 18:59:54 INFO - 'buildbot_json_path': 'buildprops.json', 18:59:54 INFO - 'config_files': ('talos/linux_config.py',), 18:59:54 INFO - 'default_actions': ('clobber', 18:59:54 INFO - 'read-buildbot-config', 18:59:54 INFO - 'download-and-extract', 18:59:54 INFO - 'populate-webroot', 18:59:54 INFO - 'create-virtualenv', 18:59:54 INFO - 'install', 18:59:54 INFO - 'run-tests'), 18:59:54 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:59:54 INFO - 'download_minidump_stackwalk': True, 18:59:54 INFO - 'download_symbols': 'ondemand', 18:59:54 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:59:54 INFO - 'tooltool.py': '/tools/tooltool.py', 18:59:54 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:59:54 INFO - '/tools/misc-python/virtualenv.py')}, 18:59:54 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:59:54 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:59:54 INFO - 'installer_path': 'installer.exe', 18:59:54 INFO - 'log_level': 'info', 18:59:54 INFO - 'log_name': 'talos', 18:59:54 INFO - 'log_to_console': True, 18:59:54 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:59:54 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:59:54 INFO - 'opt_config_files': (), 18:59:54 INFO - 'pip_index': False, 18:59:54 INFO - 'sps_profile': False, 18:59:54 INFO - 'sps_profile_interval': 0, 18:59:54 INFO - 'suite': 'dromaeojs', 18:59:54 INFO - 'system_bits': '32', 18:59:54 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:59:54 INFO - 'title': 'talos-linux64-ix-058', 18:59:54 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:59:54 INFO - 'use_talos_json': True, 18:59:54 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:59:54 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:59:54 INFO - 'work_dir': 'build'} 18:59:54 INFO - [mozharness: 2016-07-22 01:59:54.942657Z] Running clobber step. 18:59:54 INFO - Running pre-action listener: _resource_record_pre_action 18:59:54 INFO - Running main action method: clobber 18:59:54 INFO - rmtree: /builds/slave/test/build 18:59:54 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:59:55 INFO - Running post-action listener: _resource_record_post_action 18:59:55 INFO - [mozharness: 2016-07-22 01:59:55.928189Z] Finished clobber step (success) 18:59:55 INFO - [mozharness: 2016-07-22 01:59:55.928290Z] Running read-buildbot-config step. 18:59:55 INFO - Running pre-action listener: _resource_record_pre_action 18:59:55 INFO - Running main action method: read_buildbot_config 18:59:55 INFO - Using buildbot properties: 18:59:55 INFO - { 18:59:55 INFO - "project": "", 18:59:55 INFO - "product": "firefox", 18:59:55 INFO - "script_repo_revision": "production", 18:59:55 INFO - "builddir": "autoland_ubuntu64_hw_test-dromaeojs", 18:59:55 INFO - "repository": "", 18:59:55 INFO - "buildername": "Ubuntu HW 12.04 x64 autoland talos dromaeojs", 18:59:55 INFO - "buildid": "20160721180915", 18:59:55 INFO - "pgo_build": "False", 18:59:55 INFO - "basedir": "/builds/slave/test", 18:59:55 INFO - "buildnumber": 81, 18:59:55 INFO - "slavename": "talos-linux64-ix-058", 18:59:55 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 18:59:55 INFO - "platform": "ubuntu64_hw", 18:59:55 INFO - "scheduler": "tests-autoland-linux64-talos", 18:59:55 INFO - "branch": "autoland", 18:59:55 INFO - "repo_path": "integration/autoland", 18:59:55 INFO - "revision": "3ab20077e16df40c9e4a61860c69f7b1eec024da", 18:59:55 INFO - "stage_platform": "linux64", 18:59:55 INFO - "builduid": "a4d1c730b7114f0db9e699c3474a948b", 18:59:55 INFO - "slavebuilddir": "test" 18:59:55 INFO - } 18:59:55 INFO - Found installer url https://queue.taskcluster.net/v1/task/Q9tHZZfnT_qmxPhipieTCQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 18:59:55 INFO - Running post-action listener: _resource_record_post_action 18:59:55 INFO - [mozharness: 2016-07-22 01:59:55.930780Z] Finished read-buildbot-config step (success) 18:59:55 INFO - [mozharness: 2016-07-22 01:59:55.930869Z] Running download-and-extract step. 18:59:55 INFO - Running pre-action listener: _resource_record_pre_action 18:59:55 INFO - Running main action method: download_and_extract 18:59:55 INFO - mkdir: /builds/slave/test/build/tests 18:59:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:59:55 INFO - https://queue.taskcluster.net/v1/task/Q9tHZZfnT_qmxPhipieTCQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 18:59:55 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q9tHZZfnT_qmxPhipieTCQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 18:59:55 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q9tHZZfnT_qmxPhipieTCQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 18:59:55 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q9tHZZfnT_qmxPhipieTCQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 18:59:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q9tHZZfnT_qmxPhipieTCQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 18:59:58 INFO - Downloaded 1361 bytes. 18:59:58 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 18:59:58 INFO - Using the following test package requirements: 18:59:58 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 18:59:58 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 18:59:58 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 18:59:58 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 18:59:58 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 18:59:58 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 18:59:58 INFO - u'jsshell-linux-x86_64.zip'], 18:59:58 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 18:59:58 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 18:59:58 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 18:59:58 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 18:59:58 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 18:59:58 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 18:59:58 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 18:59:58 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 18:59:58 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 18:59:58 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 18:59:58 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 18:59:58 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 18:59:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:59:58 INFO - https://queue.taskcluster.net/v1/task/Q9tHZZfnT_qmxPhipieTCQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 18:59:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q9tHZZfnT_qmxPhipieTCQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 18:59:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q9tHZZfnT_qmxPhipieTCQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 18:59:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q9tHZZfnT_qmxPhipieTCQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 18:59:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q9tHZZfnT_qmxPhipieTCQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 18:59:59 INFO - Downloaded 22737165 bytes. 18:59:59 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 18:59:59 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 19:00:00 INFO - Return code: 0 19:00:00 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 19:00:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:00:00 INFO - https://queue.taskcluster.net/v1/task/Q9tHZZfnT_qmxPhipieTCQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 19:00:00 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q9tHZZfnT_qmxPhipieTCQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 19:00:00 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q9tHZZfnT_qmxPhipieTCQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 19:00:00 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q9tHZZfnT_qmxPhipieTCQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 19:00:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q9tHZZfnT_qmxPhipieTCQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 19:00:05 INFO - Downloaded 22737165 bytes. 19:00:05 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 19:00:05 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 19:00:06 INFO - Return code: 0 19:00:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:00:06 INFO - https://queue.taskcluster.net/v1/task/Q9tHZZfnT_qmxPhipieTCQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 19:00:06 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q9tHZZfnT_qmxPhipieTCQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 19:00:06 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q9tHZZfnT_qmxPhipieTCQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 19:00:06 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q9tHZZfnT_qmxPhipieTCQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 19:00:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q9tHZZfnT_qmxPhipieTCQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 19:00:07 INFO - Downloaded 14403289 bytes. 19:00:07 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 19:00:07 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 19:00:08 INFO - Return code: 0 19:00:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:00:08 INFO - https://queue.taskcluster.net/v1/task/Q9tHZZfnT_qmxPhipieTCQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 19:00:08 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q9tHZZfnT_qmxPhipieTCQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 19:00:08 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q9tHZZfnT_qmxPhipieTCQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 19:00:08 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q9tHZZfnT_qmxPhipieTCQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 19:00:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q9tHZZfnT_qmxPhipieTCQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 19:00:09 INFO - Downloaded 57158829 bytes. 19:00:09 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Q9tHZZfnT_qmxPhipieTCQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 19:00:09 INFO - mkdir: /builds/slave/test/properties 19:00:09 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:00:09 INFO - Writing to file /builds/slave/test/properties/build_url 19:00:09 INFO - Contents: 19:00:09 INFO - build_url:https://queue.taskcluster.net/v1/task/Q9tHZZfnT_qmxPhipieTCQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 19:00:10 INFO - Running post-action listener: _resource_record_post_action 19:00:10 INFO - Running post-action listener: set_extra_try_arguments 19:00:10 INFO - [mozharness: 2016-07-22 02:00:10.334318Z] Finished download-and-extract step (success) 19:00:10 INFO - [mozharness: 2016-07-22 02:00:10.334552Z] Running populate-webroot step. 19:00:10 INFO - Running pre-action listener: _resource_record_pre_action 19:00:10 INFO - Running main action method: populate_webroot 19:00:10 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:00:10 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:00:10 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:00:10 INFO - '--tppagecycles', 19:00:10 INFO - '7'], 19:00:10 INFO - 'tests': ['tsvgm']}}, 19:00:10 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:00:10 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:00:10 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:00:10 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:00:10 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:00:10 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:00:10 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:00:10 INFO - 'talos_options': ['--disable-e10s'], 19:00:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:00:10 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:00:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:00:10 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:00:10 INFO - 'talos_options': ['--disable-e10s'], 19:00:10 INFO - 'tests': ['damp', 'tps']}, 19:00:10 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:00:10 INFO - 'tests': ['damp', 'tps']}, 19:00:10 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:00:10 INFO - 'tests': ['dromaeo_dom']}, 19:00:10 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:00:10 INFO - 'g4': {'talos_options': ['--disable-e10s'], 19:00:10 INFO - 'tests': ['basic_compositor_video']}, 19:00:10 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 19:00:10 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:00:10 INFO - 'tests': ['a11yr', 19:00:10 INFO - 'ts_paint', 19:00:10 INFO - 'tpaint', 19:00:10 INFO - 'sessionrestore', 19:00:10 INFO - 'sessionrestore_no_auto_restore', 19:00:10 INFO - 'tabpaint']}, 19:00:10 INFO - 'other-e10s': {'tests': ['a11yr', 19:00:10 INFO - 'ts_paint', 19:00:10 INFO - 'tpaint', 19:00:10 INFO - 'sessionrestore', 19:00:10 INFO - 'sessionrestore_no_auto_restore', 19:00:10 INFO - 'tabpaint']}, 19:00:10 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:00:10 INFO - 'ts_paint', 19:00:10 INFO - 'tpaint', 19:00:10 INFO - 'sessionrestore', 19:00:10 INFO - 'sessionrestore_no_auto_restore', 19:00:10 INFO - 'tabpaint']}, 19:00:10 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:00:10 INFO - 'ts_paint', 19:00:10 INFO - 'tpaint', 19:00:10 INFO - 'sessionrestore', 19:00:10 INFO - 'sessionrestore_no_auto_restore', 19:00:10 INFO - 'tabpaint']}, 19:00:10 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:00:10 INFO - 'tests': ['a11yr', 19:00:10 INFO - 'ts_paint', 19:00:10 INFO - 'tpaint', 19:00:10 INFO - 'sessionrestore', 19:00:10 INFO - 'sessionrestore_no_auto_restore', 19:00:10 INFO - 'tabpaint']}, 19:00:10 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:00:10 INFO - 'tests': ['a11yr', 19:00:10 INFO - 'ts_paint', 19:00:10 INFO - 'tpaint', 19:00:10 INFO - 'sessionrestore', 19:00:10 INFO - 'sessionrestore_no_auto_restore', 19:00:10 INFO - 'tabpaint']}, 19:00:10 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:00:10 INFO - 'tests': ['tsvgx', 19:00:10 INFO - 'tsvgr_opacity', 19:00:10 INFO - 'tart', 19:00:10 INFO - 'tscrollx', 19:00:10 INFO - 'cart']}, 19:00:10 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:00:10 INFO - 'tsvgr_opacity', 19:00:10 INFO - 'tart', 19:00:10 INFO - 'tscrollx', 19:00:10 INFO - 'cart']}, 19:00:10 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:00:10 INFO - 'talos_options': ['--disable-e10s'], 19:00:10 INFO - 'tests': ['tp5o']}, 19:00:10 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:00:10 INFO - 'tests': ['tp5o']}, 19:00:10 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:00:10 INFO - 'talos_options': ['--disable-e10s', 19:00:10 INFO - '--xperf_path', 19:00:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:00:10 INFO - 'tests': ['tp5n']}, 19:00:10 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:00:10 INFO - 'talos_options': ['--xperf_path', 19:00:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:00:10 INFO - 'tests': ['tp5n']}}, 19:00:10 INFO - 'talos.zip': {'path': '', 19:00:10 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:00:10 INFO - Running post-action listener: _resource_record_post_action 19:00:10 INFO - [mozharness: 2016-07-22 02:00:10.357194Z] Finished populate-webroot step (success) 19:00:10 INFO - [mozharness: 2016-07-22 02:00:10.357283Z] Running create-virtualenv step. 19:00:10 INFO - Running pre-action listener: _resource_record_pre_action 19:00:10 INFO - Running main action method: create_virtualenv 19:00:10 INFO - Creating virtualenv /builds/slave/test/build/venv 19:00:10 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 19:00:10 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:00:10 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:00:10 INFO - Using real prefix '/usr' 19:00:10 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:00:10 INFO - Installing distribute.............................................................................................................................................................................................done. 19:00:12 INFO - Installing pip.................done. 19:00:12 INFO - Return code: 0 19:00:12 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:00:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:00:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:00:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:00:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:00:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:00:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:00:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2880030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29e38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b41180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b3f3b0>, '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 0x2b402b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b40740>, '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': '0e2a256762fed810e2f0f810000001b3-1469151937.637665-1236963751', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 19:00:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 19:00:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 19:00:12 INFO - Using env: {'DISPLAY': ':0', 19:00:12 INFO - 'HOME': '/home/cltbld', 19:00:12 INFO - 'LANG': 'en_US.UTF-8', 19:00:12 INFO - 'LANGUAGE': 'en_US:en', 19:00:12 INFO - 'LOGNAME': 'cltbld', 19:00:12 INFO - 'MAIL': '/var/mail/cltbld', 19:00:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:00:12 INFO - 'MOZ_NO_REMOTE': '1', 19:00:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:00:12 INFO - 'NO_EM_RESTART': '1', 19:00:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:00:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:00:12 INFO - 'PWD': '/builds/slave/test', 19:00:12 INFO - 'SHELL': '/bin/bash', 19:00:12 INFO - 'SHLVL': '1', 19:00:12 INFO - 'TERM': 'linux', 19:00:12 INFO - 'TMOUT': '86400', 19:00:12 INFO - 'USER': 'cltbld', 19:00:12 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1469151937.637665-1236963751', 19:00:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:00:12 INFO - '_': '/tools/buildbot/bin/python'} 19:00:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:00:13 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 19:00:13 INFO - Cleaning up... 19:00:13 INFO - Return code: 0 19:00:13 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:00:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:00:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:00:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:00:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:00:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:00:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:00:13 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 0x2880030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29e38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b41180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b3f3b0>, '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 0x2b402b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b40740>, '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': '0e2a256762fed810e2f0f810000001b3-1469151937.637665-1236963751', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 19:00:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 19:00:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 19:00:13 INFO - Using env: {'DISPLAY': ':0', 19:00:13 INFO - 'HOME': '/home/cltbld', 19:00:13 INFO - 'LANG': 'en_US.UTF-8', 19:00:13 INFO - 'LANGUAGE': 'en_US:en', 19:00:13 INFO - 'LOGNAME': 'cltbld', 19:00:13 INFO - 'MAIL': '/var/mail/cltbld', 19:00:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:00:13 INFO - 'MOZ_NO_REMOTE': '1', 19:00:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:00:13 INFO - 'NO_EM_RESTART': '1', 19:00:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:00:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:00:13 INFO - 'PWD': '/builds/slave/test', 19:00:13 INFO - 'SHELL': '/bin/bash', 19:00:13 INFO - 'SHLVL': '1', 19:00:13 INFO - 'TERM': 'linux', 19:00:13 INFO - 'TMOUT': '86400', 19:00:13 INFO - 'USER': 'cltbld', 19:00:13 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1469151937.637665-1236963751', 19:00:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:00:13 INFO - '_': '/tools/buildbot/bin/python'} 19:00:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:00:13 INFO - Downloading/unpacking psutil>=3.1.1 19:00:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:00:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:00:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:00:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:00:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:00:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:00:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:00:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:00:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:00:15 INFO - Installing collected packages: psutil 19:00:15 INFO - Running setup.py install for psutil 19:00:15 INFO - building 'psutil._psutil_linux' extension 19:00:15 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 19:00:15 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 19:00:15 INFO - building 'psutil._psutil_posix' extension 19:00:15 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 19:00:15 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 19:00:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:00:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:00:15 INFO - Successfully installed psutil 19:00:15 INFO - Cleaning up... 19:00:15 INFO - Return code: 0 19:00:15 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:00:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:00:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:00:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:00:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:00:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:00:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:00:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2880030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29e38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b41180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b3f3b0>, '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 0x2b402b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b40740>, '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': '0e2a256762fed810e2f0f810000001b3-1469151937.637665-1236963751', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 19:00:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 19:00:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 19:00:15 INFO - Using env: {'DISPLAY': ':0', 19:00:15 INFO - 'HOME': '/home/cltbld', 19:00:15 INFO - 'LANG': 'en_US.UTF-8', 19:00:15 INFO - 'LANGUAGE': 'en_US:en', 19:00:15 INFO - 'LOGNAME': 'cltbld', 19:00:15 INFO - 'MAIL': '/var/mail/cltbld', 19:00:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:00:15 INFO - 'MOZ_NO_REMOTE': '1', 19:00:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:00:15 INFO - 'NO_EM_RESTART': '1', 19:00:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:00:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:00:15 INFO - 'PWD': '/builds/slave/test', 19:00:15 INFO - 'SHELL': '/bin/bash', 19:00:15 INFO - 'SHLVL': '1', 19:00:15 INFO - 'TERM': 'linux', 19:00:15 INFO - 'TMOUT': '86400', 19:00:15 INFO - 'USER': 'cltbld', 19:00:15 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1469151937.637665-1236963751', 19:00:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:00:15 INFO - '_': '/tools/buildbot/bin/python'} 19:00:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:00:16 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:00:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:00:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:00:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:00:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:00:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:00:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:00:18 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:00:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:00:18 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:00:18 INFO - Installing collected packages: mozsystemmonitor 19:00:18 INFO - Running setup.py install for mozsystemmonitor 19:00:18 INFO - Successfully installed mozsystemmonitor 19:00:18 INFO - Cleaning up... 19:00:18 INFO - Return code: 0 19:00:18 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 19:00:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:00:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:00:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:00:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:00:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:00:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:00:18 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 0x2880030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29e38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b41180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b3f3b0>, '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 0x2b402b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b40740>, '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': '0e2a256762fed810e2f0f810000001b3-1469151937.637665-1236963751', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 19:00:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 19:00:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 19:00:18 INFO - Using env: {'DISPLAY': ':0', 19:00:18 INFO - 'HOME': '/home/cltbld', 19:00:18 INFO - 'LANG': 'en_US.UTF-8', 19:00:18 INFO - 'LANGUAGE': 'en_US:en', 19:00:18 INFO - 'LOGNAME': 'cltbld', 19:00:18 INFO - 'MAIL': '/var/mail/cltbld', 19:00:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:00:18 INFO - 'MOZ_NO_REMOTE': '1', 19:00:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:00:18 INFO - 'NO_EM_RESTART': '1', 19:00:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:00:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:00:18 INFO - 'PWD': '/builds/slave/test', 19:00:18 INFO - 'SHELL': '/bin/bash', 19:00:18 INFO - 'SHLVL': '1', 19:00:18 INFO - 'TERM': 'linux', 19:00:18 INFO - 'TMOUT': '86400', 19:00:18 INFO - 'USER': 'cltbld', 19:00:18 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1469151937.637665-1236963751', 19:00:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:00:18 INFO - '_': '/tools/buildbot/bin/python'} 19:00:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:00:18 INFO - Downloading/unpacking jsonschema==2.5.1 19:00:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:00:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:00:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:00:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:00:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:00:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:00:20 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:00:20 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:00:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:00:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:00:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:00:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:00:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:00:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:00:20 INFO - Downloading functools32-3.2.3-2.tar.gz 19:00:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:00:20 INFO - warning: no files found matching '*.txt' 19:00:20 INFO - no previously-included directories found matching 'build' 19:00:20 INFO - no previously-included directories found matching 'dist' 19:00:20 INFO - no previously-included directories found matching '.git*' 19:00:20 INFO - Installing collected packages: jsonschema, functools32 19:00:20 INFO - Running setup.py install for functools32 19:00:20 INFO - warning: no files found matching '*.txt' 19:00:20 INFO - no previously-included directories found matching 'build' 19:00:20 INFO - no previously-included directories found matching 'dist' 19:00:20 INFO - no previously-included directories found matching '.git*' 19:00:20 INFO - Successfully installed jsonschema functools32 19:00:20 INFO - Cleaning up... 19:00:20 INFO - Return code: 0 19:00:20 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:00:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:00:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:00:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:00:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:00:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:00:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:00:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2880030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29e38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b41180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b3f3b0>, '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 0x2b402b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b40740>, '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': '0e2a256762fed810e2f0f810000001b3-1469151937.637665-1236963751', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 19:00:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 19:00:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 19:00:20 INFO - Using env: {'DISPLAY': ':0', 19:00:20 INFO - 'HOME': '/home/cltbld', 19:00:20 INFO - 'LANG': 'en_US.UTF-8', 19:00:20 INFO - 'LANGUAGE': 'en_US:en', 19:00:20 INFO - 'LOGNAME': 'cltbld', 19:00:20 INFO - 'MAIL': '/var/mail/cltbld', 19:00:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:00:20 INFO - 'MOZ_NO_REMOTE': '1', 19:00:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:00:20 INFO - 'NO_EM_RESTART': '1', 19:00:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:00:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:00:20 INFO - 'PWD': '/builds/slave/test', 19:00:20 INFO - 'SHELL': '/bin/bash', 19:00:20 INFO - 'SHLVL': '1', 19:00:20 INFO - 'TERM': 'linux', 19:00:20 INFO - 'TMOUT': '86400', 19:00:20 INFO - 'USER': 'cltbld', 19:00:20 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1469151937.637665-1236963751', 19:00:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:00:20 INFO - '_': '/tools/buildbot/bin/python'} 19:00:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:00:21 INFO - Downloading/unpacking blobuploader==1.2.4 19:00:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:00:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:00:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:00:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:00:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:00:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:00:22 INFO - Downloading blobuploader-1.2.4.tar.gz 19:00:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:00:23 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:00:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:00:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:00:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:00:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:00:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:00:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:00:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:00:23 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:00:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:00:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:00:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:00:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:00:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:00:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:00:23 INFO - Downloading docopt-0.6.1.tar.gz 19:00:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:00:24 INFO - Installing collected packages: blobuploader, requests, docopt 19:00:24 INFO - Running setup.py install for blobuploader 19:00:24 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:00:24 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:00:24 INFO - Running setup.py install for requests 19:00:24 INFO - Running setup.py install for docopt 19:00:24 INFO - Successfully installed blobuploader requests docopt 19:00:24 INFO - Cleaning up... 19:00:24 INFO - Return code: 0 19:00:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:00:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:00:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:00:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:00:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:00:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:00:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:00:24 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 0x2880030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29e38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b41180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b3f3b0>, '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 0x2b402b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b40740>, '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': '0e2a256762fed810e2f0f810000001b3-1469151937.637665-1236963751', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 19:00:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 19:00:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 19:00:24 INFO - Using env: {'DISPLAY': ':0', 19:00:24 INFO - 'HOME': '/home/cltbld', 19:00:24 INFO - 'LANG': 'en_US.UTF-8', 19:00:24 INFO - 'LANGUAGE': 'en_US:en', 19:00:24 INFO - 'LOGNAME': 'cltbld', 19:00:24 INFO - 'MAIL': '/var/mail/cltbld', 19:00:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:00:24 INFO - 'MOZ_NO_REMOTE': '1', 19:00:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:00:24 INFO - 'NO_EM_RESTART': '1', 19:00:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:00:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:00:24 INFO - 'PWD': '/builds/slave/test', 19:00:24 INFO - 'SHELL': '/bin/bash', 19:00:24 INFO - 'SHLVL': '1', 19:00:24 INFO - 'TERM': 'linux', 19:00:24 INFO - 'TMOUT': '86400', 19:00:24 INFO - 'USER': 'cltbld', 19:00:24 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1469151937.637665-1236963751', 19:00:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:00:24 INFO - '_': '/tools/buildbot/bin/python'} 19:00:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:00:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:00:24 INFO - Running setup.py (path:/tmp/pip-7IR2Ko-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:00:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:00:24 INFO - Running setup.py (path:/tmp/pip-Yx0_9P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:00:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:00:24 INFO - Running setup.py (path:/tmp/pip-gnGG04-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:00:25 INFO - Running setup.py (path:/tmp/pip-BR5dr7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:00:25 INFO - Running setup.py (path:/tmp/pip-m5kcGh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:00:25 INFO - Running setup.py (path:/tmp/pip-1f8jfW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:00:25 INFO - Running setup.py (path:/tmp/pip-mXAl9I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:00:25 INFO - Running setup.py (path:/tmp/pip-P7kklR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:00:25 INFO - Running setup.py (path:/tmp/pip-hOmfYj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:00:25 INFO - Running setup.py (path:/tmp/pip-n30vXI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:00:25 INFO - Running setup.py (path:/tmp/pip-xsdgmU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:00:25 INFO - Running setup.py (path:/tmp/pip-t40ock-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:00:25 INFO - Running setup.py (path:/tmp/pip-ngGjHz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:00:25 INFO - Running setup.py (path:/tmp/pip-vPVu2o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:00:25 INFO - Running setup.py (path:/tmp/pip-nSeR44-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:00:25 INFO - Running setup.py (path:/tmp/pip-u2fyLq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:00:25 INFO - Running setup.py (path:/tmp/pip-XeNPBL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:00:26 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:00:26 INFO - Running setup.py install for manifestparser 19:00:26 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:00:26 INFO - Running setup.py install for mozcrash 19:00:26 INFO - Running setup.py install for mozdebug 19:00:26 INFO - Running setup.py install for mozdevice 19:00:26 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:00:26 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:00:26 INFO - Running setup.py install for mozfile 19:00:26 INFO - Running setup.py install for mozhttpd 19:00:26 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:00:26 INFO - Running setup.py install for mozinfo 19:00:26 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:00:26 INFO - Running setup.py install for mozInstall 19:00:26 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:00:26 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:00:26 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:00:26 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:00:26 INFO - Running setup.py install for mozleak 19:00:26 INFO - Running setup.py install for mozlog 19:00:27 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:00:27 INFO - Running setup.py install for moznetwork 19:00:27 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:00:27 INFO - Running setup.py install for mozprocess 19:00:27 INFO - Running setup.py install for mozprofile 19:00:27 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:00:27 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:00:27 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:00:27 INFO - Running setup.py install for mozrunner 19:00:27 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:00:27 INFO - Running setup.py install for mozscreenshot 19:00:27 INFO - Running setup.py install for moztest 19:00:27 INFO - Running setup.py install for mozversion 19:00:27 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:00:27 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:00:27 INFO - Cleaning up... 19:00:27 INFO - Return code: 0 19:00:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:00: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:00:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:00:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:00: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:00:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:00:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:00:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2880030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29e38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b41180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b3f3b0>, '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 0x2b402b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b40740>, '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': '0e2a256762fed810e2f0f810000001b3-1469151937.637665-1236963751', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 19:00:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 19:00:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 19:00:27 INFO - Using env: {'DISPLAY': ':0', 19:00:27 INFO - 'HOME': '/home/cltbld', 19:00:27 INFO - 'LANG': 'en_US.UTF-8', 19:00:27 INFO - 'LANGUAGE': 'en_US:en', 19:00:27 INFO - 'LOGNAME': 'cltbld', 19:00:27 INFO - 'MAIL': '/var/mail/cltbld', 19:00:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:00:27 INFO - 'MOZ_NO_REMOTE': '1', 19:00:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:00:27 INFO - 'NO_EM_RESTART': '1', 19:00:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:00:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:00:27 INFO - 'PWD': '/builds/slave/test', 19:00:27 INFO - 'SHELL': '/bin/bash', 19:00:27 INFO - 'SHLVL': '1', 19:00:27 INFO - 'TERM': 'linux', 19:00:27 INFO - 'TMOUT': '86400', 19:00:27 INFO - 'USER': 'cltbld', 19:00:27 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1469151937.637665-1236963751', 19:00:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:00:27 INFO - '_': '/tools/buildbot/bin/python'} 19:00:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:00:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:00:28 INFO - Running setup.py (path:/tmp/pip-eHecv2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:00:28 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 19:00:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:00:28 INFO - Running setup.py (path:/tmp/pip-ENZkBL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:00:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:00:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:00:28 INFO - Running setup.py (path:/tmp/pip-_RYOXu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:00:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 19:00:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:00:28 INFO - Running setup.py (path:/tmp/pip-2xixd6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:00:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:00:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:00:28 INFO - Running setup.py (path:/tmp/pip-grlWtw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:00:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 19:00:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:00:28 INFO - Running setup.py (path:/tmp/pip-IoPDxj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:00:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 19:00:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:00:28 INFO - Running setup.py (path:/tmp/pip-OZTJ2J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:00:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 19:00:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:00:28 INFO - Running setup.py (path:/tmp/pip-tovx_t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:00:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 19:00:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:00:28 INFO - Running setup.py (path:/tmp/pip-i6MaJW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:00:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 19:00:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:00:28 INFO - Running setup.py (path:/tmp/pip-V119KU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:00:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:00:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:00:28 INFO - Running setup.py (path:/tmp/pip-XIghrA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:00:28 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 19:00:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:00:28 INFO - Running setup.py (path:/tmp/pip-7lbhM4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:00:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 19:00:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:00:28 INFO - Running setup.py (path:/tmp/pip-NSzSxj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:00:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 19:00:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:00:29 INFO - Running setup.py (path:/tmp/pip-7RANed-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:00:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 19:00:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:00:29 INFO - Running setup.py (path:/tmp/pip-x7mu82-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:00:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 19:00:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:00:29 INFO - Running setup.py (path:/tmp/pip-GXvwVD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:00:29 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 19:00:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:00:29 INFO - Running setup.py (path:/tmp/pip-4zYs4k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:00:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 19:00:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:00:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:00:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 19:00:29 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:00:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:00:29 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:00:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:00:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:00:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:00:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:00:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:00:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:00:31 INFO - Downloading blessings-1.6.tar.gz 19:00:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:00:31 INFO - Installing collected packages: blessings 19:00:31 INFO - Running setup.py install for blessings 19:00:31 INFO - Successfully installed blessings 19:00:31 INFO - Cleaning up... 19:00:31 INFO - Return code: 0 19:00:31 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:00:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:00:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:00:31 INFO - Reading from file tmpfile_stdout 19:00:31 INFO - Current package versions: 19:00:31 INFO - argparse == 1.2.1 19:00:31 INFO - blessings == 1.6 19:00:31 INFO - blobuploader == 1.2.4 19:00:31 INFO - docopt == 0.6.1 19:00:31 INFO - functools32 == 3.2.3-2 19:00:31 INFO - jsonschema == 2.5.1 19:00:31 INFO - manifestparser == 1.1 19:00:31 INFO - mozInstall == 1.12 19:00:31 INFO - mozcrash == 0.17 19:00:31 INFO - mozdebug == 0.1 19:00:31 INFO - mozdevice == 0.48 19:00:31 INFO - mozfile == 1.2 19:00:31 INFO - mozhttpd == 0.7 19:00:31 INFO - mozinfo == 0.9 19:00:31 INFO - mozleak == 0.1 19:00:31 INFO - mozlog == 3.3 19:00:31 INFO - moznetwork == 0.27 19:00:31 INFO - mozprocess == 0.23 19:00:31 INFO - mozprofile == 0.28 19:00:31 INFO - mozrunner == 6.12 19:00:31 INFO - mozscreenshot == 0.1 19:00:31 INFO - mozsystemmonitor == 0.3 19:00:31 INFO - moztest == 0.7 19:00:31 INFO - mozversion == 1.4 19:00:31 INFO - psutil == 3.1.1 19:00:31 INFO - requests == 1.2.3 19:00:31 INFO - wsgiref == 0.1.2 19:00:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:00:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:00:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:00:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:00:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:00:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:00:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:00:31 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 0x2880030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29e38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b41180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b3f3b0>, '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 0x2b402b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b40740>, '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': '0e2a256762fed810e2f0f810000001b3-1469151937.637665-1236963751', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 19:00:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 19:00:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 19:00:31 INFO - Using env: {'DISPLAY': ':0', 19:00:31 INFO - 'HOME': '/home/cltbld', 19:00:31 INFO - 'LANG': 'en_US.UTF-8', 19:00:31 INFO - 'LANGUAGE': 'en_US:en', 19:00:31 INFO - 'LOGNAME': 'cltbld', 19:00:31 INFO - 'MAIL': '/var/mail/cltbld', 19:00:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:00:31 INFO - 'MOZ_NO_REMOTE': '1', 19:00:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:00:31 INFO - 'NO_EM_RESTART': '1', 19:00:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:00:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:00:31 INFO - 'PWD': '/builds/slave/test', 19:00:31 INFO - 'SHELL': '/bin/bash', 19:00:31 INFO - 'SHLVL': '1', 19:00:31 INFO - 'TERM': 'linux', 19:00:31 INFO - 'TMOUT': '86400', 19:00:31 INFO - 'USER': 'cltbld', 19:00:31 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1469151937.637665-1236963751', 19:00:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:00:31 INFO - '_': '/tools/buildbot/bin/python'} 19:00:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:00:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 19:00:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 19:00:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 19:00:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 19:00:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 19:00:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 19:00:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 19:00:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 19:00:31 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 19:00:31 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 19:00:31 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 19:00:31 INFO - Cleaning up... 19:00:31 INFO - Return code: 0 19:00:31 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:00:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:00:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:00:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:00:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:00:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:00:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:00:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2880030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29e38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b41180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b3f3b0>, '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 0x2b402b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b40740>, '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': '0e2a256762fed810e2f0f810000001b3-1469151937.637665-1236963751', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 19:00:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 19:00:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 19:00:31 INFO - Using env: {'DISPLAY': ':0', 19:00:31 INFO - 'HOME': '/home/cltbld', 19:00:31 INFO - 'LANG': 'en_US.UTF-8', 19:00:31 INFO - 'LANGUAGE': 'en_US:en', 19:00:31 INFO - 'LOGNAME': 'cltbld', 19:00:31 INFO - 'MAIL': '/var/mail/cltbld', 19:00:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:00:31 INFO - 'MOZ_NO_REMOTE': '1', 19:00:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:00:31 INFO - 'NO_EM_RESTART': '1', 19:00:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:00:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:00:31 INFO - 'PWD': '/builds/slave/test', 19:00:31 INFO - 'SHELL': '/bin/bash', 19:00:31 INFO - 'SHLVL': '1', 19:00:31 INFO - 'TERM': 'linux', 19:00:31 INFO - 'TMOUT': '86400', 19:00:31 INFO - 'USER': 'cltbld', 19:00:31 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1469151937.637665-1236963751', 19:00:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:00:31 INFO - '_': '/tools/buildbot/bin/python'} 19:00:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:00:32 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:00:32 INFO - Cleaning up... 19:00:32 INFO - Return code: 0 19:00:32 INFO - Running post-action listener: _resource_record_post_action 19:00:32 INFO - Running post-action listener: _start_resource_monitoring 19:00:32 INFO - Starting resource monitoring. 19:00:32 INFO - [mozharness: 2016-07-22 02:00:32.216165Z] Finished create-virtualenv step (success) 19:00:32 INFO - [mozharness: 2016-07-22 02:00:32.216598Z] Running install step. 19:00:32 INFO - Running pre-action listener: _resource_record_pre_action 19:00:32 INFO - Running main action method: install 19:00:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:00:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:00:32 INFO - Reading from file tmpfile_stdout 19:00:32 INFO - Detecting whether we're running mozinstall >=1.0... 19:00:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:00:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:00:32 INFO - Reading from file tmpfile_stdout 19:00:32 INFO - Output received: 19:00:32 INFO - Usage: mozinstall [options] installer 19:00:32 INFO - Options: 19:00:32 INFO - -h, --help show this help message and exit 19:00:32 INFO - -d DEST, --destination=DEST 19:00:32 INFO - Directory to install application into. [default: 19:00:32 INFO - "/builds/slave/test"] 19:00:32 INFO - --app=APP Application being installed. [default: firefox] 19:00:32 INFO - mkdir: /builds/slave/test/build/application 19:00:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 19:00:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 19:00:48 INFO - Reading from file tmpfile_stdout 19:00:48 INFO - Output received: 19:00:48 INFO - /builds/slave/test/build/application/firefox/firefox 19:00:48 INFO - Running post-action listener: _resource_record_post_action 19:00:48 INFO - [mozharness: 2016-07-22 02:00:48.959368Z] Finished install step (success) 19:00:48 INFO - [mozharness: 2016-07-22 02:00:48.959642Z] Running run-tests step. 19:00:48 INFO - Running pre-action listener: _resource_record_pre_action 19:00:48 INFO - Running main action method: run_tests 19:00:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:00:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:00:48 INFO - Python 2.7.3 19:00:48 INFO - Return code: 0 19:00:48 INFO - grabbing minidump binary from tooltool 19:00:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:00:48 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 0x2b3f3b0>, '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 0x2b402b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b40740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:00:48 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 19:00:48 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 19:00:48 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 19:00:49 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:00:49 INFO - Return code: 0 19:00:49 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 19:00:49 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:00:49 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:00:49 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 19:00:49 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:00:49 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/Q9tHZZfnT_qmxPhipieTCQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-058', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 19:00:49 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/Q9tHZZfnT_qmxPhipieTCQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-058 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 19:00:49 INFO - Using env: {'DISPLAY': ':0', 19:00:49 INFO - 'HOME': '/home/cltbld', 19:00:49 INFO - 'LANG': 'en_US.UTF-8', 19:00:49 INFO - 'LANGUAGE': 'en_US:en', 19:00:49 INFO - 'LOGNAME': 'cltbld', 19:00:49 INFO - 'MAIL': '/var/mail/cltbld', 19:00:49 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:00:49 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 19:00:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:00:49 INFO - 'MOZ_NO_REMOTE': '1', 19:00:49 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:00:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:00:49 INFO - 'NO_EM_RESTART': '1', 19:00:49 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:00:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:00:49 INFO - 'PWD': '/builds/slave/test', 19:00:49 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:00:49 INFO - 'SHELL': '/bin/bash', 19:00:49 INFO - 'SHLVL': '1', 19:00:49 INFO - 'TERM': 'linux', 19:00:49 INFO - 'TMOUT': '86400', 19:00:49 INFO - 'USER': 'cltbld', 19:00:49 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1469151937.637665-1236963751', 19:00:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:00:49 INFO - '_': '/tools/buildbot/bin/python'} 19:00:49 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/Q9tHZZfnT_qmxPhipieTCQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-058', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 19:00:49 INFO - mozversion application_buildid: 20160721180915 19:00:49 INFO - mozversion application_changeset: 3ab20077e16df40c9e4a61860c69f7b1eec024da 19:00:49 INFO - mozversion application_display_name: Nightly 19:00:49 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:00:49 INFO - mozversion application_name: Firefox 19:00:49 INFO - mozversion application_remotingname: firefox 19:00:49 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 19:00:49 INFO - mozversion application_vendor: Mozilla 19:00:49 INFO - mozversion application_version: 50.0a1 19:00:49 INFO - mozversion platform_buildid: 20160721180915 19:00:49 INFO - mozversion platform_changeset: 3ab20077e16df40c9e4a61860c69f7b1eec024da 19:00:49 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 19:00:49 INFO - mozversion platform_version: 50.0a1 19:00:49 INFO - using testdate: 1469152849 19:00:49 INFO - actual date: 1469152849 19:00:49 INFO - starting webserver on 'localhost:36865' 19:00:49 INFO - SUITE-START | Running 2 tests 19:00:49 INFO - TEST-START | dromaeo_css 19:00:49 INFO - operating with platform_type : linux_ 19:00:49 INFO - Initialising browser for dromaeo_css test... 19:00:49 INFO - TEST-INFO | started process 23724 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpLh39Op/profile http://localhost:36865/getInfo.html) 19:00:52 INFO - PROCESS | 23724 | JavaScript error: , line 0: uncaught exception: undefined 19:00:53 INFO - PROCESS | 23724 | __metrics Screen width/height:1600/1200 19:00:53 INFO - PROCESS | 23724 | colorDepth:24 19:00:53 INFO - PROCESS | 23724 | Browser inner width/height: 1024/697 19:00:53 INFO - PROCESS | 23724 | __metrics 19:00:54 INFO - PROCESS | 23724 | 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" 19:00:54 INFO - PROCESS | 23724 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 58: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:00:55 INFO - TEST-INFO | 23724: exit 0 19:00:55 INFO - Browser initialized. 19:00:55 INFO - Running cycle 1/1 for dromaeo_css test... 19:00:55 INFO - TEST-INFO | started process 23803 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpLh39Op/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:01:07 INFO - PROCESS | 23803 | RSS: Main: 159178752 19:01:07 INFO - PROCESS | 23803 | 19:01:58 INFO - PROCESS | 23803 | 1469152918966 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 19:04:10 INFO - PROCESS | 23803 | Cycle 1(1): loaded http://localhost:36865/tests/dromaeo/cssquery-dojo.html (next: http://localhost:36865/tests/dromaeo/cssquery-ext.html) 19:04:11 INFO - PROCESS | 23803 | RSS: Main: 234602496 19:04:11 INFO - PROCESS | 23803 | 19:07:14 INFO - PROCESS | 23803 | Cycle 1(1): loaded http://localhost:36865/tests/dromaeo/cssquery-ext.html (next: http://localhost:36865/tests/dromaeo/cssquery-jquery.html) 19:07:15 INFO - PROCESS | 23803 | RSS: Main: 257183744 19:07:15 INFO - PROCESS | 23803 | 19:10:17 INFO - PROCESS | 23803 | Cycle 1(1): loaded http://localhost:36865/tests/dromaeo/cssquery-jquery.html (next: http://localhost:36865/tests/dromaeo/cssquery-mootools.html) 19:10:18 INFO - PROCESS | 23803 | RSS: Main: 190517248 19:10:18 INFO - PROCESS | 23803 | 19:13:20 INFO - PROCESS | 23803 | Cycle 1(1): loaded http://localhost:36865/tests/dromaeo/cssquery-mootools.html (next: http://localhost:36865/tests/dromaeo/cssquery-prototype.html) 19:13:20 INFO - PROCESS | 23803 | RSS: Main: 190234624 19:13:20 INFO - PROCESS | 23803 | 19:16:23 INFO - PROCESS | 23803 | Cycle 1(1): loaded http://localhost:36865/tests/dromaeo/cssquery-prototype.html (next: http://localhost:36865/tests/dromaeo/cssquery-yui.html) 19:16:24 INFO - PROCESS | 23803 | RSS: Main: 191098880 19:16:24 INFO - PROCESS | 23803 | 19:19:27 INFO - PROCESS | 23803 | Cycle 1(1): loaded http://localhost:36865/tests/dromaeo/cssquery-yui.html (next: http://localhost:36865/tests/dromaeo/cssquery-dojo.html) 19:19:27 INFO - PROCESS | 23803 | RSS: Main: 282324992 19:19:27 INFO - PROCESS | 23803 | 19:19:27 INFO - PROCESS | 23803 | __start_tp_report 19:19:27 INFO - PROCESS | 23803 | _x_x_mozilla_page_load 19:19:27 INFO - PROCESS | 23803 | _x_x_mozilla_page_load_details 19:19:27 INFO - PROCESS | 23803 | |i|pagename|runs| 19:19:27 INFO - PROCESS | 23803 | |0;dojo.html;2620.908268210613;2627.1724406811863;2661.2282438092952;2676.772474339681;2678.290253082932;4518.322251662253;4628.722276663402;4631.212693842046;4638.188317044516;4659.4175728034;4631.004334068175;4660.930086048704;4667.533246675331;4710.364100846385;4728.692634978724;4210.7473551587;4217.409562661214;4251.426057482231;4344.304911214206;4357.825686972526;4311.180875633635;4368.737875727451;4411.911761764763;4423.4249547558775;4443.22243607369;4356.215881141393;4389.188000219953;4403.603675669189;4428.114377124572;4440.911181776379;4202.642775364097;4211.305134652778;4243.681723870691;4265.786710664454;4334.13317336534;4297.183535128315;4301.462317210348;4305.806238719234;4360.934585981213;4367.58507941745;4767.95232047682;4778.784954677048;4811.831585894512;4848.296996935424;4848.903021939542;4812.807487700487;5006.774695138727;5015.448300686921;5150.047241260341;5153.098207813655;5111.974440127776;5111.977604479119;5146.716930568818;5154.742262886878;5157.329547158883;4775.641826862964;4779.832705855279;4802.639802014863;4804.543568361;4806.206975848946;5018.824341148043;5090.211017292251;5100.132977393852;5102.209157580581;5260.92108618371;4967.90064198714;4969.751512424436;4974.452810190876;4986.626003049786;4993.550580447777;5062.417821950449;5107.770150343244;5114.156164232934;5116.206987916805;5121.923171152435;4818.518148185153;4961.8511444656715;4996.775145118479;5003.124453220672;5013.047520971004;5045.949540504622;5047.570956468668;5049.293098966147;5056.747162641853;5078.415982162025;4287.506936702319;4287.785610719514;4293.141371725667;4328.567143285646;4339.4792624884585;3682.926341473209;3687.1335236219466;3719.5350581177354;3763.8682646107254;3831.750936189139;4166.687498437629;4236.809343579546;4254.595813397722;4265.317549192115;4287.957120428756;5020.272060551272;5027.924581131286;5028.899422011539;5038.748062596929;5086.21163719624;4276.5295817460055;4292.849750258726;4297.119090586422;4299.14017196562;4333.566643335641;4436.866893993185;4472.172648060055;4475.731456112644;4509.864704058883;4566.520515345907;4709.599684026896;4709.976450117728;4710.317004034464;4713.198756211452;4758.333833263277;5002.299678045004;5048.823291184938;5051.1160546904875;5059.924101138559;5108.080545501845;4598.8620341389815;4618.261078227468;4655.255159174516;4664.137134630104;4730.692504987165;4896.951030489792;4918.803247870188;4921.50784921505;4931.260310953386;4976.975115124401;4913.9017219656125;4916.7541622918;4917.385326834145;4922.556969872729;4972.975135124301;4756.025014872012;4803.159447096814;4845.200541910545;4862.489438608895;4933.062718083553;4841.927371089366;4882.707037577615;4886.047220791911;4886.657933944732;4939.6542242042715;4827.879303017453;4847.757612119451;4848.490908454562;4886.218205087312;4886.828960986349;4742.027884283782;4895.583875370633;4928.605711542997;4944.480829512993;5040.57155141817;4832.395950506187;4865.732384718733;4866;4870.196417591058;4911.8280860169725 19:19:27 INFO - PROCESS | 23803 | |1;ext.html;569.1207085053593;586.4135864135864;587.4331270324136;588.2029849553854;588.3528119069027;27456.725432745665;30138.246543836416;30162;30197.849010754944;39891.40162897559;38019.42970855432;41036;41083.38374924379;41085.58914410855;41095.79452102743;7723.459357844968;7858.371330293563;7862.921370786276;7864.803379915491;7865.213478652117;7494.587797671126;7682.731104411347;7699.153093159776;7781.494202876823;7783.377329813629;7698;7740.922590774105;9973.800523989516;10193.439360835151;10218.795624087514;10074.093331600154;10111.3427627204;10121.443320617363;10123.29136960413;10131.797364052714;9908.405495670211;9970.451625160613;9987.101160895481;9987.850182247272;9987.850182247272;30562.69437305621;37459.438108428396;38522.61477385218;38584.80707596472;41234;3045.421369939704;3048.878044878202;3140.560321554984;3453.4992426098074;3627.546556680415;29024.854875725698;29114.272143196376;29370.55944160839;29643;30355.544666830014;3489.4068008438626;3512.0868574170645;3587.1749497615697;3591.6408359164134;3613;236596;239089;239426.45161935335;241363;241393.99999999825;47682.28476572854;53207.46792532025;53411.732941335045;53481.465185348425;53940.19089713658;52423;55738;56090.158647620316;56135.43864561302;56193.7190314054;15149.091054536542;15279.541613751606;15288.159151246566;15288.464903728538;15321.846781532042;11467.942660286646;11554.480048397842;11573;11573.074154067654;11573.826392604118;15982.840171598367;17887.463376098738;17931.641367172844;18061.09694515269;18134.727979080326;6216.222972128484;6673.663998726525;6729.1252137221845;7023.438124949992;7095.716171353191;768.3123604374053;812.2446125103593;865.8917635295588;942.8255772682076;963.2967933408651;26615.201543953714;27705.445891082065;27928.16215513538;28145;28156.29609259744;910.1262787723726;923.7967647117623;943.4716558727134;959.4435227567993;978.0806042320196;14387.640308992359;14899.553013409399;14919.477818276526;15283.312250948511;15305;380335;385949;386905;387725;387830;44685;45729.31406028912;45737.542624573325;48995;49006.75496622566;1187.619961612293;1191.1899908062428;1214.477774556949;1216.5194748074462;1220.0605533738972;27548.449031019667;28560;28894.42211155723;29045.419091617627;29342.853285733436;43186;43599.12801744011;43640.12719745651;43662.56337436585;43716.56283437125;318666;319617;319753;320134;554544;37218.44172337363;37787.62212377951;37851.242975139794;37873;38372;41055.58944410518;41083.383749243185;41243.58756412518;42096;42125;35834.64165358417;35838.64161358353;35870.641293587774;36606.63393366032;36809.81595092007;35488;35794.64205358017;36360.82237466104;36400.63599363973;36541.26917461689;34979;35353.46969795404;36004.639953600126;36069.63930360663;36179;30866.228344291572;32181.839090804395;32547.674523255413;32991.67008329982;33000.174995624344;24688.629670555314;25116.74883251217;25194.496110078064;25495.617565736156;25659 19:19:27 INFO - PROCESS | 23803 | |2;jquery.html;333.73634828485496;341.0892915914508;359.84346809138026;364.57891135738225;378.22275224413835;12723.809142862865;16294.918525407385;16733.916330418346;16819.83180168198;17877.106144692745;15781.368745250205;15816.44642437515;16408.589785255375;16516.339346426128;16535.503934881974;4986.227134794094;5176;5186.170212765958;5417.945820541783;5453.727313634322;3388.593368795748;5466.125419932811;5494.038543254935;5494.2857428534435;5593.972030139844;5882.499987501067;5888.5877988540815;5902.232709747726;5910.261217347832;5912.083627037816;5794.787086986694;5824.068149096167;5883.8529036774;5902.085176797603;5943.4056594340655;5238.947610523884;5240.659357141773;5241.606879484054;5244.239585260116;5255.76349064293;11825.113116516295;11974.443322368123;12236.938815305866;12250.693732656666;12322.81515777264;1879.830815226636;1904.2954106980505;1933.7679478462674;2142.4322554523064;2206.7572567017614;8473.279771219453;8549.358798090168;8705.347098967539;8829.779255518599;9344.67293644726;1727.870409719276;1745.834145756164;1754.9723384831714;1761.6652835961127;1792.8158599820733;64907.350926491075;68490.31509684939;69395.65302173507;69651;70236;9477.194438472668;9538.380005299634;9767.755806104904;9802.901970980342;9917.553710082919;13118.737625247442;13530.18818870871;13531.188128712309;13552.322383880766;13560.796588051187;7062.894056589155;7110.964445177741;7156.391706704989;7199.676014579357;7297.708091676273;6791.626460544718;6833.3166683331265;6952.478564107712;6992.6853291602;7048.823779405453;3982.183652351261;4017.678585713136;4083.714140010171;4121.464209652726;4181.205570941512;1542.3522120709104;1719.9140042998051;1771.503978885914;1775.174543837122;1810.076860801001;688.3116883116883;813.6785969541997;822.2928281677753;871.2115535440437;896.2381975320925;15419.614509637124;16290.429834955956;16753.832461675473;16786.4124755633;17134.3710576247;792.5244853088101;830.7839961610025;832.7736736064377;832.8584140696096;840.3865178419787;8296.543690097102;9087.681931132378;9111.27109831212;9117.31620128493;9237.30720195988;407218;407228;408180;409550;412552;84918.5754071238;85070;87124;90571.64142537868;90723.54638226899;866.0776273269074;874.8775171475999;887.281637815191;900.144862380741;909.9363044586815;15287.312070956612;15431.305591248196;16289.51131466058;16416.425425110065;16582.50252492427;26623.068192613166;27930.720692793624;37280.81359593293;39090.80454597709;39604;51005;51503.2274515875;53043;53289.20066198933;53397.19904201362;7249.130104387507;7289.307515786204;7399.816029435265;7571.3942884568005;7581.54510729358;8401.285890699359;10931.39877306724;11342.659720208407;11766.529183852019;11873.287602743863;10166.339187952759;10219.182465402899;10222.591096356062;10240.231982601485;10264.538095785856;10065.245106616887;10249.846252306072;10414.166866650801;10599.841002384816;10621.362718236931;10052.396856188652;10256;10508.054275115277;10652.307600005975;10908.94545527299;7416.110066792019;7440.441966852616;7524.322810947041;7565.735199268;7644.541327520366;8452.36607254446;10156.746081348025;10199.592016319264;10300.227482938659;10351 19:19:27 INFO - PROCESS | 23803 | |3;mootools.html;1376.9449222031117;1425.1235490173542;1451.4484495891559;1634.3299247308605;1639.9508014759554;7847.489913155648;8474.23731864132;8474.279686226662;8478.982522097349;8480.067192608816;6791.864162716743;7642.656080476378;7795.415343849219;7871.055473343207;8165.101838797742;2178.9128434862587;2188.89055547222;2202.4383782135615;2209.1163534586135;2213.236433430464;2174.4563859035243;2197.3188311623367;2235.441139715071;2236.4185311818883;2239.1826983151113;1830.2313028528051;1892.4984879007006;1894.2707057782784;1906.780720217172;1911.6941289393699;2130.968035479469;2201.328594778592;2210.0386331945656;2223.055201539339;2234.452559123009;2184.6941428199907;2210;2211.2702808073295;2213.6015517206897;2216.490207252325;8088.514689118671;8135.715249966282;8177.427580069398;8200.343972482187;8260;4607.124646317189;4725.440604600473;4727.290906364038;4729.432468103815;4733;2822.32264256579;2906.796524243304;2961.1116665000413;2966.4512065267777;2973.093206571995;870.7518357268211;915.6428992692855;916.4272329793879;919.4575200631659;934.4907025671025;11255.887441125566;11256.268342557707;11262.54949802007;11266.605668801634;11303.943480282545;3946.664533514626;4260.914781704349;4391.560843915583;4394.12117576486;4399.318105693623;9540.809183816424;9554;9670.032996700415;9677.661281855102;9686.370385925033;4731.195696731564;4744.335792988984;4776.23580227162;4808.543188397097;4809.95190048102;4285.957140428556;4337.652987760972;4341.5007274163245;4341.6743744218675;4341.804618792162;3709.0912726382207;3744.475773391727;3801.7718936863325;3846.0577158595765;3858.1126340941178;1168.7384910198793;1184.3663639952756;1191.7914364986093;1197.233770386953;1239.2378687107544;663.3598577372859;681.9045333653293;734.9853002939911;741.2143128284036;742.2429122295229;4966.180580204225;4980.526849949249;5138.2806407103035;5177.8187763428095;5204.115300398941;389.31286279716323;391.8589307849172;395.2233860464144;888.373696543935;955.9617615295449;3732.7760334380023;3962.227365663726;3964.484616999829;3992.840286388512;4032.1129351542613;10175.287729858986;10271.024252695983;10399.272050956512;10420.947895260626;10421.322614030063;9406.435613863054;9673.516324183762;9851.014898510091;10297.53661085253;10320.174386049099;7470.626468676544;7647.349975252055;7656.425768067528;7668.156502784689;7678.347340476123;5395.24466574672;5479.506844384025;5535.892821435809;5603.607747457801;5618.887622247614;9479.767630208029;9482.95258523703;9492.57283422234;9498.19265362452;9603.951980240054;9361.204297634777;9447.574859131493;9592;9716.805663886824;9785.315027947976;7030.789076327718;7176.96411517939;7182.3535881770895;7185.389241914287;7188.460865435009;7820.804479888048;7847.254510821463;7868.331191848757;7872.960635197017;7906.130325664288;5395.379531353788;6914.619695916773;7007.859842803014;7014.964925175342;7283.708651653875;6721.193456785217;7031.613261270679;7034.402075823612;7067.2579379164445;7171.533850299714;6282.120503129657;6396.296407395276;6399.55203135776;6464.321246269074;6465.482761379172;4564.771761411983;4658.347831303688;4688.531146885421;4689.859304220879;4845.636577256791;4603.654725895504;4633.258678611406;4645.233536466445;4653.650976176733;4689.624830013522 19:19:27 INFO - PROCESS | 23803 | |4;prototype.html;297.4734719546403;301.7917636830587;309.6470024172444;313.7803537523734;314.352433985989;4986.850394488167;4998.975005124974;5000.9996001599375;5006.224035274538;5018.949810501903;4899.314096026559;4906.8773280668;4946.0602485527725;4962.578180854632;4982.252662100677;3587.9282414351633;3664.175560498893;3694.8409110210787;3782.111203867089;3787.2993496203153;3800.3349413852466;3805.866794662187;3809.2762375148773;3811.752236104658;3826.4260360945805;3773.3208022555928;3775.169462718198;3775.8867233983024;3784.470174175604;3787.337215987205;3617.3669607818656;3698.704103671727;3800.6009369016133;3814.5803961564206;3821.426785982125;3377.8986630401128;3378.8986330410125;3384.1539615096226;3386.542816719736;3387.6442973487924;4776.880577985544;4786.617070634341;4790.473047964721;4794.113089078532;4814.542618451242;4109.97945010276;4347.434833471629;4395;4395.494518130424;4417.712848664826;5130.127878260705;5130.461301563357;5141.280220769096;5145.30538377318;5149.480903133584;3617.0957260684827;3624.4200927851416;3631.2011357501306;3647.434647629622;3651.6348365163535;5086.542211200973;5389.865253368658;5447.237386765816;5472.34331880173;5474.644148130398;3651.3062518121474;3703.833327500215;3704.944425833615;3706.6849317808287;3709.2025214578457;3759.7180211484247;3761.3417651910804;3763;3774.8678796241998;3779.6976241900584;4335.7615331157085;4357.324614684729;4361.389405483234;4361.411209486742;4369.344598310279;5026.84919452417;5093.668911520739;5128.410232823198;5173.482651734872;5224;1280.5326055989683;1289.9551363395678;1292.5346875124947;1295.57893684551;1297.6626077219992;770.2643975003858;785.297159042665;792.6631181747734;802.1577343788998;806.4919100966354;4327.37253098299;4446.666500012448;4471.28459446487;4471.8434854779925;4478.216312145361;5280.709560974184;5294.602904782157;5294.708791016531;5307.814226502054;5313.203019547099;4470.8435204767675;4484.609770971009;4488.304312831517;4489.708168969007;4526.479454862733;2652.9204123876316;2805.466961277351;2814.7326004029583;2819.6052552642645;2824.632797736281;4991.5258050485145;5006.3992320921725;5016;5018.121828679967;5039.21892106724;5251.921221181686;5399.811006614749;5423.186522021729;5488.506034456918;5489.85895500755;4905.901881962341;4933.605311575066;4934.5805606523845;4935.950640493621;4942.283368911416;5236.266922630759;5271.182966640177;5302.655327403706;5308.443342960303;5320.334958130234;4993.0388874196215;5205.687658740488;5233.869153271199;5242.633015689018;5256.579473642176;4985.401751789808;4989.850304490871;4990.750462476789;4992.603550295806;4995.875103122451;3360.2271477559807;3376.8818091366684;3394.5050387964334;3400.9829950851076;3419.5721038063252;4579.908401832011;4716.434027916534;4734.6449016324605;4759.190937540558;4807.927881081717;4543.932845329456;4564.908701826012;4578.679492435497;4601.653156889944;4610.331501932268;4547.340635607885;4566.566176213254;4577;4586.839460618862;4600.2409602416565;4363.890902727458;4484.349769283371;4517.254652982221;4530.637548996138;4567.725936443824;4587.797857028711;4598.448186217675;4882.389701287339;4950.836553409874;5102.36220472441;5017.322661440805;5018.04657115147;5069.695818250917;5073.619478539198;5079.16193828029 19:19:27 INFO - PROCESS | 23803 | |5;yui.html;653.647030603474;683.2347770497041;700.6356694518854;725.7750097469785;746.0674157303371;12441.751164976695;13263.801042984338;13279.867201327985;13290.667733306673;13301.334933253334;12493.312867792269;12589;12599.3070381129;12600.432980515876;12649.747005059917;617.8331850400389;634.3212762344293;754.1628792040831;754.2985023927745;785.654312102673;761.9695212191506;770.6570576093641;773.7678696391089;775.414562005685;775.7129861951086;829.7344849648115;830.1781236575777;830.2983978538126;830.7217082277444;831.0276975938167;651.2282944710533;797.3860127701695;814.0516298512226;826.2687521543447;826.7809030606894;592.7095723095695;596.4155127974609;598.0222336479886;598.0938877600439;598.6408155106901;12311.876881231163;12327.260364378166;12348.073894457862;12350.62948111558;12353;11814.291142531476;12188;12589.433475493624;12634.620961371174;12820.678642317378;10214.336068155546;10608.045275925127;11282.097432205404;11325.320480771097;11958.820617690655;4144.668426525901;4158.6465150462245;4207.873763787091;4279.272523670952;4383.627391671712;53911;55183.44816551863;55386.44613553853;61661.38338616646;62351;9925.553350099262;9978.750531236778;10107.29248952581;10138.087572118544;10751.462426878625;18266.54333641643;18312.63374732498;18616.162272697762;18893.90553047226;18906.05469726508;6909.792706218822;6915.965420172867;6938.618375989268;7080.539764915264;7192.532485388538;3679.9448008279896;3720.162963333228;3768.2086761779783;3790.5451345838674;3835.53973523179;2734.412101398169;2839.6024556561683;2848.4303139371796;2853.315204350941;2854.471922694308;503.1471655543818;511.40166005773335;514.2363590068745;514.9871253218699;525.1990714160935;543.9646422982494;591.1251348004977;593.6022864680674;605.5488660947623;607.7143742441045;7193.820154496179;7525.397968162534;7568.810779730551;7599.962000189964;7846.489978151402;558.8091994710169;573.6156774960787;575.7063897412266;579.5537436174207;596.5943158652158;3344.6989770920736;3599.7300202484394;3610.4403817408343;3648.890533284006;3734.514513113278;99045.50477247711;99741;101478;101938.49030754798;102287;49329.50670493321;53310.73344633252;74507.25492745111;81876.18123818804;82439;73121.6343918277;74393.76357523253;76689.61655191689;77035;79234;6856.348646878738;7058.3647471725735;7093.32613401726;7134.32223938725;7212.855742885218;44181;44787.55212447834;44828.55171448244;45080;46045;63974.36025639684;64906.35093649003;66110.66944665246;66525.33474665192;68031.3196868025;7487;7817.726379576688;8424.789380265542;9142.725718228463;9542.904570954202;9925.057119573628;9950.502474876082;10005.399676019462;10124.13944814699;10127;9874.308798384043;9980.500974951368;10092.192624590161;10229.74425639365;10277.383356998604;9635.843698756194;9870.654527091518;9892.851607225753;9897.752556186153;10065.094141527297;9525.809483810424;9528.95235523818;9588.712338629852;9694.9030509694;9801.460919649488;8118.634661440367;8349.95825020871;8359.247667709935;8457.450265732708;8917.375783695079;8330.250277475056;8748;8762.956185219033;8766.254868336007;8830.514321712368 19:19:27 INFO - PROCESS | 23803 | __end_tp_report 19:19:27 INFO - PROCESS | 23803 | __start_cc_report 19:19:27 INFO - PROCESS | 23803 | _x_x_mozilla_cycle_collect,1123 19:19:27 INFO - PROCESS | 23803 | __end_cc_report 19:19:27 INFO - PROCESS | 23803 | __startTimestamp1469153967723__endTimestamp 19:19:27 INFO - PROCESS | 23803 | 19:19:27 INFO - PROCESS | 23803 | ------- Summary: start ------- 19:19:27 INFO - PROCESS | 23803 | Number of tests: 6 19:19:27 INFO - PROCESS | 23803 | 19:19:27 INFO - PROCESS | 23803 | [#0] dojo.html Cycles:165 Average:4646.95 Median:4803.16 stddev:487.70 (10.2%) stddev-sans-first:462.57 19:19:27 INFO - PROCESS | 23803 | Values: 2620.9 2627.2 2661.2 2676.8 2678.3 4518.3 4628.7 4631.2 4638.2 4659.4 4631.0 4660.9 4667.5 4710.4 4728.7 4210.7 4217.4 4251.4 4344.3 4357.8 4311.2 4368.7 4411.9 4423.4 4443.2 4356.2 4389.2 4403.6 4428.1 4440.9 4202.6 4211.3 4243.7 4265.8 4334.1 4297.2 4301.5 4305.8 4360.9 4367.6 4768.0 4778.8 4811.8 4848.3 4848.9 4812.8 5006.8 5015.4 5150.0 5153.1 5112.0 5112.0 5146.7 5154.7 5157.3 4775.6 4779.8 4802.6 4804.5 4806.2 5018.8 5090.2 5100.1 5102.2 5260.9 4967.9 4969.8 4974.5 4986.6 4993.6 5062.4 5107.8 5114.2 5116.2 5121.9 4818.5 4961.9 4996.8 5003.1 5013.0 5045.9 5047.6 5049.3 5056.7 5078.4 4287.5 4287.8 4293.1 4328.6 4339.5 3682.9 3687.1 3719.5 3763.9 3831.8 4166.7 4236.8 4254.6 4265.3 4288.0 5020.3 5027.9 5028.9 5038.7 5086.2 4276.5 4292.8 4297.1 4299.1 4333.6 4436.9 4472.2 4475.7 4509.9 4566.5 4709.6 4710.0 4710.3 4713.2 4758.3 5002.3 5048.8 5051.1 5059.9 5108.1 4598.9 4618.3 4655.3 4664.1 4730.7 4897.0 4918.8 4921.5 4931.3 4977.0 4913.9 4916.8 4917.4 4922.6 4973.0 4756.0 4803.2 4845.2 4862.5 4933.1 4841.9 4882.7 4886.0 4886.7 4939.7 4827.9 4847.8 4848.5 4886.2 4886.8 4742.0 4895.6 4928.6 4944.5 5040.6 4832.4 4865.7 4866.0 4870.2 4911.8 19:19:27 INFO - PROCESS | 23803 | 19:19:27 INFO - PROCESS | 23803 | [#1] ext.html Cycles:180 Average:48648.43 Median:19994.18 stddev:90115.56 (450.7%) stddev-sans-first:90296.05 19:19:27 INFO - PROCESS | 23803 | Values: 569.1 586.4 587.4 588.2 588.4 27456.7 30138.2 30162.0 30197.8 39891.4 38019.4 41036.0 41083.4 41085.6 41095.8 7723.5 7858.4 7862.9 7864.8 7865.2 7494.6 7682.7 7699.2 7781.5 7783.4 7698.0 7740.9 9973.8 10193.4 10218.8 10074.1 10111.3 10121.4 10123.3 10131.8 9908.4 9970.5 9987.1 9987.9 9987.9 30562.7 37459.4 38522.6 38584.8 41234.0 3045.4 3048.9 3140.6 3453.5 3627.5 29024.9 29114.3 29370.6 29643.0 30355.5 3489.4 3512.1 3587.2 3591.6 3613.0 236596.0 239089.0 239426.5 241363.0 241394.0 47682.3 53207.5 53411.7 53481.5 53940.2 52423.0 55738.0 56090.2 56135.4 56193.7 15149.1 15279.5 15288.2 15288.5 15321.8 11467.9 11554.5 11573.0 11573.1 11573.8 15982.8 17887.5 17931.6 18061.1 18134.7 6216.2 6673.7 6729.1 7023.4 7095.7 768.3 812.2 865.9 942.8 963.3 26615.2 27705.4 27928.2 28145.0 28156.3 910.1 923.8 943.5 959.4 978.1 14387.6 14899.6 14919.5 15283.3 15305.0 380335.0 385949.0 386905.0 387725.0 387830.0 44685.0 45729.3 45737.5 48995.0 49006.8 1187.6 1191.2 1214.5 1216.5 1220.1 27548.4 28560.0 28894.4 29045.4 29342.9 43186.0 43599.1 43640.1 43662.6 43716.6 318666.0 319617.0 319753.0 320134.0 554544.0 37218.4 37787.6 37851.2 37873.0 38372.0 41055.6 41083.4 41243.6 42096.0 42125.0 35834.6 35838.6 35870.6 36606.6 36809.8 35488.0 35794.6 36360.8 36400.6 36541.3 34979.0 35353.5 36004.6 36069.6 36179.0 30866.2 32181.8 32547.7 32991.7 33000.2 24688.6 25116.7 25194.5 25495.6 25659.0 19:19:27 INFO - PROCESS | 23803 | 19:19:27 INFO - PROCESS | 23803 | [#2] jquery.html Cycles:180 Average:24727.79 Median:51254.11 stddev:67710.81 (132.1%) stddev-sans-first:67875.99 19:19:27 INFO - PROCESS | 23803 | Values: 333.7 341.1 359.8 364.6 378.2 12723.8 16294.9 16733.9 16819.8 17877.1 15781.4 15816.4 16408.6 16516.3 16535.5 4986.2 5176.0 5186.2 5417.9 5453.7 3388.6 5466.1 5494.0 5494.3 5594.0 5882.5 5888.6 5902.2 5910.3 5912.1 5794.8 5824.1 5883.9 5902.1 5943.4 5238.9 5240.7 5241.6 5244.2 5255.8 11825.1 11974.4 12236.9 12250.7 12322.8 1879.8 1904.3 1933.8 2142.4 2206.8 8473.3 8549.4 8705.3 8829.8 9344.7 1727.9 1745.8 1755.0 1761.7 1792.8 64907.4 68490.3 69395.7 69651.0 70236.0 9477.2 9538.4 9767.8 9802.9 9917.6 13118.7 13530.2 13531.2 13552.3 13560.8 7062.9 7111.0 7156.4 7199.7 7297.7 6791.6 6833.3 6952.5 6992.7 7048.8 3982.2 4017.7 4083.7 4121.5 4181.2 1542.4 1719.9 1771.5 1775.2 1810.1 688.3 813.7 822.3 871.2 896.2 15419.6 16290.4 16753.8 16786.4 17134.4 792.5 830.8 832.8 832.9 840.4 8296.5 9087.7 9111.3 9117.3 9237.3 407218.0 407228.0 408180.0 409550.0 412552.0 84918.6 85070.0 87124.0 90571.6 90723.5 866.1 874.9 887.3 900.1 909.9 15287.3 15431.3 16289.5 16416.4 16582.5 26623.1 27930.7 37280.8 39090.8 39604.0 51005.0 51503.2 53043.0 53289.2 53397.2 7249.1 7289.3 7399.8 7571.4 7581.5 8401.3 10931.4 11342.7 11766.5 11873.3 10166.3 10219.2 10222.6 10240.2 10264.5 10065.2 10249.8 10414.2 10599.8 10621.4 10052.4 10256.0 10508.1 10652.3 10908.9 7416.1 7440.4 7524.3 7565.7 7644.5 8452.4 10156.7 10199.6 10300.2 10351.0 19:19:27 INFO - PROCESS | 23803 | 19:19:27 INFO - PROCESS | 23803 | [#3] mootools.html Cycles:180 Average:5319.90 Median:4760.29 stddev:3079.90 (64.7%) stddev-sans-first:3074.29 19:19:27 INFO - PROCESS | 23803 | Values: 1376.9 1425.1 1451.4 1634.3 1640.0 7847.5 8474.2 8474.3 8479.0 8480.1 6791.9 7642.7 7795.4 7871.1 8165.1 2178.9 2188.9 2202.4 2209.1 2213.2 2174.5 2197.3 2235.4 2236.4 2239.2 1830.2 1892.5 1894.3 1906.8 1911.7 2131.0 2201.3 2210.0 2223.1 2234.5 2184.7 2210.0 2211.3 2213.6 2216.5 8088.5 8135.7 8177.4 8200.3 8260.0 4607.1 4725.4 4727.3 4729.4 4733.0 2822.3 2906.8 2961.1 2966.5 2973.1 870.8 915.6 916.4 919.5 934.5 11255.9 11256.3 11262.5 11266.6 11303.9 3946.7 4260.9 4391.6 4394.1 4399.3 9540.8 9554.0 9670.0 9677.7 9686.4 4731.2 4744.3 4776.2 4808.5 4810.0 4286.0 4337.7 4341.5 4341.7 4341.8 3709.1 3744.5 3801.8 3846.1 3858.1 1168.7 1184.4 1191.8 1197.2 1239.2 663.4 681.9 735.0 741.2 742.2 4966.2 4980.5 5138.3 5177.8 5204.1 389.3 391.9 395.2 888.4 956.0 3732.8 3962.2 3964.5 3992.8 4032.1 10175.3 10271.0 10399.3 10420.9 10421.3 9406.4 9673.5 9851.0 10297.5 10320.2 7470.6 7647.3 7656.4 7668.2 7678.3 5395.2 5479.5 5535.9 5603.6 5618.9 9479.8 9483.0 9492.6 9498.2 9604.0 9361.2 9447.6 9592.0 9716.8 9785.3 7030.8 7177.0 7182.4 7185.4 7188.5 7820.8 7847.3 7868.3 7873.0 7906.1 5395.4 6914.6 7007.9 7015.0 7283.7 6721.2 7031.6 7034.4 7067.3 7171.5 6282.1 6396.3 6399.6 6464.3 6465.5 4564.8 4658.3 4688.5 4689.9 4845.6 4603.7 4633.3 4645.2 4653.7 4689.6 19:19:27 INFO - PROCESS | 23803 | 19:19:27 INFO - PROCESS | 23803 | [#4] prototype.html Cycles:180 Average:4179.13 Median:4579.29 stddev:1222.18 (26.7%) stddev-sans-first:1190.38 19:19:27 INFO - PROCESS | 23803 | Values: 297.5 301.8 309.6 313.8 314.4 4986.9 4999.0 5001.0 5006.2 5018.9 4899.3 4906.9 4946.1 4962.6 4982.3 3587.9 3664.2 3694.8 3782.1 3787.3 3800.3 3805.9 3809.3 3811.8 3826.4 3773.3 3775.2 3775.9 3784.5 3787.3 3617.4 3698.7 3800.6 3814.6 3821.4 3377.9 3378.9 3384.2 3386.5 3387.6 4776.9 4786.6 4790.5 4794.1 4814.5 4110.0 4347.4 4395.0 4395.5 4417.7 5130.1 5130.5 5141.3 5145.3 5149.5 3617.1 3624.4 3631.2 3647.4 3651.6 5086.5 5389.9 5447.2 5472.3 5474.6 3651.3 3703.8 3704.9 3706.7 3709.2 3759.7 3761.3 3763.0 3774.9 3779.7 4335.8 4357.3 4361.4 4361.4 4369.3 5026.8 5093.7 5128.4 5173.5 5224.0 1280.5 1290.0 1292.5 1295.6 1297.7 770.3 785.3 792.7 802.2 806.5 4327.4 4446.7 4471.3 4471.8 4478.2 5280.7 5294.6 5294.7 5307.8 5313.2 4470.8 4484.6 4488.3 4489.7 4526.5 2652.9 2805.5 2814.7 2819.6 2824.6 4991.5 5006.4 5016.0 5018.1 5039.2 5251.9 5399.8 5423.2 5488.5 5489.9 4905.9 4933.6 4934.6 4936.0 4942.3 5236.3 5271.2 5302.7 5308.4 5320.3 4993.0 5205.7 5233.9 5242.6 5256.6 4985.4 4989.9 4990.8 4992.6 4995.9 3360.2 3376.9 3394.5 3401.0 3419.6 4579.9 4716.4 4734.6 4759.2 4807.9 4543.9 4564.9 4578.7 4601.7 4610.3 4547.3 4566.6 4577.0 4586.8 4600.2 4363.9 4484.3 4517.3 4530.6 4567.7 4587.8 4598.4 4882.4 4950.8 5102.4 5017.3 5018.0 5069.7 5073.6 5079.2 19:19:27 INFO - PROCESS | 23803 | 19:19:27 INFO - PROCESS | 23803 | [#5] yui.html Cycles:180 Average:16986.61 Median:602.09 stddev:24902.87 (4136.0%) stddev-sans-first:24942.53 19:19:27 INFO - PROCESS | 23803 | Values: 653.6 683.2 700.6 725.8 746.1 12441.8 13263.8 13279.9 13290.7 13301.3 12493.3 12589.0 12599.3 12600.4 12649.7 617.8 634.3 754.2 754.3 785.7 762.0 770.7 773.8 775.4 775.7 829.7 830.2 830.3 830.7 831.0 651.2 797.4 814.1 826.3 826.8 592.7 596.4 598.0 598.1 598.6 12311.9 12327.3 12348.1 12350.6 12353.0 11814.3 12188.0 12589.4 12634.6 12820.7 10214.3 10608.0 11282.1 11325.3 11958.8 4144.7 4158.6 4207.9 4279.3 4383.6 53911.0 55183.4 55386.4 61661.4 62351.0 9925.6 9978.8 10107.3 10138.1 10751.5 18266.5 18312.6 18616.2 18893.9 18906.1 6909.8 6916.0 6938.6 7080.5 7192.5 3679.9 3720.2 3768.2 3790.5 3835.5 2734.4 2839.6 2848.4 2853.3 2854.5 503.1 511.4 514.2 515.0 525.2 544.0 591.1 593.6 605.5 607.7 7193.8 7525.4 7568.8 7600.0 7846.5 558.8 573.6 575.7 579.6 596.6 3344.7 3599.7 3610.4 3648.9 3734.5 99045.5 99741.0 101478.0 101938.5 102287.0 49329.5 53310.7 74507.3 81876.2 82439.0 73121.6 74393.8 76689.6 77035.0 79234.0 6856.3 7058.4 7093.3 7134.3 7212.9 44181.0 44787.6 44828.6 45080.0 46045.0 63974.4 64906.4 66110.7 66525.3 68031.3 7487.0 7817.7 8424.8 9142.7 9542.9 9925.1 9950.5 10005.4 10124.1 10127.0 9874.3 9980.5 10092.2 10229.7 10277.4 9635.8 9870.7 9892.9 9897.8 10065.1 9525.8 9529.0 9588.7 9694.9 9801.5 8118.6 8350.0 8359.2 8457.5 8917.4 8330.3 8748.0 8763.0 8766.3 8830.5 19:19:27 INFO - PROCESS | 23803 | -------- Summary: end -------- 19:19:27 INFO - PROCESS | 23803 | 19:19:28 INFO - TEST-INFO | 23803: exit 0 19:19:28 INFO - TEST-OK | dromaeo_css | took 1119363ms 19:19:28 INFO - TEST-START | kraken 19:19:28 INFO - operating with platform_type : linux_ 19:19:28 INFO - Initialising browser for kraken test... 19:19:28 INFO - TEST-INFO | started process 23992 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpaQmqvo/profile http://localhost:36865/getInfo.html) 19:19:31 INFO - PROCESS | 23992 | JavaScript error: , line 0: uncaught exception: undefined 19:19:32 INFO - PROCESS | 23992 | __metrics Screen width/height:1600/1200 19:19:32 INFO - PROCESS | 23992 | colorDepth:24 19:19:32 INFO - PROCESS | 23992 | Browser inner width/height: 1024/697 19:19:32 INFO - PROCESS | 23992 | __metrics 19:19:33 INFO - PROCESS | 23992 | 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" 19:19:33 INFO - PROCESS | 23992 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 58: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:19:33 INFO - PROCESS | 23992 | console.error: PushService: 19:19:33 INFO - PROCESS | 23992 | stateChangeProcessEnqueue: Error transitioning state 19:19:33 INFO - PROCESS | 23992 | UnknownError 19:19:34 INFO - TEST-INFO | 23992: exit 0 19:19:34 INFO - Browser initialized. 19:19:34 INFO - Running cycle 1/1 for kraken test... 19:19:34 INFO - TEST-INFO | started process 24075 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpaQmqvo/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:19:46 INFO - PROCESS | 24075 | RSS: Main: 159141888 19:19:46 INFO - PROCESS | 24075 | 19:19:49 INFO - PROCESS | 24075 | Cycle 1(1): loaded http://localhost:36865/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:36865/tests/kraken/driver.html?testName=audio-beat-detection) 19:19:49 INFO - PROCESS | 24075 | RSS: Main: 215134208 19:19:49 INFO - PROCESS | 24075 | 19:19:52 INFO - PROCESS | 24075 | Cycle 1(1): loaded http://localhost:36865/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:36865/tests/kraken/driver.html?testName=audio-dft) 19:19:53 INFO - PROCESS | 24075 | RSS: Main: 189972480 19:19:53 INFO - PROCESS | 24075 | 19:19:56 INFO - PROCESS | 24075 | Cycle 1(1): loaded http://localhost:36865/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:36865/tests/kraken/driver.html?testName=audio-fft) 19:19:57 INFO - PROCESS | 24075 | RSS: Main: 272830464 19:19:57 INFO - PROCESS | 24075 | 19:19:59 INFO - PROCESS | 24075 | Cycle 1(1): loaded http://localhost:36865/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:36865/tests/kraken/driver.html?testName=audio-oscillator) 19:19:59 INFO - PROCESS | 24075 | RSS: Main: 181366784 19:19:59 INFO - PROCESS | 24075 | 19:20:02 INFO - PROCESS | 24075 | Cycle 1(1): loaded http://localhost:36865/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:36865/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:20:02 INFO - PROCESS | 24075 | RSS: Main: 186671104 19:20:02 INFO - PROCESS | 24075 | 19:20:05 INFO - PROCESS | 24075 | Cycle 1(1): loaded http://localhost:36865/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:36865/tests/kraken/driver.html?testName=imaging-darkroom) 19:20:06 INFO - PROCESS | 24075 | RSS: Main: 223440896 19:20:06 INFO - PROCESS | 24075 | 19:20:10 INFO - PROCESS | 24075 | Cycle 1(1): loaded http://localhost:36865/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:36865/tests/kraken/driver.html?testName=imaging-desaturate) 19:20:10 INFO - PROCESS | 24075 | RSS: Main: 222924800 19:20:10 INFO - PROCESS | 24075 | 19:20:13 INFO - PROCESS | 24075 | Cycle 1(1): loaded http://localhost:36865/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:36865/tests/kraken/driver.html?testName=json-parse-financial) 19:20:14 INFO - PROCESS | 24075 | RSS: Main: 222605312 19:20:14 INFO - PROCESS | 24075 | 19:20:15 INFO - PROCESS | 24075 | Cycle 1(1): loaded http://localhost:36865/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:36865/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:20:16 INFO - PROCESS | 24075 | RSS: Main: 186355712 19:20:16 INFO - PROCESS | 24075 | 19:20:18 INFO - PROCESS | 24075 | Cycle 1(1): loaded http://localhost:36865/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:36865/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:20:18 INFO - PROCESS | 24075 | RSS: Main: 191692800 19:20:18 INFO - PROCESS | 24075 | 19:20:21 INFO - PROCESS | 24075 | Cycle 1(1): loaded http://localhost:36865/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:36865/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:20:21 INFO - PROCESS | 24075 | RSS: Main: 197160960 19:20:21 INFO - PROCESS | 24075 | 19:20:24 INFO - PROCESS | 24075 | Cycle 1(1): loaded http://localhost:36865/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:36865/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:20:25 INFO - PROCESS | 24075 | RSS: Main: 191496192 19:20:25 INFO - PROCESS | 24075 | 19:20:27 INFO - PROCESS | 24075 | Cycle 1(1): loaded http://localhost:36865/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:36865/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:20:28 INFO - PROCESS | 24075 | RSS: Main: 195440640 19:20:28 INFO - PROCESS | 24075 | 19:20:30 INFO - PROCESS | 24075 | Cycle 1(1): loaded http://localhost:36865/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:36865/tests/kraken/driver.html?testName=ai-astar) 19:20:30 INFO - PROCESS | 24075 | RSS: Main: 245043200 19:20:30 INFO - PROCESS | 24075 | 19:20:30 INFO - PROCESS | 24075 | __start_tp_report 19:20:30 INFO - PROCESS | 24075 | _x_x_mozilla_page_load 19:20:30 INFO - PROCESS | 24075 | _x_x_mozilla_page_load_details 19:20:30 INFO - PROCESS | 24075 | |i|pagename|runs| 19:20:30 INFO - PROCESS | 24075 | |0;ai-astar;103;115;112;114;114;113;112;111;111;112 19:20:30 INFO - PROCESS | 24075 | |1;audio-beat-detection;147;127;123;187;123;124;147;122;161;123 19:20:30 INFO - PROCESS | 24075 | |2;audio-dft;163;161;161;161;161;162;163;161;160;160 19:20:30 INFO - PROCESS | 24075 | |3;audio-fft;77;75;77;115;75;77;83;74;106;76 19:20:30 INFO - PROCESS | 24075 | |4;audio-oscillator;86;86;117;85;86;86;116;85;86;131 19:20:30 INFO - PROCESS | 24075 | |5;imaging-gaussian-blur;117;117;117;117;117;116;117;117;117;117 19:20:30 INFO - PROCESS | 24075 | |6;imaging-darkroom;147;146;146;148;146;146;145;146;147;147 19:20:30 INFO - PROCESS | 24075 | |7;imaging-desaturate;95;95;95;96;95;96;95;95;96;95 19:20:30 INFO - PROCESS | 24075 | |8;json-parse-financial;61;62;61;60;62;61;61;60;60;61 19:20:30 INFO - PROCESS | 24075 | |9;json-stringify-tinderbox;65;66;65;65;66;65;66;66;66;67 19:20:30 INFO - PROCESS | 24075 | |10;stanford-crypto-aes;79;79;80;90;82;80;79;84;79;79 19:20:30 INFO - PROCESS | 24075 | |11;stanford-crypto-ccm;130;139;134;137;128;135;126;125;177;132 19:20:30 INFO - PROCESS | 24075 | |12;stanford-crypto-pbkdf2;166;168;161;162;161;161;161;161;161;168 19:20:30 INFO - PROCESS | 24075 | |13;stanford-crypto-sha256-iterative;69;72;67;72;68;69;67;67;66;66 19:20:30 INFO - PROCESS | 24075 | __end_tp_report 19:20:30 INFO - PROCESS | 24075 | __start_cc_report 19:20:30 INFO - PROCESS | 24075 | _x_x_mozilla_cycle_collect,1960 19:20:30 INFO - PROCESS | 24075 | __end_cc_report 19:20:30 INFO - PROCESS | 24075 | __startTimestamp1469154030635__endTimestamp 19:20:30 INFO - PROCESS | 24075 | 19:20:30 INFO - PROCESS | 24075 | ------- Summary: start ------- 19:20:30 INFO - PROCESS | 24075 | Number of tests: 14 19:20:30 INFO - PROCESS | 24075 | 19:20:30 INFO - PROCESS | 24075 | [#0] ai-astar Cycles:10 Average:111.70 Median:112.50 stddev:3.33 (3.0%) stddev-sans-first:1.41 19:20:30 INFO - PROCESS | 24075 | Values: 103.0 115.0 112.0 114.0 114.0 113.0 112.0 111.0 111.0 112.0 19:20:30 INFO - PROCESS | 24075 | 19:20:30 INFO - PROCESS | 24075 | [#1] audio-beat-detection Cycles:10 Average:138.40 Median:137.00 stddev:21.96 (16.0%) stddev-sans-first:23.07 19:20:30 INFO - PROCESS | 24075 | Values: 147.0 127.0 123.0 187.0 123.0 124.0 147.0 122.0 161.0 123.0 19:20:30 INFO - PROCESS | 24075 | 19:20:30 INFO - PROCESS | 24075 | [#2] audio-dft Cycles:10 Average:161.30 Median:161.00 stddev:1.06 (0.7%) stddev-sans-first:0.93 19:20:30 INFO - PROCESS | 24075 | Values: 163.0 161.0 161.0 161.0 161.0 162.0 163.0 161.0 160.0 160.0 19:20:30 INFO - PROCESS | 24075 | 19:20:30 INFO - PROCESS | 24075 | [#3] audio-fft Cycles:10 Average:83.50 Median:76.50 stddev:14.59 (19.1%) stddev-sans-first:15.29 19:20:30 INFO - PROCESS | 24075 | Values: 77.0 75.0 77.0 115.0 75.0 77.0 83.0 74.0 106.0 76.0 19:20:30 INFO - PROCESS | 24075 | 19:20:30 INFO - PROCESS | 24075 | [#4] audio-oscillator Cycles:10 Average:96.40 Median:86.00 stddev:17.66 (20.5%) stddev-sans-first:18.32 19:20:30 INFO - PROCESS | 24075 | Values: 86.0 86.0 117.0 85.0 86.0 86.0 116.0 85.0 86.0 131.0 19:20:30 INFO - PROCESS | 24075 | 19:20:30 INFO - PROCESS | 24075 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 19:20:30 INFO - PROCESS | 24075 | Values: 117.0 117.0 117.0 117.0 117.0 116.0 117.0 117.0 117.0 117.0 19:20:30 INFO - PROCESS | 24075 | 19:20:30 INFO - PROCESS | 24075 | [#6] imaging-darkroom Cycles:10 Average:146.40 Median:146.50 stddev:0.84 (0.6%) stddev-sans-first:0.87 19:20:30 INFO - PROCESS | 24075 | Values: 147.0 146.0 146.0 148.0 146.0 146.0 145.0 146.0 147.0 147.0 19:20:30 INFO - PROCESS | 24075 | 19:20:30 INFO - PROCESS | 24075 | [#7] imaging-desaturate Cycles:10 Average:95.30 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 19:20:30 INFO - PROCESS | 24075 | Values: 95.0 95.0 95.0 96.0 95.0 96.0 95.0 95.0 96.0 95.0 19:20:30 INFO - PROCESS | 24075 | 19:20:30 INFO - PROCESS | 24075 | [#8] json-parse-financial Cycles:10 Average:60.90 Median:61.00 stddev:0.74 (1.2%) stddev-sans-first:0.78 19:20:30 INFO - PROCESS | 24075 | Values: 61.0 62.0 61.0 60.0 62.0 61.0 61.0 60.0 60.0 61.0 19:20:30 INFO - PROCESS | 24075 | 19:20:30 INFO - PROCESS | 24075 | [#9] json-stringify-tinderbox Cycles:10 Average:65.70 Median:66.00 stddev:0.67 (1.0%) stddev-sans-first:0.67 19:20:30 INFO - PROCESS | 24075 | Values: 65.0 66.0 65.0 65.0 66.0 65.0 66.0 66.0 66.0 67.0 19:20:30 INFO - PROCESS | 24075 | 19:20:30 INFO - PROCESS | 24075 | [#10] stanford-crypto-aes Cycles:10 Average:81.10 Median:80.00 stddev:3.54 (4.4%) stddev-sans-first:3.67 19:20:30 INFO - PROCESS | 24075 | Values: 79.0 79.0 80.0 90.0 82.0 80.0 79.0 84.0 79.0 79.0 19:20:30 INFO - PROCESS | 24075 | 19:20:30 INFO - PROCESS | 24075 | [#11] stanford-crypto-ccm Cycles:10 Average:136.30 Median:134.50 stddev:15.03 (11.2%) stddev-sans-first:15.76 19:20:30 INFO - PROCESS | 24075 | Values: 130.0 139.0 134.0 137.0 128.0 135.0 126.0 125.0 177.0 132.0 19:20:30 INFO - PROCESS | 24075 | 19:20:30 INFO - PROCESS | 24075 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:163.00 Median:161.50 stddev:3.06 (1.9%) stddev-sans-first:3.04 19:20:30 INFO - PROCESS | 24075 | Values: 166.0 168.0 161.0 162.0 161.0 161.0 161.0 161.0 161.0 168.0 19:20:30 INFO - PROCESS | 24075 | 19:20:30 INFO - PROCESS | 24075 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.30 Median:68.50 stddev:2.21 (3.2%) stddev-sans-first:2.33 19:20:30 INFO - PROCESS | 24075 | Values: 69.0 72.0 67.0 72.0 68.0 69.0 67.0 67.0 66.0 66.0 19:20:30 INFO - PROCESS | 24075 | -------- Summary: end -------- 19:20:30 INFO - PROCESS | 24075 | 19:20:31 INFO - TEST-INFO | 24075: exit 0 19:20:31 INFO - TEST-OK | kraken | took 63106ms 19:20:31 INFO - SUITE-END | took 1182s 19:20:31 INFO - Completed test suite (00:19:42) 19:20:31 INFO - javascript benchmark 19:20:31 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2620.908268210613, 2627.1724406811863, 2661.2282438092952, 2676.772474339681, 2678.290253082932, 4518.322251662253, 4628.722276663402, 4631.212693842046, 4638.188317044516, 4659.4175728034, 4631.004334068175, 4660.930086048704, 4667.533246675331, 4710.364100846385, 4728.692634978724, 4210.7473551587, 4217.409562661214, 4251.426057482231, 4344.304911214206, 4357.825686972526, 4311.180875633635, 4368.737875727451, 4411.911761764763, 4423.4249547558775, 4443.22243607369, 4356.215881141393, 4389.188000219953, 4403.603675669189, 4428.114377124572, 4440.911181776379, 4202.642775364097, 4211.305134652778, 4243.681723870691, 4265.786710664454, 4334.13317336534, 4297.183535128315, 4301.462317210348, 4305.806238719234, 4360.934585981213, 4367.58507941745, 4767.95232047682, 4778.784954677048, 4811.831585894512, 4848.296996935424, 4848.903021939542, 4812.807487700487, 5006.774695138727, 5015.448300686921, 5150.047241260341, 5153.098207813655, 5111.974440127776, 5111.977604479119, 5146.716930568818, 5154.742262886878, 5157.329547158883, 4775.641826862964, 4779.832705855279, 4802.639802014863, 4804.543568361, 4806.206975848946, 5018.824341148043, 5090.211017292251, 5100.132977393852, 5102.209157580581, 5260.92108618371, 4967.90064198714, 4969.751512424436, 4974.452810190876, 4986.626003049786, 4993.550580447777, 5062.417821950449, 5107.770150343244, 5114.156164232934, 5116.206987916805, 5121.923171152435, 4818.518148185153, 4961.8511444656715, 4996.775145118479, 5003.124453220672, 5013.047520971004, 5045.949540504622, 5047.570956468668, 5049.293098966147, 5056.747162641853, 5078.415982162025, 4287.506936702319, 4287.785610719514, 4293.141371725667, 4328.567143285646, 4339.4792624884585, 3682.926341473209, 3687.1335236219466, 3719.5350581177354, 3763.8682646107254, 3831.750936189139, 4166.687498437629, 4236.809343579546, 4254.595813397722, 4265.317549192115, 4287.957120428756, 5020.272060551272, 5027.924581131286, 5028.899422011539, 5038.748062596929, 5086.21163719624, 4276.5295817460055, 4292.849750258726, 4297.119090586422, 4299.14017196562, 4333.566643335641, 4436.866893993185, 4472.172648060055, 4475.731456112644, 4509.864704058883, 4566.520515345907, 4709.599684026896, 4709.976450117728, 4710.317004034464, 4713.198756211452, 4758.333833263277, 5002.299678045004, 5048.823291184938, 5051.1160546904875, 5059.924101138559, 5108.080545501845, 4598.8620341389815, 4618.261078227468, 4655.255159174516, 4664.137134630104, 4730.692504987165, 4896.951030489792, 4918.803247870188, 4921.50784921505, 4931.260310953386, 4976.975115124401, 4913.9017219656125, 4916.7541622918, 4917.385326834145, 4922.556969872729, 4972.975135124301, 4756.025014872012, 4803.159447096814, 4845.200541910545, 4862.489438608895, 4933.062718083553, 4841.927371089366, 4882.707037577615, 4886.047220791911, 4886.657933944732, 4939.6542242042715, 4827.879303017453, 4847.757612119451, 4848.490908454562, 4886.218205087312, 4886.828960986349, 4742.027884283782, 4895.583875370633, 4928.605711542997, 4944.480829512993, 5040.57155141817, 4832.395950506187, 4865.732384718733, 4866.0, 4870.196417591058, 4911.8280860169725], "value": 4616.062417239861, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [569.1207085053593, 586.4135864135864, 587.4331270324136, 588.2029849553854, 588.3528119069027, 27456.725432745665, 30138.246543836416, 30162.0, 30197.849010754944, 39891.40162897559, 38019.42970855432, 41036.0, 41083.38374924379, 41085.58914410855, 41095.79452102743, 7723.459357844968, 7858.371330293563, 7862.921370786276, 7864.803379915491, 7865.213478652117, 7494.587797671126, 7682.731104411347, 7699.153093159776, 7781.494202876823, 7783.377329813629, 7698.0, 7740.922590774105, 9973.800523989516, 10193.439360835151, 10218.795624087514, 10074.093331600154, 10111.3427627204, 10121.443320617363, 10123.29136960413, 10131.797364052714, 9908.405495670211, 9970.451625160613, 9987.101160895481, 9987.850182247272, 9987.850182247272, 30562.69437305621, 37459.438108428396, 38522.61477385218, 38584.80707596472, 41234.0, 3045.421369939704, 3048.878044878202, 3140.560321554984, 3453.4992426098074, 3627.546556680415, 29024.854875725698, 29114.272143196376, 29370.55944160839, 29643.0, 30355.544666830014, 3489.4068008438626, 3512.0868574170645, 3587.1749497615697, 3591.6408359164134, 3613.0, 236596.0, 239089.0, 239426.45161935335, 241363.0, 241393.99999999825, 47682.28476572854, 53207.46792532025, 53411.732941335045, 53481.465185348425, 53940.19089713658, 52423.0, 55738.0, 56090.158647620316, 56135.43864561302, 56193.7190314054, 15149.091054536542, 15279.541613751606, 15288.159151246566, 15288.464903728538, 15321.846781532042, 11467.942660286646, 11554.480048397842, 11573.0, 11573.074154067654, 11573.826392604118, 15982.840171598367, 17887.463376098738, 17931.641367172844, 18061.09694515269, 18134.727979080326, 6216.222972128484, 6673.663998726525, 6729.1252137221845, 7023.438124949992, 7095.716171353191, 768.3123604374053, 812.2446125103593, 865.8917635295588, 942.8255772682076, 963.2967933408651, 26615.201543953714, 27705.445891082065, 27928.16215513538, 28145.0, 28156.29609259744, 910.1262787723726, 923.7967647117623, 943.4716558727134, 959.4435227567993, 978.0806042320196, 14387.640308992359, 14899.553013409399, 14919.477818276526, 15283.312250948511, 15305.0, 380335.0, 385949.0, 386905.0, 387725.0, 387830.0, 44685.0, 45729.31406028912, 45737.542624573325, 48995.0, 49006.75496622566, 1187.619961612293, 1191.1899908062428, 1214.477774556949, 1216.5194748074462, 1220.0605533738972, 27548.449031019667, 28560.0, 28894.42211155723, 29045.419091617627, 29342.853285733436, 43186.0, 43599.12801744011, 43640.12719745651, 43662.56337436585, 43716.56283437125, 318666.0, 319617.0, 319753.0, 320134.0, 554544.0, 37218.44172337363, 37787.62212377951, 37851.242975139794, 37873.0, 38372.0, 41055.58944410518, 41083.383749243185, 41243.58756412518, 42096.0, 42125.0, 35834.64165358417, 35838.64161358353, 35870.641293587774, 36606.63393366032, 36809.81595092007, 35488.0, 35794.64205358017, 36360.82237466104, 36400.63599363973, 36541.26917461689, 34979.0, 35353.46969795404, 36004.639953600126, 36069.63930360663, 36179.0, 30866.228344291572, 32181.839090804395, 32547.674523255413, 32991.67008329982, 33000.174995624344, 24688.629670555314, 25116.74883251217, 25194.496110078064, 25495.617565736156, 25659.0], "value": 18006.877615119673, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [333.73634828485496, 341.0892915914508, 359.84346809138026, 364.57891135738225, 378.22275224413835, 12723.809142862865, 16294.918525407385, 16733.916330418346, 16819.83180168198, 17877.106144692745, 15781.368745250205, 15816.44642437515, 16408.589785255375, 16516.339346426128, 16535.503934881974, 4986.227134794094, 5176.0, 5186.170212765958, 5417.945820541783, 5453.727313634322, 3388.593368795748, 5466.125419932811, 5494.038543254935, 5494.2857428534435, 5593.972030139844, 5882.499987501067, 5888.5877988540815, 5902.232709747726, 5910.261217347832, 5912.083627037816, 5794.787086986694, 5824.068149096167, 5883.8529036774, 5902.085176797603, 5943.4056594340655, 5238.947610523884, 5240.659357141773, 5241.606879484054, 5244.239585260116, 5255.76349064293, 11825.113116516295, 11974.443322368123, 12236.938815305866, 12250.693732656666, 12322.81515777264, 1879.830815226636, 1904.2954106980505, 1933.7679478462674, 2142.4322554523064, 2206.7572567017614, 8473.279771219453, 8549.358798090168, 8705.347098967539, 8829.779255518599, 9344.67293644726, 1727.870409719276, 1745.834145756164, 1754.9723384831714, 1761.6652835961127, 1792.8158599820733, 64907.350926491075, 68490.31509684939, 69395.65302173507, 69651.0, 70236.0, 9477.194438472668, 9538.380005299634, 9767.755806104904, 9802.901970980342, 9917.553710082919, 13118.737625247442, 13530.18818870871, 13531.188128712309, 13552.322383880766, 13560.796588051187, 7062.894056589155, 7110.964445177741, 7156.391706704989, 7199.676014579357, 7297.708091676273, 6791.626460544718, 6833.3166683331265, 6952.478564107712, 6992.6853291602, 7048.823779405453, 3982.183652351261, 4017.678585713136, 4083.714140010171, 4121.464209652726, 4181.205570941512, 1542.3522120709104, 1719.9140042998051, 1771.503978885914, 1775.174543837122, 1810.076860801001, 688.3116883116883, 813.6785969541997, 822.2928281677753, 871.2115535440437, 896.2381975320925, 15419.614509637124, 16290.429834955956, 16753.832461675473, 16786.4124755633, 17134.3710576247, 792.5244853088101, 830.7839961610025, 832.7736736064377, 832.8584140696096, 840.3865178419787, 8296.543690097102, 9087.681931132378, 9111.27109831212, 9117.31620128493, 9237.30720195988, 407218.0, 407228.0, 408180.0, 409550.0, 412552.0, 84918.5754071238, 85070.0, 87124.0, 90571.64142537868, 90723.54638226899, 866.0776273269074, 874.8775171475999, 887.281637815191, 900.144862380741, 909.9363044586815, 15287.312070956612, 15431.305591248196, 16289.51131466058, 16416.425425110065, 16582.50252492427, 26623.068192613166, 27930.720692793624, 37280.81359593293, 39090.80454597709, 39604.0, 51005.0, 51503.2274515875, 53043.0, 53289.20066198933, 53397.19904201362, 7249.130104387507, 7289.307515786204, 7399.816029435265, 7571.3942884568005, 7581.54510729358, 8401.285890699359, 10931.39877306724, 11342.659720208407, 11766.529183852019, 11873.287602743863, 10166.339187952759, 10219.182465402899, 10222.591096356062, 10240.231982601485, 10264.538095785856, 10065.245106616887, 10249.846252306072, 10414.166866650801, 10599.841002384816, 10621.362718236931, 10052.396856188652, 10256.0, 10508.054275115277, 10652.307600005975, 10908.94545527299, 7416.110066792019, 7440.441966852616, 7524.322810947041, 7565.735199268, 7644.541327520366, 8452.36607254446, 10156.746081348025, 10199.592016319264, 10300.227482938659, 10351.0], "value": 7998.457936564391, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1376.9449222031117, 1425.1235490173542, 1451.4484495891559, 1634.3299247308605, 1639.9508014759554, 7847.489913155648, 8474.23731864132, 8474.279686226662, 8478.982522097349, 8480.067192608816, 6791.864162716743, 7642.656080476378, 7795.415343849219, 7871.055473343207, 8165.101838797742, 2178.9128434862587, 2188.89055547222, 2202.4383782135615, 2209.1163534586135, 2213.236433430464, 2174.4563859035243, 2197.3188311623367, 2235.441139715071, 2236.4185311818883, 2239.1826983151113, 1830.2313028528051, 1892.4984879007006, 1894.2707057782784, 1906.780720217172, 1911.6941289393699, 2130.968035479469, 2201.328594778592, 2210.0386331945656, 2223.055201539339, 2234.452559123009, 2184.6941428199907, 2210.0, 2211.2702808073295, 2213.6015517206897, 2216.490207252325, 8088.514689118671, 8135.715249966282, 8177.427580069398, 8200.343972482187, 8260.0, 4607.124646317189, 4725.440604600473, 4727.290906364038, 4729.432468103815, 4733.0, 2822.32264256579, 2906.796524243304, 2961.1116665000413, 2966.4512065267777, 2973.093206571995, 870.7518357268211, 915.6428992692855, 916.4272329793879, 919.4575200631659, 934.4907025671025, 11255.887441125566, 11256.268342557707, 11262.54949802007, 11266.605668801634, 11303.943480282545, 3946.664533514626, 4260.914781704349, 4391.560843915583, 4394.12117576486, 4399.318105693623, 9540.809183816424, 9554.0, 9670.032996700415, 9677.661281855102, 9686.370385925033, 4731.195696731564, 4744.335792988984, 4776.23580227162, 4808.543188397097, 4809.95190048102, 4285.957140428556, 4337.652987760972, 4341.5007274163245, 4341.6743744218675, 4341.804618792162, 3709.0912726382207, 3744.475773391727, 3801.7718936863325, 3846.0577158595765, 3858.1126340941178, 1168.7384910198793, 1184.3663639952756, 1191.7914364986093, 1197.233770386953, 1239.2378687107544, 663.3598577372859, 681.9045333653293, 734.9853002939911, 741.2143128284036, 742.2429122295229, 4966.180580204225, 4980.526849949249, 5138.2806407103035, 5177.8187763428095, 5204.115300398941, 389.31286279716323, 391.8589307849172, 395.2233860464144, 888.373696543935, 955.9617615295449, 3732.7760334380023, 3962.227365663726, 3964.484616999829, 3992.840286388512, 4032.1129351542613, 10175.287729858986, 10271.024252695983, 10399.272050956512, 10420.947895260626, 10421.322614030063, 9406.435613863054, 9673.516324183762, 9851.014898510091, 10297.53661085253, 10320.174386049099, 7470.626468676544, 7647.349975252055, 7656.425768067528, 7668.156502784689, 7678.347340476123, 5395.24466574672, 5479.506844384025, 5535.892821435809, 5603.607747457801, 5618.887622247614, 9479.767630208029, 9482.95258523703, 9492.57283422234, 9498.19265362452, 9603.951980240054, 9361.204297634777, 9447.574859131493, 9592.0, 9716.805663886824, 9785.315027947976, 7030.789076327718, 7176.96411517939, 7182.3535881770895, 7185.389241914287, 7188.460865435009, 7820.804479888048, 7847.254510821463, 7868.331191848757, 7872.960635197017, 7906.130325664288, 5395.379531353788, 6914.619695916773, 7007.859842803014, 7014.964925175342, 7283.708651653875, 6721.193456785217, 7031.613261270679, 7034.402075823612, 7067.2579379164445, 7171.533850299714, 6282.120503129657, 6396.296407395276, 6399.55203135776, 6464.321246269074, 6465.482761379172, 4564.771761411983, 4658.347831303688, 4688.531146885421, 4689.859304220879, 4845.636577256791, 4603.654725895504, 4633.258678611406, 4645.233536466445, 4653.650976176733, 4689.624830013522], "value": 4185.4518904421075, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [297.4734719546403, 301.7917636830587, 309.6470024172444, 313.7803537523734, 314.352433985989, 4986.850394488167, 4998.975005124974, 5000.9996001599375, 5006.224035274538, 5018.949810501903, 4899.314096026559, 4906.8773280668, 4946.0602485527725, 4962.578180854632, 4982.252662100677, 3587.9282414351633, 3664.175560498893, 3694.8409110210787, 3782.111203867089, 3787.2993496203153, 3800.3349413852466, 3805.866794662187, 3809.2762375148773, 3811.752236104658, 3826.4260360945805, 3773.3208022555928, 3775.169462718198, 3775.8867233983024, 3784.470174175604, 3787.337215987205, 3617.3669607818656, 3698.704103671727, 3800.6009369016133, 3814.5803961564206, 3821.426785982125, 3377.8986630401128, 3378.8986330410125, 3384.1539615096226, 3386.542816719736, 3387.6442973487924, 4776.880577985544, 4786.617070634341, 4790.473047964721, 4794.113089078532, 4814.542618451242, 4109.97945010276, 4347.434833471629, 4395.0, 4395.494518130424, 4417.712848664826, 5130.127878260705, 5130.461301563357, 5141.280220769096, 5145.30538377318, 5149.480903133584, 3617.0957260684827, 3624.4200927851416, 3631.2011357501306, 3647.434647629622, 3651.6348365163535, 5086.542211200973, 5389.865253368658, 5447.237386765816, 5472.34331880173, 5474.644148130398, 3651.3062518121474, 3703.833327500215, 3704.944425833615, 3706.6849317808287, 3709.2025214578457, 3759.7180211484247, 3761.3417651910804, 3763.0, 3774.8678796241998, 3779.6976241900584, 4335.7615331157085, 4357.324614684729, 4361.389405483234, 4361.411209486742, 4369.344598310279, 5026.84919452417, 5093.668911520739, 5128.410232823198, 5173.482651734872, 5224.0, 1280.5326055989683, 1289.9551363395678, 1292.5346875124947, 1295.57893684551, 1297.6626077219992, 770.2643975003858, 785.297159042665, 792.6631181747734, 802.1577343788998, 806.4919100966354, 4327.37253098299, 4446.666500012448, 4471.28459446487, 4471.8434854779925, 4478.216312145361, 5280.709560974184, 5294.602904782157, 5294.708791016531, 5307.814226502054, 5313.203019547099, 4470.8435204767675, 4484.609770971009, 4488.304312831517, 4489.708168969007, 4526.479454862733, 2652.9204123876316, 2805.466961277351, 2814.7326004029583, 2819.6052552642645, 2824.632797736281, 4991.5258050485145, 5006.3992320921725, 5016.0, 5018.121828679967, 5039.21892106724, 5251.921221181686, 5399.811006614749, 5423.186522021729, 5488.506034456918, 5489.85895500755, 4905.901881962341, 4933.605311575066, 4934.5805606523845, 4935.950640493621, 4942.283368911416, 5236.266922630759, 5271.182966640177, 5302.655327403706, 5308.443342960303, 5320.334958130234, 4993.0388874196215, 5205.687658740488, 5233.869153271199, 5242.633015689018, 5256.579473642176, 4985.401751789808, 4989.850304490871, 4990.750462476789, 4992.603550295806, 4995.875103122451, 3360.2271477559807, 3376.8818091366684, 3394.5050387964334, 3400.9829950851076, 3419.5721038063252, 4579.908401832011, 4716.434027916534, 4734.6449016324605, 4759.190937540558, 4807.927881081717, 4543.932845329456, 4564.908701826012, 4578.679492435497, 4601.653156889944, 4610.331501932268, 4547.340635607885, 4566.566176213254, 4577.0, 4586.839460618862, 4600.2409602416565, 4363.890902727458, 4484.349769283371, 4517.254652982221, 4530.637548996138, 4567.725936443824, 4587.797857028711, 4598.448186217675, 4882.389701287339, 4950.836553409874, 5102.36220472441, 5017.322661440805, 5018.04657115147, 5069.695818250917, 5073.619478539198, 5079.16193828029], "value": 3789.691111064193, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [653.647030603474, 683.2347770497041, 700.6356694518854, 725.7750097469785, 746.0674157303371, 12441.751164976695, 13263.801042984338, 13279.867201327985, 13290.667733306673, 13301.334933253334, 12493.312867792269, 12589.0, 12599.3070381129, 12600.432980515876, 12649.747005059917, 617.8331850400389, 634.3212762344293, 754.1628792040831, 754.2985023927745, 785.654312102673, 761.9695212191506, 770.6570576093641, 773.7678696391089, 775.414562005685, 775.7129861951086, 829.7344849648115, 830.1781236575777, 830.2983978538126, 830.7217082277444, 831.0276975938167, 651.2282944710533, 797.3860127701695, 814.0516298512226, 826.2687521543447, 826.7809030606894, 592.7095723095695, 596.4155127974609, 598.0222336479886, 598.0938877600439, 598.6408155106901, 12311.876881231163, 12327.260364378166, 12348.073894457862, 12350.62948111558, 12353.0, 11814.291142531476, 12188.0, 12589.433475493624, 12634.620961371174, 12820.678642317378, 10214.336068155546, 10608.045275925127, 11282.097432205404, 11325.320480771097, 11958.820617690655, 4144.668426525901, 4158.6465150462245, 4207.873763787091, 4279.272523670952, 4383.627391671712, 53911.0, 55183.44816551863, 55386.44613553853, 61661.38338616646, 62351.0, 9925.553350099262, 9978.750531236778, 10107.29248952581, 10138.087572118544, 10751.462426878625, 18266.54333641643, 18312.63374732498, 18616.162272697762, 18893.90553047226, 18906.05469726508, 6909.792706218822, 6915.965420172867, 6938.618375989268, 7080.539764915264, 7192.532485388538, 3679.9448008279896, 3720.162963333228, 3768.2086761779783, 3790.5451345838674, 3835.53973523179, 2734.412101398169, 2839.6024556561683, 2848.4303139371796, 2853.315204350941, 2854.471922694308, 503.1471655543818, 511.40166005773335, 514.2363590068745, 514.9871253218699, 525.1990714160935, 543.9646422982494, 591.1251348004977, 593.6022864680674, 605.5488660947623, 607.7143742441045, 7193.820154496179, 7525.397968162534, 7568.810779730551, 7599.962000189964, 7846.489978151402, 558.8091994710169, 573.6156774960787, 575.7063897412266, 579.5537436174207, 596.5943158652158, 3344.6989770920736, 3599.7300202484394, 3610.4403817408343, 3648.890533284006, 3734.514513113278, 99045.50477247711, 99741.0, 101478.0, 101938.49030754798, 102287.0, 49329.50670493321, 53310.73344633252, 74507.25492745111, 81876.18123818804, 82439.0, 73121.6343918277, 74393.76357523253, 76689.61655191689, 77035.0, 79234.0, 6856.348646878738, 7058.3647471725735, 7093.32613401726, 7134.32223938725, 7212.855742885218, 44181.0, 44787.55212447834, 44828.55171448244, 45080.0, 46045.0, 63974.36025639684, 64906.35093649003, 66110.66944665246, 66525.33474665192, 68031.3196868025, 7487.0, 7817.726379576688, 8424.789380265542, 9142.725718228463, 9542.904570954202, 9925.057119573628, 9950.502474876082, 10005.399676019462, 10124.13944814699, 10127.0, 9874.308798384043, 9980.500974951368, 10092.192624590161, 10229.74425639365, 10277.383356998604, 9635.843698756194, 9870.654527091518, 9892.851607225753, 9897.752556186153, 10065.094141527297, 9525.809483810424, 9528.95235523818, 9588.712338629852, 9694.9030509694, 9801.460919649488, 8118.634661440367, 8349.95825020871, 8359.247667709935, 8457.450265732708, 8917.375783695079, 8330.250277475056, 8748.0, 8762.956185219033, 8766.254868336007, 8830.514321712368], "value": 6251.044732624087, "unit": "score"}], "name": "dromaeo_css", "value": 6355.90765014686, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 115.0, 112.0, 114.0, 114.0, 113.0, 112.0, 111.0, 111.0, 112.0], "value": 111.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 127.0, 123.0, 187.0, 123.0, 124.0, 147.0, 122.0, 161.0, 123.0], "value": 138.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 161.0, 161.0, 161.0, 161.0, 162.0, 163.0, 161.0, 160.0, 160.0], "value": 161.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 75.0, 77.0, 115.0, 75.0, 77.0, 83.0, 74.0, 106.0, 76.0], "value": 83.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 117.0, 85.0, 86.0, 86.0, 116.0, 85.0, 86.0, 131.0], "value": 96.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 116.0, 117.0, 117.0, 117.0, 117.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 146.0, 148.0, 146.0, 146.0, 145.0, 146.0, 147.0, 147.0], "value": 146.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 96.0, 95.0, 96.0, 95.0, 95.0, 96.0, 95.0], "value": 95.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 62.0, 61.0, 60.0, 62.0, 61.0, 61.0, 60.0, 60.0, 61.0], "value": 60.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 65.0, 65.0, 66.0, 65.0, 66.0, 66.0, 66.0, 67.0], "value": 65.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 80.0, 90.0, 82.0, 80.0, 79.0, 84.0, 79.0, 79.0], "value": 81.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [130.0, 139.0, 134.0, 137.0, 128.0, 135.0, 126.0, 125.0, 177.0, 132.0], "value": 136.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 168.0, 161.0, 162.0, 161.0, 161.0, 161.0, 161.0, 161.0, 168.0], "value": 163.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 72.0, 67.0, 72.0, 68.0, 69.0, 67.0, 67.0, 66.0, 66.0], "value": 68.3, "unit": "score"}], "name": "kraken", "value": 1525.1999999999998, "alertThreshold": 2.0}]} 19:20:31 INFO - Return code: 0 19:20:31 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:20:31 INFO - # TBPL SUCCESS # 19:20:31 INFO - Running post-action listener: _resource_record_post_action 19:20:31 INFO - [mozharness: 2016-07-22 02:20:31.950591Z] Finished run-tests step (success) 19:20:31 INFO - Running post-run listener: _resource_record_post_run 19:20:32 INFO - Total resource usage - Wall time: 1199s; CPU: 13.0%; Read bytes: 4096; Write bytes: 553091072; Read time: 12; Write time: 533148 19:20:32 INFO - TinderboxPrint: CPU usage
13.0% 19:20:32 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 19:20:32 INFO - TinderboxPrint: I/O write bytes / time
553,091,072 / 533,148 19:20:32 INFO - TinderboxPrint: CPU idle
8,322.3 (87.0%) 19:20:32 INFO - TinderboxPrint: CPU user
1,201.9 (12.6%) 19:20:32 INFO - TinderboxPrint: Swap in / out
0 / 0 19:20:32 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 263438336; Read time: 0; Write time: 292832 19:20:32 INFO - run-tests - Wall time: 1183s; CPU: 13.0%; Read bytes: 4096; Write bytes: 289382400; Read time: 12; Write time: 240296 19:20:32 INFO - Running post-run listener: _upload_blobber_files 19:20:32 INFO - Blob upload gear active. 19:20:32 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:20:32 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 19:20:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Autoland-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 19:20:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Autoland-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 19:20:32 INFO - (blobuploader) - INFO - Open directory for files ... 19:20:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 19:20:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:20:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:20:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 19:20:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:20:33 INFO - (blobuploader) - INFO - Done attempting. 19:20:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 19:20:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:20:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:20:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 19:20:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:20:34 INFO - (blobuploader) - INFO - Done attempting. 19:20:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:20:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:20:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:20:56 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:20:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:20:56 INFO - (blobuploader) - INFO - Done attempting. 19:20:56 INFO - (blobuploader) - INFO - Iteration through files over. 19:20:56 INFO - Return code: 0 19:20:56 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:20:56 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:20:56 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/f28855cfbdd65adef5d7956e86f2abe48398c1d82571e6b75cfed05a69390374ce7365110a9a48dcf702672fe541bcf174cb85bf029a3d04e202a7cace6f59ca", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/4409661f1cf8741799fd301237d6aad1d22cde0f10d7569d87f920c0e6aa71effa03a0a0607e30cafa31557252c4e1fa189552dde499fe44bdf86f9a103d9466", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/ec5b0c1d43fe3e226e324f115eed9aea8be20263bbf94a6674a615a3808061311a9bcb601a04a8330b37c653fd9ff8747d74230cb85807a518b3c64327d704aa"} 19:20:56 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:20:56 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:20:56 INFO - Contents: 19:20:56 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/f28855cfbdd65adef5d7956e86f2abe48398c1d82571e6b75cfed05a69390374ce7365110a9a48dcf702672fe541bcf174cb85bf029a3d04e202a7cace6f59ca", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/4409661f1cf8741799fd301237d6aad1d22cde0f10d7569d87f920c0e6aa71effa03a0a0607e30cafa31557252c4e1fa189552dde499fe44bdf86f9a103d9466", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/ec5b0c1d43fe3e226e324f115eed9aea8be20263bbf94a6674a615a3808061311a9bcb601a04a8330b37c653fd9ff8747d74230cb85807a518b3c64327d704aa"} 19:20:56 INFO - Running post-run listener: copy_logs_to_upload_dir 19:20:56 INFO - Copying logs to upload dir... 19:20:56 INFO - mkdir: /builds/slave/test/build/upload/logs 19:20:56 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1261.589459 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2016-07-21 19:20:56.342262) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-21 19:20:56.345775) ========= 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=0e2a256762fed810e2f0f810000001b3-1469151937.637665-1236963751 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/f28855cfbdd65adef5d7956e86f2abe48398c1d82571e6b75cfed05a69390374ce7365110a9a48dcf702672fe541bcf174cb85bf029a3d04e202a7cace6f59ca", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/4409661f1cf8741799fd301237d6aad1d22cde0f10d7569d87f920c0e6aa71effa03a0a0607e30cafa31557252c4e1fa189552dde499fe44bdf86f9a103d9466", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/ec5b0c1d43fe3e226e324f115eed9aea8be20263bbf94a6674a615a3808061311a9bcb601a04a8330b37c653fd9ff8747d74230cb85807a518b3c64327d704aa"} build_url:https://queue.taskcluster.net/v1/task/Q9tHZZfnT_qmxPhipieTCQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011379 build_url: 'https://queue.taskcluster.net/v1/task/Q9tHZZfnT_qmxPhipieTCQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/f28855cfbdd65adef5d7956e86f2abe48398c1d82571e6b75cfed05a69390374ce7365110a9a48dcf702672fe541bcf174cb85bf029a3d04e202a7cace6f59ca", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/4409661f1cf8741799fd301237d6aad1d22cde0f10d7569d87f920c0e6aa71effa03a0a0607e30cafa31557252c4e1fa189552dde499fe44bdf86f9a103d9466", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/ec5b0c1d43fe3e226e324f115eed9aea8be20263bbf94a6674a615a3808061311a9bcb601a04a8330b37c653fd9ff8747d74230cb85807a518b3c64327d704aa"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-21 19:20:56.371159) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 19:20:56.371541) ========= 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=0e2a256762fed810e2f0f810000001b3-1469151937.637665-1236963751 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004550 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 19:20:56.457105) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-21 19:20:56.457442) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-21 19:20:56.457748) ========= ========= Total master_lag: 0.18 =========