builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-016 starttime: 1469141937.17 results: success (0) buildid: 20160721144820 builduid: fb0d2f467d3543f18d34a808d054c000 revision: 10501352b0eef4ecf19dd4d5b1d307ebb212cf5e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-21 15:58:57.174835) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-21 15:58:57.175236) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-21 15:58:57.175532) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-21 15:58:57.202122) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 15:58:57.202393) ========= 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=00204705a9daa57ed339a427000001b9-1469140399.960441-1326953982 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-21 15:58:57-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 71.0M=0s 2016-07-21 15:58:57 (71.0 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.160798 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 15:58:57.377568) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 15:58:57.378014) ========= 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=00204705a9daa57ed339a427000001b9-1469140399.960441-1326953982 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014543 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 15:58:57.432321) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 15:58:57.432676) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 10501352b0eef4ecf19dd4d5b1d307ebb212cf5e --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 10501352b0eef4ecf19dd4d5b1d307ebb212cf5e --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=00204705a9daa57ed339a427000001b9-1469140399.960441-1326953982 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-21 15:58:57,525 truncating revision to first 12 chars 2016-07-21 15:58:57,525 Setting DEBUG logging. 2016-07-21 15:58:57,525 attempt 1/10 2016-07-21 15:58:57,525 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/10501352b0ee?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-21 15:58:57,836 unpacking tar archive at: mozilla-central-10501352b0ee/testing/mozharness/ program finished with exit code 0 elapsedTime=0.648290 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 15:58:58.094242) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-21 15:58:58.094560) ========= script_repo_revision: 10501352b0eef4ecf19dd4d5b1d307ebb212cf5e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-21 15:58:58.094980) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-21 15:58:58.095242) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-21 15:58:58.106032) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2016-07-21 15:58:58.106301) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-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', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-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=00204705a9daa57ed339a427000001b9-1469140399.960441-1326953982 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:58:58 INFO - MultiFileLogger online at 20160721 15:58:58 in /builds/slave/test 15:58:58 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 15:58:58 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:58:58 INFO - {'append_to_log': False, 15:58:58 INFO - 'base_work_dir': '/builds/slave/test', 15:58:58 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 15:58:58 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:58:58 INFO - 'branch': 'Firefox-Non-PGO', 15:58:58 INFO - 'buildbot_json_path': 'buildprops.json', 15:58:58 INFO - 'config_files': ('talos/linux_config.py',), 15:58:58 INFO - 'default_actions': ('clobber', 15:58:58 INFO - 'read-buildbot-config', 15:58:58 INFO - 'download-and-extract', 15:58:58 INFO - 'populate-webroot', 15:58:58 INFO - 'create-virtualenv', 15:58:58 INFO - 'install', 15:58:58 INFO - 'run-tests'), 15:58:58 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:58:58 INFO - 'download_minidump_stackwalk': True, 15:58:58 INFO - 'download_symbols': 'ondemand', 15:58:58 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:58:58 INFO - 'tooltool.py': '/tools/tooltool.py', 15:58:58 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:58:58 INFO - '/tools/misc-python/virtualenv.py')}, 15:58:58 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:58:58 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:58:58 INFO - 'installer_path': 'installer.exe', 15:58:58 INFO - 'log_level': 'info', 15:58:58 INFO - 'log_name': 'talos', 15:58:58 INFO - 'log_to_console': True, 15:58:58 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:58:58 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:58:58 INFO - 'opt_config_files': (), 15:58:58 INFO - 'pip_index': False, 15:58:58 INFO - 'sps_profile': False, 15:58:58 INFO - 'sps_profile_interval': 0, 15:58:58 INFO - 'suite': 'dromaeojs', 15:58:58 INFO - 'system_bits': '32', 15:58:58 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:58:58 INFO - 'title': 'talos-linux64-ix-016', 15:58:58 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:58:58 INFO - 'use_talos_json': True, 15:58:58 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:58:58 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:58:58 INFO - 'work_dir': 'build'} 15:58:58 INFO - [mozharness: 2016-07-21 22:58:58.293522Z] Running clobber step. 15:58:58 INFO - Running pre-action listener: _resource_record_pre_action 15:58:58 INFO - Running main action method: clobber 15:58:58 INFO - rmtree: /builds/slave/test/build 15:58:58 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:58:58 INFO - Running post-action listener: _resource_record_post_action 15:58:58 INFO - [mozharness: 2016-07-21 22:58:58.755355Z] Finished clobber step (success) 15:58:58 INFO - [mozharness: 2016-07-21 22:58:58.755455Z] Running read-buildbot-config step. 15:58:58 INFO - Running pre-action listener: _resource_record_pre_action 15:58:58 INFO - Running main action method: read_buildbot_config 15:58:58 INFO - Using buildbot properties: 15:58:58 INFO - { 15:58:58 INFO - "project": "", 15:58:58 INFO - "product": "firefox", 15:58:58 INFO - "script_repo_revision": "production", 15:58:58 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 15:58:58 INFO - "repository": "", 15:58:58 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 15:58:58 INFO - "buildid": "20160721144820", 15:58:58 INFO - "pgo_build": "False", 15:58:58 INFO - "basedir": "/builds/slave/test", 15:58:58 INFO - "buildnumber": 656, 15:58:58 INFO - "slavename": "talos-linux64-ix-016", 15:58:58 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 15:58:58 INFO - "platform": "ubuntu64_hw", 15:58:58 INFO - "scheduler": "tests-mozilla-central-linux64-talos", 15:58:58 INFO - "branch": "mozilla-central", 15:58:58 INFO - "repo_path": "mozilla-central", 15:58:58 INFO - "revision": "10501352b0eef4ecf19dd4d5b1d307ebb212cf5e", 15:58:58 INFO - "stage_platform": "linux64", 15:58:58 INFO - "builduid": "fb0d2f467d3543f18d34a808d054c000", 15:58:58 INFO - "slavebuilddir": "test" 15:58:58 INFO - } 15:58:58 INFO - Found installer url https://queue.taskcluster.net/v1/task/JYRAzwcmSci5-mG6CSINfg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 15:58:58 INFO - Running post-action listener: _resource_record_post_action 15:58:58 INFO - [mozharness: 2016-07-21 22:58:58.757868Z] Finished read-buildbot-config step (success) 15:58:58 INFO - [mozharness: 2016-07-21 22:58:58.757956Z] Running download-and-extract step. 15:58:58 INFO - Running pre-action listener: _resource_record_pre_action 15:58:58 INFO - Running main action method: download_and_extract 15:58:58 INFO - mkdir: /builds/slave/test/build/tests 15:58: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')]} 15:58:58 INFO - https://queue.taskcluster.net/v1/task/JYRAzwcmSci5-mG6CSINfg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 15:58:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JYRAzwcmSci5-mG6CSINfg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 15:58:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JYRAzwcmSci5-mG6CSINfg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 15:58:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JYRAzwcmSci5-mG6CSINfg/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 15:58:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JYRAzwcmSci5-mG6CSINfg/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 15:59:07 INFO - Downloaded 1361 bytes. 15:59:07 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 15:59:07 INFO - Using the following test package requirements: 15:59:07 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 15:59:07 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 15:59:07 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 15:59:07 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 15:59:07 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 15:59:07 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 15:59:07 INFO - u'jsshell-linux-x86_64.zip'], 15:59:07 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 15:59:07 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 15:59:07 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 15:59:07 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 15:59:07 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 15:59:07 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 15:59:07 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 15:59:07 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 15:59:07 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 15:59:07 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 15:59:07 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 15:59:07 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 15:59:07 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')]} 15:59:07 INFO - https://queue.taskcluster.net/v1/task/JYRAzwcmSci5-mG6CSINfg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 15:59:07 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JYRAzwcmSci5-mG6CSINfg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 15:59:07 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JYRAzwcmSci5-mG6CSINfg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 15:59:07 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JYRAzwcmSci5-mG6CSINfg/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 15:59:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JYRAzwcmSci5-mG6CSINfg/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 15:59:18 INFO - Downloaded 22735470 bytes. 15:59:18 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'] 15:59:18 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 15:59:19 INFO - Return code: 0 15:59:19 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 15:59:19 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')]} 15:59:19 INFO - https://queue.taskcluster.net/v1/task/JYRAzwcmSci5-mG6CSINfg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 15:59:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JYRAzwcmSci5-mG6CSINfg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 15:59:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JYRAzwcmSci5-mG6CSINfg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 15:59:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JYRAzwcmSci5-mG6CSINfg/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 15:59:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JYRAzwcmSci5-mG6CSINfg/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 15:59:21 INFO - Downloaded 22735470 bytes. 15:59:21 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'] 15:59:21 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 15:59:22 INFO - Return code: 0 15:59:22 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')]} 15:59:22 INFO - https://queue.taskcluster.net/v1/task/JYRAzwcmSci5-mG6CSINfg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 15:59:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JYRAzwcmSci5-mG6CSINfg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 15:59:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JYRAzwcmSci5-mG6CSINfg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 15:59:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JYRAzwcmSci5-mG6CSINfg/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 15:59:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JYRAzwcmSci5-mG6CSINfg/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 15:59:23 INFO - Downloaded 14403317 bytes. 15:59:23 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'] 15:59:23 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 15:59:23 INFO - Return code: 0 15:59:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 15:59:23 INFO - https://queue.taskcluster.net/v1/task/JYRAzwcmSci5-mG6CSINfg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 15:59:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JYRAzwcmSci5-mG6CSINfg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 15:59:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JYRAzwcmSci5-mG6CSINfg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 15:59:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JYRAzwcmSci5-mG6CSINfg/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 15:59:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JYRAzwcmSci5-mG6CSINfg/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 15:59:25 INFO - Downloaded 57148915 bytes. 15:59:25 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/JYRAzwcmSci5-mG6CSINfg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 15:59:25 INFO - mkdir: /builds/slave/test/properties 15:59:25 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:59:25 INFO - Writing to file /builds/slave/test/properties/build_url 15:59:25 INFO - Contents: 15:59:25 INFO - build_url:https://queue.taskcluster.net/v1/task/JYRAzwcmSci5-mG6CSINfg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 15:59:25 INFO - Running post-action listener: _resource_record_post_action 15:59:25 INFO - Running post-action listener: set_extra_try_arguments 15:59:25 INFO - [mozharness: 2016-07-21 22:59:25.737755Z] Finished download-and-extract step (success) 15:59:25 INFO - [mozharness: 2016-07-21 22:59:25.737987Z] Running populate-webroot step. 15:59:25 INFO - Running pre-action listener: _resource_record_pre_action 15:59:25 INFO - Running main action method: populate_webroot 15:59:25 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:59:25 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:59:25 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:59:25 INFO - '--tppagecycles', 15:59:25 INFO - '7'], 15:59:25 INFO - 'tests': ['tsvgm']}}, 15:59:25 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:59:25 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:59:25 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:59:25 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:59:25 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:59:25 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:59:25 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:59:25 INFO - 'talos_options': ['--disable-e10s'], 15:59:25 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:59:25 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:59:25 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:59:25 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:59:25 INFO - 'talos_options': ['--disable-e10s'], 15:59:25 INFO - 'tests': ['damp', 'tps']}, 15:59:25 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:59:25 INFO - 'tests': ['damp', 'tps']}, 15:59:25 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:59:25 INFO - 'tests': ['dromaeo_dom']}, 15:59:25 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:59:25 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:59:25 INFO - 'tests': ['basic_compositor_video']}, 15:59:25 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:59:25 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:59:25 INFO - 'tests': ['a11yr', 15:59:25 INFO - 'ts_paint', 15:59:25 INFO - 'tpaint', 15:59:25 INFO - 'sessionrestore', 15:59:25 INFO - 'sessionrestore_no_auto_restore', 15:59:25 INFO - 'tabpaint']}, 15:59:25 INFO - 'other-e10s': {'tests': ['a11yr', 15:59:25 INFO - 'ts_paint', 15:59:25 INFO - 'tpaint', 15:59:25 INFO - 'sessionrestore', 15:59:25 INFO - 'sessionrestore_no_auto_restore', 15:59:25 INFO - 'tabpaint']}, 15:59:25 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:59:25 INFO - 'ts_paint', 15:59:25 INFO - 'tpaint', 15:59:25 INFO - 'sessionrestore', 15:59:25 INFO - 'sessionrestore_no_auto_restore', 15:59:25 INFO - 'tabpaint']}, 15:59:25 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:59:25 INFO - 'ts_paint', 15:59:25 INFO - 'tpaint', 15:59:25 INFO - 'sessionrestore', 15:59:25 INFO - 'sessionrestore_no_auto_restore', 15:59:25 INFO - 'tabpaint']}, 15:59:25 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:59:25 INFO - 'tests': ['a11yr', 15:59:25 INFO - 'ts_paint', 15:59:25 INFO - 'tpaint', 15:59:25 INFO - 'sessionrestore', 15:59:25 INFO - 'sessionrestore_no_auto_restore', 15:59:25 INFO - 'tabpaint']}, 15:59:25 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:59:25 INFO - 'tests': ['a11yr', 15:59:25 INFO - 'ts_paint', 15:59:25 INFO - 'tpaint', 15:59:25 INFO - 'sessionrestore', 15:59:25 INFO - 'sessionrestore_no_auto_restore', 15:59:25 INFO - 'tabpaint']}, 15:59:25 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:59:25 INFO - 'tests': ['tsvgx', 15:59:25 INFO - 'tsvgr_opacity', 15:59:25 INFO - 'tart', 15:59:25 INFO - 'tscrollx', 15:59:25 INFO - 'cart']}, 15:59:25 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:59:25 INFO - 'tsvgr_opacity', 15:59:25 INFO - 'tart', 15:59:25 INFO - 'tscrollx', 15:59:25 INFO - 'cart']}, 15:59:25 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:59:25 INFO - 'talos_options': ['--disable-e10s'], 15:59:25 INFO - 'tests': ['tp5o']}, 15:59:25 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:59:25 INFO - 'tests': ['tp5o']}, 15:59:25 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:59:25 INFO - 'talos_options': ['--disable-e10s', 15:59:25 INFO - '--xperf_path', 15:59:25 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:59:25 INFO - 'tests': ['tp5n']}, 15:59:25 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:59:25 INFO - 'talos_options': ['--xperf_path', 15:59:25 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:59:25 INFO - 'tests': ['tp5n']}}, 15:59:25 INFO - 'talos.zip': {'path': '', 15:59:25 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:59:25 INFO - Running post-action listener: _resource_record_post_action 15:59:25 INFO - [mozharness: 2016-07-21 22:59:25.758354Z] Finished populate-webroot step (success) 15:59:25 INFO - [mozharness: 2016-07-21 22:59:25.758443Z] Running create-virtualenv step. 15:59:25 INFO - Running pre-action listener: _resource_record_pre_action 15:59:25 INFO - Running main action method: create_virtualenv 15:59:25 INFO - Creating virtualenv /builds/slave/test/build/venv 15:59:25 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 15:59:25 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:59:25 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:59:25 INFO - Using real prefix '/usr' 15:59:25 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:59:26 INFO - Installing distribute.............................................................................................................................................................................................done. 15:59:28 INFO - Installing pip.................done. 15:59:28 INFO - Return code: 0 15:59:28 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:59:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 15:59:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:59:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:59:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 15:59:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:59:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:59:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21c6030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23298a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2487180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24853b0>, '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 0x24862b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2486740>, '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': '00204705a9daa57ed339a427000001b9-1469140399.960441-1326953982', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:59:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 15:59:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 15:59:28 INFO - Using env: {'DISPLAY': ':0', 15:59:28 INFO - 'HOME': '/home/cltbld', 15:59:28 INFO - 'LANG': 'en_US.UTF-8', 15:59:28 INFO - 'LANGUAGE': 'en_US:en', 15:59:28 INFO - 'LOGNAME': 'cltbld', 15:59:28 INFO - 'MAIL': '/var/mail/cltbld', 15:59:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:59:28 INFO - 'MOZ_NO_REMOTE': '1', 15:59:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:59:28 INFO - 'NO_EM_RESTART': '1', 15:59:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:59:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:59:28 INFO - 'PWD': '/builds/slave/test', 15:59:28 INFO - 'SHELL': '/bin/bash', 15:59:28 INFO - 'SHLVL': '1', 15:59:28 INFO - 'TERM': 'linux', 15:59:28 INFO - 'TMOUT': '86400', 15:59:28 INFO - 'USER': 'cltbld', 15:59:28 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1469140399.960441-1326953982', 15:59:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:59:28 INFO - '_': '/tools/buildbot/bin/python'} 15:59:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:59:28 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 15:59:28 INFO - Cleaning up... 15:59:28 INFO - Return code: 0 15:59:28 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:59:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 15:59:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:59:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:59:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 15:59:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:59:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:59:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x21c6030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23298a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2487180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24853b0>, '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 0x24862b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2486740>, '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': '00204705a9daa57ed339a427000001b9-1469140399.960441-1326953982', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:59:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 15:59:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 15:59:28 INFO - Using env: {'DISPLAY': ':0', 15:59:28 INFO - 'HOME': '/home/cltbld', 15:59:28 INFO - 'LANG': 'en_US.UTF-8', 15:59:28 INFO - 'LANGUAGE': 'en_US:en', 15:59:28 INFO - 'LOGNAME': 'cltbld', 15:59:28 INFO - 'MAIL': '/var/mail/cltbld', 15:59:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:59:28 INFO - 'MOZ_NO_REMOTE': '1', 15:59:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:59:28 INFO - 'NO_EM_RESTART': '1', 15:59:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:59:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:59:28 INFO - 'PWD': '/builds/slave/test', 15:59:28 INFO - 'SHELL': '/bin/bash', 15:59:28 INFO - 'SHLVL': '1', 15:59:28 INFO - 'TERM': 'linux', 15:59:28 INFO - 'TMOUT': '86400', 15:59:28 INFO - 'USER': 'cltbld', 15:59:28 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1469140399.960441-1326953982', 15:59:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:59:28 INFO - '_': '/tools/buildbot/bin/python'} 15:59:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:59:28 INFO - Downloading/unpacking psutil>=3.1.1 15:59:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:59:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:59:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:59:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:59:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:59:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:59:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:59:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:59:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:59:30 INFO - Installing collected packages: psutil 15:59:30 INFO - Running setup.py install for psutil 15:59:30 INFO - building 'psutil._psutil_linux' extension 15:59:30 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 15:59:31 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 15:59:31 INFO - building 'psutil._psutil_posix' extension 15:59:31 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 15:59:31 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 15:59:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:59:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:59:31 INFO - Successfully installed psutil 15:59:31 INFO - Cleaning up... 15:59:31 INFO - Return code: 0 15:59:31 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:59: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')]} 15:59:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:59:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:59: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')]} 15:59:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:59:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:59: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21c6030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23298a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2487180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24853b0>, '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 0x24862b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2486740>, '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': '00204705a9daa57ed339a427000001b9-1469140399.960441-1326953982', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:59: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 15:59: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 mozsystemmonitor==0.3 15:59:31 INFO - Using env: {'DISPLAY': ':0', 15:59:31 INFO - 'HOME': '/home/cltbld', 15:59:31 INFO - 'LANG': 'en_US.UTF-8', 15:59:31 INFO - 'LANGUAGE': 'en_US:en', 15:59:31 INFO - 'LOGNAME': 'cltbld', 15:59:31 INFO - 'MAIL': '/var/mail/cltbld', 15:59:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:59:31 INFO - 'MOZ_NO_REMOTE': '1', 15:59:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:59:31 INFO - 'NO_EM_RESTART': '1', 15:59:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:59:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:59:31 INFO - 'PWD': '/builds/slave/test', 15:59:31 INFO - 'SHELL': '/bin/bash', 15:59:31 INFO - 'SHLVL': '1', 15:59:31 INFO - 'TERM': 'linux', 15:59:31 INFO - 'TMOUT': '86400', 15:59:31 INFO - 'USER': 'cltbld', 15:59:31 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1469140399.960441-1326953982', 15:59:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:59:31 INFO - '_': '/tools/buildbot/bin/python'} 15:59:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:59:31 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:59:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:59:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:59:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:59:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:59:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:59:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:59:33 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:59:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:59:33 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:59:33 INFO - Installing collected packages: mozsystemmonitor 15:59:33 INFO - Running setup.py install for mozsystemmonitor 15:59:33 INFO - Successfully installed mozsystemmonitor 15:59:33 INFO - Cleaning up... 15:59:33 INFO - Return code: 0 15:59:33 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:59:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 15:59:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:59:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:59:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 15:59:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:59:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:59:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x21c6030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23298a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2487180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24853b0>, '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 0x24862b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2486740>, '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': '00204705a9daa57ed339a427000001b9-1469140399.960441-1326953982', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:59:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 15:59:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 15:59:33 INFO - Using env: {'DISPLAY': ':0', 15:59:33 INFO - 'HOME': '/home/cltbld', 15:59:33 INFO - 'LANG': 'en_US.UTF-8', 15:59:33 INFO - 'LANGUAGE': 'en_US:en', 15:59:33 INFO - 'LOGNAME': 'cltbld', 15:59:33 INFO - 'MAIL': '/var/mail/cltbld', 15:59:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:59:33 INFO - 'MOZ_NO_REMOTE': '1', 15:59:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:59:33 INFO - 'NO_EM_RESTART': '1', 15:59:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:59:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:59:33 INFO - 'PWD': '/builds/slave/test', 15:59:33 INFO - 'SHELL': '/bin/bash', 15:59:33 INFO - 'SHLVL': '1', 15:59:33 INFO - 'TERM': 'linux', 15:59:33 INFO - 'TMOUT': '86400', 15:59:33 INFO - 'USER': 'cltbld', 15:59:33 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1469140399.960441-1326953982', 15:59:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:59:33 INFO - '_': '/tools/buildbot/bin/python'} 15:59:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:59:33 INFO - Downloading/unpacking jsonschema==2.5.1 15:59:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:59:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:59:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:59:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:59:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:59:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:59:35 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:59:35 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:59:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:59:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:59:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:59:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:59:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:59:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:59:35 INFO - Downloading functools32-3.2.3-2.tar.gz 15:59:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:59:35 INFO - warning: no files found matching '*.txt' 15:59:35 INFO - no previously-included directories found matching 'build' 15:59:35 INFO - no previously-included directories found matching 'dist' 15:59:35 INFO - no previously-included directories found matching '.git*' 15:59:35 INFO - Installing collected packages: jsonschema, functools32 15:59:36 INFO - Running setup.py install for functools32 15:59:36 INFO - warning: no files found matching '*.txt' 15:59:36 INFO - no previously-included directories found matching 'build' 15:59:36 INFO - no previously-included directories found matching 'dist' 15:59:36 INFO - no previously-included directories found matching '.git*' 15:59:36 INFO - Successfully installed jsonschema functools32 15:59:36 INFO - Cleaning up... 15:59:36 INFO - Return code: 0 15:59:36 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:59:36 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')]} 15:59:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:59:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:59:36 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')]} 15:59:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:59:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:59:36 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 0x21c6030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23298a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2487180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24853b0>, '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 0x24862b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2486740>, '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': '00204705a9daa57ed339a427000001b9-1469140399.960441-1326953982', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:59:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 15:59:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 15:59:36 INFO - Using env: {'DISPLAY': ':0', 15:59:36 INFO - 'HOME': '/home/cltbld', 15:59:36 INFO - 'LANG': 'en_US.UTF-8', 15:59:36 INFO - 'LANGUAGE': 'en_US:en', 15:59:36 INFO - 'LOGNAME': 'cltbld', 15:59:36 INFO - 'MAIL': '/var/mail/cltbld', 15:59:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:59:36 INFO - 'MOZ_NO_REMOTE': '1', 15:59:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:59:36 INFO - 'NO_EM_RESTART': '1', 15:59:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:59:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:59:36 INFO - 'PWD': '/builds/slave/test', 15:59:36 INFO - 'SHELL': '/bin/bash', 15:59:36 INFO - 'SHLVL': '1', 15:59:36 INFO - 'TERM': 'linux', 15:59:36 INFO - 'TMOUT': '86400', 15:59:36 INFO - 'USER': 'cltbld', 15:59:36 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1469140399.960441-1326953982', 15:59:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:59:36 INFO - '_': '/tools/buildbot/bin/python'} 15:59:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:59:36 INFO - Downloading/unpacking blobuploader==1.2.4 15:59:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:59:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:59:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:59:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:59:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:59:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:59:38 INFO - Downloading blobuploader-1.2.4.tar.gz 15:59:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:59:38 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:59:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:59:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:59:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:59:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:59:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:59:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:59:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:59:38 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:59:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:59:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:59:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:59:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:59:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:59:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:59:39 INFO - Downloading docopt-0.6.1.tar.gz 15:59:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:59:39 INFO - Installing collected packages: blobuploader, requests, docopt 15:59:39 INFO - Running setup.py install for blobuploader 15:59:39 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:59:39 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:59:39 INFO - Running setup.py install for requests 15:59:39 INFO - Running setup.py install for docopt 15:59:39 INFO - Successfully installed blobuploader requests docopt 15:59:39 INFO - Cleaning up... 15:59:39 INFO - Return code: 0 15:59:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:59:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 15:59:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:59:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:59:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 15:59:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:59:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:59:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21c6030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23298a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2487180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24853b0>, '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 0x24862b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2486740>, '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': '00204705a9daa57ed339a427000001b9-1469140399.960441-1326953982', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:59:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 15:59:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:59:39 INFO - Using env: {'DISPLAY': ':0', 15:59:39 INFO - 'HOME': '/home/cltbld', 15:59:39 INFO - 'LANG': 'en_US.UTF-8', 15:59:39 INFO - 'LANGUAGE': 'en_US:en', 15:59:39 INFO - 'LOGNAME': 'cltbld', 15:59:39 INFO - 'MAIL': '/var/mail/cltbld', 15:59:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:59:39 INFO - 'MOZ_NO_REMOTE': '1', 15:59:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:59:39 INFO - 'NO_EM_RESTART': '1', 15:59:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:59:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:59:39 INFO - 'PWD': '/builds/slave/test', 15:59:39 INFO - 'SHELL': '/bin/bash', 15:59:39 INFO - 'SHLVL': '1', 15:59:39 INFO - 'TERM': 'linux', 15:59:39 INFO - 'TMOUT': '86400', 15:59:39 INFO - 'USER': 'cltbld', 15:59:39 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1469140399.960441-1326953982', 15:59:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:59:39 INFO - '_': '/tools/buildbot/bin/python'} 15:59:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:59:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:59:39 INFO - Running setup.py (path:/tmp/pip-jlOj6L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:59:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:59:40 INFO - Running setup.py (path:/tmp/pip-pEyZze-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:59:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:59:40 INFO - Running setup.py (path:/tmp/pip-gQlp3W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:59:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:59:40 INFO - Running setup.py (path:/tmp/pip-reQmZc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:59:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:59:40 INFO - Running setup.py (path:/tmp/pip-b7YoTw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:59:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:59:40 INFO - Running setup.py (path:/tmp/pip-tsvVhQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:59:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:59:40 INFO - Running setup.py (path:/tmp/pip-XEW4V7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:59:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:59:40 INFO - Running setup.py (path:/tmp/pip-IyrX_O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:59:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:59:40 INFO - Running setup.py (path:/tmp/pip-IIN1Em-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:59:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:59:40 INFO - Running setup.py (path:/tmp/pip-srsorc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:59:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:59:40 INFO - Running setup.py (path:/tmp/pip-EhvmeJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:59:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:59:40 INFO - Running setup.py (path:/tmp/pip-darABH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:59:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:59:40 INFO - Running setup.py (path:/tmp/pip-5v_P2y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:59:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:59:40 INFO - Running setup.py (path:/tmp/pip-yl0wQs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:59:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:59:40 INFO - Running setup.py (path:/tmp/pip-mqo3kG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:59:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:59:41 INFO - Running setup.py (path:/tmp/pip-NbJ057-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:59:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:59:41 INFO - Running setup.py (path:/tmp/pip-hVNBtQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:59:41 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:59:41 INFO - Running setup.py install for manifestparser 15:59:41 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:59:41 INFO - Running setup.py install for mozcrash 15:59:41 INFO - Running setup.py install for mozdebug 15:59:41 INFO - Running setup.py install for mozdevice 15:59:41 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:59:41 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:59:41 INFO - Running setup.py install for mozfile 15:59:41 INFO - Running setup.py install for mozhttpd 15:59:41 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:59:41 INFO - Running setup.py install for mozinfo 15:59:41 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:59:41 INFO - Running setup.py install for mozInstall 15:59:42 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:59:42 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:59:42 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:59:42 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:59:42 INFO - Running setup.py install for mozleak 15:59:42 INFO - Running setup.py install for mozlog 15:59:42 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:59:42 INFO - Running setup.py install for moznetwork 15:59:42 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:59:42 INFO - Running setup.py install for mozprocess 15:59:42 INFO - Running setup.py install for mozprofile 15:59:42 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:59:42 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:59:42 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:59:42 INFO - Running setup.py install for mozrunner 15:59:42 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:59:42 INFO - Running setup.py install for mozscreenshot 15:59:42 INFO - Running setup.py install for moztest 15:59:42 INFO - Running setup.py install for mozversion 15:59:42 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:59:42 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:59:42 INFO - Cleaning up... 15:59:43 INFO - Return code: 0 15:59:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:59:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 15:59:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:59:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:59:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 15:59:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:59:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:59:43 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 0x21c6030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23298a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2487180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24853b0>, '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 0x24862b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2486740>, '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': '00204705a9daa57ed339a427000001b9-1469140399.960441-1326953982', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:59:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 15:59:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:59:43 INFO - Using env: {'DISPLAY': ':0', 15:59:43 INFO - 'HOME': '/home/cltbld', 15:59:43 INFO - 'LANG': 'en_US.UTF-8', 15:59:43 INFO - 'LANGUAGE': 'en_US:en', 15:59:43 INFO - 'LOGNAME': 'cltbld', 15:59:43 INFO - 'MAIL': '/var/mail/cltbld', 15:59:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:59:43 INFO - 'MOZ_NO_REMOTE': '1', 15:59:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:59:43 INFO - 'NO_EM_RESTART': '1', 15:59:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:59:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:59:43 INFO - 'PWD': '/builds/slave/test', 15:59:43 INFO - 'SHELL': '/bin/bash', 15:59:43 INFO - 'SHLVL': '1', 15:59:43 INFO - 'TERM': 'linux', 15:59:43 INFO - 'TMOUT': '86400', 15:59:43 INFO - 'USER': 'cltbld', 15:59:43 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1469140399.960441-1326953982', 15:59:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:59:43 INFO - '_': '/tools/buildbot/bin/python'} 15:59:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:59:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:59:43 INFO - Running setup.py (path:/tmp/pip-PROf1i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:59:43 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 15:59:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:59:43 INFO - Running setup.py (path:/tmp/pip-plmouL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:59:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:59:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:59:43 INFO - Running setup.py (path:/tmp/pip-uHzvkw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:59:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 15:59:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:59:43 INFO - Running setup.py (path:/tmp/pip-IR4Cy7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:59:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:59:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:59:43 INFO - Running setup.py (path:/tmp/pip-D5slo7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:59:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 15:59:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:59:43 INFO - Running setup.py (path:/tmp/pip-xhV7qE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:59:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 15:59:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:59:43 INFO - Running setup.py (path:/tmp/pip-UePqGW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:59:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 15:59:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:59:43 INFO - Running setup.py (path:/tmp/pip-MNcdHm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:59:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 15:59:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:59:43 INFO - Running setup.py (path:/tmp/pip-MJCfxZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:59:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 15:59:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:59:43 INFO - Running setup.py (path:/tmp/pip-V1SeyL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:59:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:59:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:59:43 INFO - Running setup.py (path:/tmp/pip-5Ci6SN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:59:44 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 15:59:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:59:44 INFO - Running setup.py (path:/tmp/pip-opFANe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:59:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 15:59:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:59:44 INFO - Running setup.py (path:/tmp/pip-9CFtMC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:59:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 15:59:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:59:44 INFO - Running setup.py (path:/tmp/pip-PbzWtO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:59:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 15:59:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:59:44 INFO - Running setup.py (path:/tmp/pip-B7eYFi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:59:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 15:59:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:59:44 INFO - Running setup.py (path:/tmp/pip-pNan7c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:59:44 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 15:59:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:59:44 INFO - Running setup.py (path:/tmp/pip-aN5dLI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:59:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 15:59:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:59:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:59:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 15:59:44 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:59:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:59:44 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:59:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:59:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:59:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:59:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:59:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:59:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:59:46 INFO - Downloading blessings-1.6.tar.gz 15:59:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:59:46 INFO - Installing collected packages: blessings 15:59:46 INFO - Running setup.py install for blessings 15:59:46 INFO - Successfully installed blessings 15:59:46 INFO - Cleaning up... 15:59:46 INFO - Return code: 0 15:59:46 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:59:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:59:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:59:46 INFO - Reading from file tmpfile_stdout 15:59:46 INFO - Current package versions: 15:59:46 INFO - argparse == 1.2.1 15:59:46 INFO - blessings == 1.6 15:59:46 INFO - blobuploader == 1.2.4 15:59:46 INFO - docopt == 0.6.1 15:59:46 INFO - functools32 == 3.2.3-2 15:59:46 INFO - jsonschema == 2.5.1 15:59:46 INFO - manifestparser == 1.1 15:59:46 INFO - mozInstall == 1.12 15:59:46 INFO - mozcrash == 0.17 15:59:46 INFO - mozdebug == 0.1 15:59:46 INFO - mozdevice == 0.48 15:59:46 INFO - mozfile == 1.2 15:59:46 INFO - mozhttpd == 0.7 15:59:46 INFO - mozinfo == 0.9 15:59:46 INFO - mozleak == 0.1 15:59:46 INFO - mozlog == 3.3 15:59:46 INFO - moznetwork == 0.27 15:59:46 INFO - mozprocess == 0.23 15:59:46 INFO - mozprofile == 0.28 15:59:46 INFO - mozrunner == 6.12 15:59:46 INFO - mozscreenshot == 0.1 15:59:46 INFO - mozsystemmonitor == 0.3 15:59:46 INFO - moztest == 0.7 15:59:46 INFO - mozversion == 1.4 15:59:46 INFO - psutil == 3.1.1 15:59:46 INFO - requests == 1.2.3 15:59:46 INFO - wsgiref == 0.1.2 15:59:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:59:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 15:59:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:59:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:59:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 15:59:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:59:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:59:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21c6030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23298a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2487180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24853b0>, '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 0x24862b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2486740>, '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': '00204705a9daa57ed339a427000001b9-1469140399.960441-1326953982', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:59:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 15:59:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:59:46 INFO - Using env: {'DISPLAY': ':0', 15:59:46 INFO - 'HOME': '/home/cltbld', 15:59:46 INFO - 'LANG': 'en_US.UTF-8', 15:59:46 INFO - 'LANGUAGE': 'en_US:en', 15:59:46 INFO - 'LOGNAME': 'cltbld', 15:59:46 INFO - 'MAIL': '/var/mail/cltbld', 15:59:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:59:46 INFO - 'MOZ_NO_REMOTE': '1', 15:59:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:59:46 INFO - 'NO_EM_RESTART': '1', 15:59:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:59:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:59:46 INFO - 'PWD': '/builds/slave/test', 15:59:46 INFO - 'SHELL': '/bin/bash', 15:59:46 INFO - 'SHLVL': '1', 15:59:46 INFO - 'TERM': 'linux', 15:59:46 INFO - 'TMOUT': '86400', 15:59:46 INFO - 'USER': 'cltbld', 15:59:46 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1469140399.960441-1326953982', 15:59:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:59:46 INFO - '_': '/tools/buildbot/bin/python'} 15:59:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:59:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 15:59:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 15:59:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 15:59:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 15:59:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 15:59:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 15:59:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 15:59:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 15:59:47 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 15:59:47 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 15:59:47 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 15:59:47 INFO - Cleaning up... 15:59:47 INFO - Return code: 0 15:59:47 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:59:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 15:59:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:59:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:59:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 15:59:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:59:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:59:47 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 0x21c6030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23298a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2487180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24853b0>, '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 0x24862b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2486740>, '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': '00204705a9daa57ed339a427000001b9-1469140399.960441-1326953982', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:59:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 15:59:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 15:59:47 INFO - Using env: {'DISPLAY': ':0', 15:59:47 INFO - 'HOME': '/home/cltbld', 15:59:47 INFO - 'LANG': 'en_US.UTF-8', 15:59:47 INFO - 'LANGUAGE': 'en_US:en', 15:59:47 INFO - 'LOGNAME': 'cltbld', 15:59:47 INFO - 'MAIL': '/var/mail/cltbld', 15:59:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:59:47 INFO - 'MOZ_NO_REMOTE': '1', 15:59:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:59:47 INFO - 'NO_EM_RESTART': '1', 15:59:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:59:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:59:47 INFO - 'PWD': '/builds/slave/test', 15:59:47 INFO - 'SHELL': '/bin/bash', 15:59:47 INFO - 'SHLVL': '1', 15:59:47 INFO - 'TERM': 'linux', 15:59:47 INFO - 'TMOUT': '86400', 15:59:47 INFO - 'USER': 'cltbld', 15:59:47 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1469140399.960441-1326953982', 15:59:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:59:47 INFO - '_': '/tools/buildbot/bin/python'} 15:59:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:59:47 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:59:47 INFO - Cleaning up... 15:59:47 INFO - Return code: 0 15:59:47 INFO - Running post-action listener: _resource_record_post_action 15:59:47 INFO - Running post-action listener: _start_resource_monitoring 15:59:47 INFO - Starting resource monitoring. 15:59:47 INFO - [mozharness: 2016-07-21 22:59:47.316656Z] Finished create-virtualenv step (success) 15:59:47 INFO - [mozharness: 2016-07-21 22:59:47.317090Z] Running install step. 15:59:47 INFO - Running pre-action listener: _resource_record_pre_action 15:59:47 INFO - Running main action method: install 15:59:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:59:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:59:47 INFO - Reading from file tmpfile_stdout 15:59:47 INFO - Detecting whether we're running mozinstall >=1.0... 15:59:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:59:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:59:47 INFO - Reading from file tmpfile_stdout 15:59:47 INFO - Output received: 15:59:47 INFO - Usage: mozinstall [options] installer 15:59:47 INFO - Options: 15:59:47 INFO - -h, --help show this help message and exit 15:59:47 INFO - -d DEST, --destination=DEST 15:59:47 INFO - Directory to install application into. [default: 15:59:47 INFO - "/builds/slave/test"] 15:59:47 INFO - --app=APP Application being installed. [default: firefox] 15:59:47 INFO - mkdir: /builds/slave/test/build/application 15:59:47 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'] 15:59:47 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 16:00:04 INFO - Reading from file tmpfile_stdout 16:00:04 INFO - Output received: 16:00:04 INFO - /builds/slave/test/build/application/firefox/firefox 16:00:04 INFO - Running post-action listener: _resource_record_post_action 16:00:04 INFO - [mozharness: 2016-07-21 23:00:04.023376Z] Finished install step (success) 16:00:04 INFO - [mozharness: 2016-07-21 23:00:04.023653Z] Running run-tests step. 16:00:04 INFO - Running pre-action listener: _resource_record_pre_action 16:00:04 INFO - Running main action method: run_tests 16:00:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:00:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:00:04 INFO - Python 2.7.3 16:00:04 INFO - Return code: 0 16:00:04 INFO - grabbing minidump binary from tooltool 16:00:04 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')]} 16:00:04 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 0x24853b0>, '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 0x24862b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2486740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:00:04 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 16:00:04 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 16:00:04 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 16:00:04 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:00:04 INFO - Return code: 0 16:00:04 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:00:04 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:00:04 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:00:04 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:00:04 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:00:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JYRAzwcmSci5-mG6CSINfg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-016', '--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 16:00:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/JYRAzwcmSci5-mG6CSINfg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-016 --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 16:00:04 INFO - Using env: {'DISPLAY': ':0', 16:00:04 INFO - 'HOME': '/home/cltbld', 16:00:04 INFO - 'LANG': 'en_US.UTF-8', 16:00:04 INFO - 'LANGUAGE': 'en_US:en', 16:00:04 INFO - 'LOGNAME': 'cltbld', 16:00:04 INFO - 'MAIL': '/var/mail/cltbld', 16:00:04 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:00:04 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:00:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:00:04 INFO - 'MOZ_NO_REMOTE': '1', 16:00:04 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:00:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:00:04 INFO - 'NO_EM_RESTART': '1', 16:00:04 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:00:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:00:04 INFO - 'PWD': '/builds/slave/test', 16:00:04 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:00:04 INFO - 'SHELL': '/bin/bash', 16:00:04 INFO - 'SHLVL': '1', 16:00:04 INFO - 'TERM': 'linux', 16:00:04 INFO - 'TMOUT': '86400', 16:00:04 INFO - 'USER': 'cltbld', 16:00:04 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1469140399.960441-1326953982', 16:00:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:00:04 INFO - '_': '/tools/buildbot/bin/python'} 16:00:04 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JYRAzwcmSci5-mG6CSINfg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-016', '--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 16:00:04 INFO - mozversion application_buildid: 20160721144820 16:00:04 INFO - mozversion application_changeset: 10501352b0eef4ecf19dd4d5b1d307ebb212cf5e 16:00:04 INFO - mozversion application_display_name: Nightly 16:00:04 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:00:04 INFO - mozversion application_name: Firefox 16:00:04 INFO - mozversion application_remotingname: firefox 16:00:04 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 16:00:04 INFO - mozversion application_vendor: Mozilla 16:00:04 INFO - mozversion application_version: 50.0a1 16:00:04 INFO - mozversion platform_buildid: 20160721144820 16:00:04 INFO - mozversion platform_changeset: 10501352b0eef4ecf19dd4d5b1d307ebb212cf5e 16:00:04 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 16:00:04 INFO - mozversion platform_version: 50.0a1 16:00:04 INFO - using testdate: 1469142004 16:00:04 INFO - actual date: 1469142004 16:00:04 INFO - starting webserver on 'localhost:38010' 16:00:04 INFO - SUITE-START | Running 2 tests 16:00:04 INFO - TEST-START | dromaeo_css 16:00:04 INFO - operating with platform_type : linux_ 16:00:04 INFO - Initialising browser for dromaeo_css test... 16:00:04 INFO - TEST-INFO | started process 11347 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpzCfUIt/profile http://localhost:38010/getInfo.html) 16:00:07 INFO - PROCESS | 11347 | JavaScript error: , line 0: uncaught exception: undefined 16:00:08 INFO - PROCESS | 11347 | __metrics Screen width/height:1600/1200 16:00:08 INFO - PROCESS | 11347 | colorDepth:24 16:00:08 INFO - PROCESS | 11347 | Browser inner width/height: 1024/697 16:00:08 INFO - PROCESS | 11347 | __metrics 16:00:09 INFO - PROCESS | 11347 | 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" 16:00:09 INFO - PROCESS | 11347 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 58: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:00:09 INFO - PROCESS | 11347 | console.error: PushService: 16:00:09 INFO - PROCESS | 11347 | stateChangeProcessEnqueue: Error transitioning state 16:00:09 INFO - PROCESS | 11347 | UnknownError 16:00:10 INFO - TEST-INFO | 11347: exit 0 16:00:10 INFO - Browser initialized. 16:00:10 INFO - Running cycle 1/1 for dromaeo_css test... 16:00:10 INFO - TEST-INFO | started process 11426 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpzCfUIt/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:00:22 INFO - PROCESS | 11426 | RSS: Main: 159264768 16:00:22 INFO - PROCESS | 11426 | 16:01:13 INFO - PROCESS | 11426 | 1469142073926 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] 16:03:26 INFO - PROCESS | 11426 | Cycle 1(1): loaded http://localhost:38010/tests/dromaeo/cssquery-dojo.html (next: http://localhost:38010/tests/dromaeo/cssquery-ext.html) 16:03:27 INFO - PROCESS | 11426 | RSS: Main: 288223232 16:03:27 INFO - PROCESS | 11426 | 16:06:30 INFO - PROCESS | 11426 | Cycle 1(1): loaded http://localhost:38010/tests/dromaeo/cssquery-ext.html (next: http://localhost:38010/tests/dromaeo/cssquery-jquery.html) 16:06:30 INFO - PROCESS | 11426 | RSS: Main: 247894016 16:06:30 INFO - PROCESS | 11426 | 16:09:33 INFO - PROCESS | 11426 | Cycle 1(1): loaded http://localhost:38010/tests/dromaeo/cssquery-jquery.html (next: http://localhost:38010/tests/dromaeo/cssquery-mootools.html) 16:09:33 INFO - PROCESS | 11426 | RSS: Main: 187445248 16:09:33 INFO - PROCESS | 11426 | 16:12:35 INFO - PROCESS | 11426 | Cycle 1(1): loaded http://localhost:38010/tests/dromaeo/cssquery-mootools.html (next: http://localhost:38010/tests/dromaeo/cssquery-prototype.html) 16:12:36 INFO - PROCESS | 11426 | RSS: Main: 197840896 16:12:36 INFO - PROCESS | 11426 | 16:15:39 INFO - PROCESS | 11426 | Cycle 1(1): loaded http://localhost:38010/tests/dromaeo/cssquery-prototype.html (next: http://localhost:38010/tests/dromaeo/cssquery-yui.html) 16:15:40 INFO - PROCESS | 11426 | RSS: Main: 188047360 16:15:40 INFO - PROCESS | 11426 | 16:18:43 INFO - PROCESS | 11426 | Cycle 1(1): loaded http://localhost:38010/tests/dromaeo/cssquery-yui.html (next: http://localhost:38010/tests/dromaeo/cssquery-dojo.html) 16:18:43 INFO - PROCESS | 11426 | RSS: Main: 286187520 16:18:43 INFO - PROCESS | 11426 | 16:18:43 INFO - PROCESS | 11426 | __start_tp_report 16:18:43 INFO - PROCESS | 11426 | _x_x_mozilla_page_load 16:18:43 INFO - PROCESS | 11426 | _x_x_mozilla_page_load_details 16:18:43 INFO - PROCESS | 11426 | |i|pagename|runs| 16:18:43 INFO - PROCESS | 11426 | |0;dojo.html;2551.4897020595863;2553.8978440862365;2591.080166540877;2598.1945596864975;2601.3236558494787;4570.97714511427;4637.605803506706;4665.276882083278;4701.106789709957;4711.646626503013;4540.636749060075;4626.606738427237;4667.953320466794;4668.603168730662;4692.343071969918;4254.234237837188;4310.07333423314;4316.460442444694;4326.5673432656795;4336.04606986464;4297.076128632343;4346.043870348518;4362.105768317488;4374.212641724488;4423.0711550574415;4372.97813510932;4387.210302145612;4387.3857659927635;4406.647468202536;4414.801333939964;4199.475065616798;4201.34879093741;4210.010647497836;4296.2696341621995;4354.303311470149;4196.706230563862;4231.217224813419;4239.915201695949;4248;4341.45731783527;4639.211334073216;4680.695754775928;4695.131400690883;4706.105839890444;4773.260144677581;4994.500549945013;5012.72430016349;5017.172166592508;5041.773120209599;5064;4846.248831431134;4934.5312195341385;4961.727105009223;4996.200607902718;5073.6702114362815;4634.86095417138;4664.973705784722;4705.811767529295;4708.952910470852;4713.387259656222;5077.314562534085;5159.871003224949;5164.48355164488;5168.121419358718;5169.638125331266;4717.8112875485285;4803.831865884677;4832.49258827818;4851.102546028996;4913.65604407688;4967.103786431477;4991.276264941564;5001.849944501598;5006.224035274466;5007.4491805901325;4890;4897.583705385011;4907.607391408679;4918.139325618003;4968.354113965162;4793.49668284832;4823.469418363977;4827.396575428072;4922.90154196914;4927.211646136601;4244.448221731217;4249.660027197879;4278.122984788111;4282.914341713148;4289.099289149313;3700.611435799256;3714.9071273218387;3732.346839303111;3742.9812850936114;3848.4034974578426;4312.310030395121;4319;4321.913561728811;4360.7819609019425;4365.388845561624;5028.698278103326;5039.647224694309;5039.949600503948;5043.84868453947;5085.898282034339;4199.475065616798;4267.914641707211;4291.721038132511;4311.396404503372;4356.041670832412;4554.453465584151;4568.588827005586;4606.838760643362;4610.985583171697;4611.608013318905;4609.7695115244105;4618.3143152748335;4646.395968524138;4655.8603241903475;4720.103180395714;4867.683600565952;4913.336699545658;4984.053029924303;5031.119554077948;5040.949590504048;4647.837325693585;4652.395188625457;4671.602913752233;4696.765161741968;4714.81140754366;4949.529794669501;4951.529604687549;4951.579115775199;4956.281339205867;5006.299118123393;4818.8795280118275;4879.853604391874;4991.825286114969;5026.045051440215;5077.720725359993;4756.5956893664425;4757.619390448686;4765.189917713928;4820.373351464287;4864.416270047617;4783.091212669582;4808;4815.3499277597075;4817.807287708592;4867.075255701406;4613.284940834176;4728.62171026324;4762.404699412574;4806.2310030394965;4812.711237325771;4852.2479015752615;4887.315775791321;4904;4929.507049295042;4957.975210123926;4814.061258054646;4819.638527110551;4823.276508523609;4861.635377346643;4866.197077482176 16:18:43 INFO - PROCESS | 11426 | |1;ext.html;540.1948198353646;584.436019241432;590.7194082810663;593.8604427959426;595.5682130455419;38879.61120388795;40073.79963100184;40204.19591608166;45144;45338;41626.79186604063;42092.57907420925;42140;42155;42157.367639485354;7624.6187690615525;7802.063752349726;7804.063512378494;7812.10160831506;7822.100458447266;7792.142864284924;7862.567558784264;7866.331361834251;7872.921270787276;7873;7891.763247102596;7917.049954005527;7917.366610671162;7922.326602238817;7945.920540794605;7825.843483130306;7843.058832940055;7876.960615196945;7883.132855385932;7896.486728362638;7821.2960833524685;7866.803329916741;7880.684772609089;7886.526808391457;7887;39604.40593391102;39905.40141897874;39910.401343979865;39981.20037599232;39996.40005399921;2906;2949.631296087989;2990.626171728534;3003.639563252402;3050;29453;29845.552316715268;29942.401151977057;30162.396752065055;31121.8443907779;3045.1321373408473;3172.080891060898;3340.448825943716;3359.4456914609304;3417.026147547937;270798;272218;272685;272848.63575682195;273437;32294;33096.66903330984;33455;56964.43035569674;57481.425185747605;34565;34796.30407391889;34916.127096822784;34919.301613968084;34953.300933981685;12320.322382268881;12350.62948111558;12358;12359.32023738685;12372.381380930918;10763.892361076445;10880.12958963281;10890.836637450444;10900.96440838119;10902.236843420884;7927.8810817837775;7949.284564389232;7956.283934445928;8026.277635012877;8040.437169398203;3077.6768439313996;3087.6294844618787;3130.733887619532;3143.8113713176826;3171.349873275973;855.7774978505508;895.394402289335;902.4540153207215;953.3755390219418;974.0016483104732;28608.570871436947;28778.28054298659;28859.567106493418;29383;29444.852775735984;810.5217921426387;962.499500259861;966.4925913895261;997.1922742578535;1016.1159790981893;14452.349644266033;15239.390424383122;15353.846461535464;15501.689966200613;15741.449049283448;387226;388749;389385;390800;391535;50861.49138508642;51271.48728512742;51301.23048154281;51417;51468.74265628723;1029.4235228272191;1097.9231453798318;1122.5622007417114;1134.7163209197702;1156.2542160306555;26395.208143755717;29809.85095074511;30282.697173028868;30517;30725.23186920345;43992.34011489766;44528.55471445244;44560.55439445564;44577.331340029275;44722;299486;299709;301073;301255;302508;25482.362940926625;26171.345716357242;26246.34384140412;26309.4738105233;27993.580096298163;40370;40395.394069089576;42183.78908105563;43002.35496467493;43772.78113609539;36333.27333453369;36423.453648194765;36562.45156322711;36988.44517332188;37439.06889776534;36614.26771464502;37102.257954840214;37262;37387.6261237395;38019.61980380161;36714.8164259177;36763.816180919996;36769;36808.81595592005;37788.62211377961;31545.8422707885;33788.994390586115;34091.31817363589;34155.48766768451;34616.65383346134;25876.482470350864;25935;26178;26356.341091472867;26575.734242657327 16:18:43 INFO - PROCESS | 11426 | |2;jquery.html;336.53558089836025;355.50762194360817;362.9909252268693;369.69684858416093;382.6766382406867;12601.369931503423;16211;16382.262798174066;18270.360537381195;18412.723809142874;17290.913545432286;17593.82406175941;17715;17780.64438711225;17788.288468461247;5026.924596131061;5267.47325267474;5502.642328248651;5529.474699903523;5636.04187288162;3506.684398404143;5538.556915446755;5571.164325351209;5573.164025396182;5588.748506317205;5809.651420914759;5876.08920617305;5895.587308888379;5904.498117659982;5909.9704501477645;5467.917981230285;5507.311586051744;5525.0883604205255;5861.238039054917;5967.940320596804;5279.841604751864;5281.36623605166;5283.947160528384;5287.656302340336;5290.100682883881;11944.522219111224;11966.82049769254;12302.81545776814;12336.074794390366;12494.375281235902;1735.1237624999294;1798.2087881332302;1817.318505560415;1961.9411417657493;2197.340797760681;8778.736637900873;9055.275577953747;9065.728028159167;9329.346945713734;9561.952190239073;1679.4793613979705;1686.907220102894;1710.6835235481476;1748.0997286397514;1786.7051936430473;66262;71449;71523;71548.28451715468;73138.63430682813;8249.505029698237;8529.275011623957;8632.223099921037;8684;9715.514224288758;11572.074234061254;11664.066874650007;11667.59163429276;11671.416429178507;11675.007624351856;6589.472842172615;6668.466522678174;6719.361660642231;6782.253952065269;6822.522423430411;6504.772332968418;6589;6736.157980252468;6738.157730283715;6787.321267873173;3672.4674922136674;3729.776213427149;3747.0819649186124;3838.5777564467885;3843.7117216208335;1721.4749501402193;1760.8063113057553;1769.539919620882;1784.0722824131637;1804.5218017225445;798.9161138080534;811.2252798577463;871.172386233081;871.7384784564605;925.5048549026238;16816.57958551046;17008.404705835237;17522.299108035535;17655;18052.458426247234;787.4409169489612;813.4387272781762;837.530982649706;848.3552500099969;886.4149661223562;9383.343165978316;9745.805083898282;9794.706158815105;9795.657151999645;9900.55447504864;368521;388893;389575;390683;390884;75753;80116;88179;91476.22452091513;93849;884.4958373726914;933.1461712533091;934.1032608695729;940.4733349324264;945.952702364879;15470.303836327617;16099.75850362269;16828.66342673164;16927.32290708407;17305.65388692194;35567;38959.80520097382;40307.193856122125;41522.602061731784;41527.58472415237;52276.21585676142;53882.73058634682;54005.45994540004;54347.72826135844;54861;7158.785236442915;7578.469507134668;7652.349550288288;7671.61641917913;7784.494007889469;9724.27067969891;11892.227005244631;12186.146969712036;12351.135420520477;12408.875911240772;9564.4739539326;9782.168515676247;10148.797024059626;10162.89837101649;10582.365058096539;9999.950000249952;10321.74195645115;10400.27198096126;10887.237893347707;10970.177236707439;10056.798864022825;10155.64455244063;10233.74415639615;10412.947935260276;10536.209784266057;7504.887426688713;7695.807604809925;7772.883406749016;7799.571023593757;7923.603819809102;8937.821243575223;9967.202623790225;9977.20182385393;10263.076323130954;10525.9473702631 16:18:43 INFO - PROCESS | 11426 | |3;mootools.html;1265.784816581181;1384.598466444731;1384.9792253116204;1506.4049700368355;1729.1181497436312;6745.494087943405;7689.154193038768;7952.165022672622;7959.920400795991;8220.506769593814;8027.157148499411;8090.878636820453;8092.0694120176195;8092.393070519705;8100.4734692244965;2208.8012078912893;2236.944076398095;2237.3959031061604;2237.496563273267;2238.9888050559725;2146.5170336674278;2192.550527141932;2222.4777177363308;2224.232639739287;2224.555088982202;2194.122351059573;2195.769444208359;2199.49411635324;2203.063697928382;2231.542533780579;2144.2066435418997;2160.8487405881597;2165.7725938776357;2166.458385403649;2172.663237198237;2115.1962254343407;2137.487003119256;2146.1308170191096;2152.300502336747;2155.3103007037753;7910.960445197795;8023.638936247883;8041.477303975223;8090.757277281691;8122.553259570721;4555.74943378114;4751.619870410359;4760.476347601796;4767.880802979918;4768.117898188846;2749.931251718723;2847.117393607988;2882.4523340565434;2892.0311695582004;2899.130260921715;841.142035124176;860.5525126934022;869.3088994249587;874.9168828961239;885.7342797160826;10774.461276936201;10835.945820270848;10857.891421085767;10874.72813179669;10882.29265097766;3888.125171836314;4034.293998550242;4105.568915263914;4187.162567486454;4195.098053918422;8623.956880215685;8627.266682331947;8627.61175747093;8627.741167764978;8631.611577479029;4579.473360563577;4585.243434833282;4594.104149690778;4597.7241365518175;4603.815847366068;3645.708343332527;3727.869524566627;4144.979275103666;4245.766482843412;4248.065425606362;3286.6713328666942;3341.983290083534;3427.691507764313;3434.261633748756;3435.879744208991;967.5569187800859;1003.6888564545014;1004.9748756281006;1012.8835183953793;1021.4739409204266;660.7687309441657;709.4395427612128;794.8410317936435;814.5682788122305;843.7300063979591;5096.23556466533;5199.610029247821;5222.477752224747;5312.73436328182;5418.970395624898;388.73177507520285;391.44610376317195;392.8840991907394;395.2648074581277;400.8156248125836;3631.5097461842843;3731.2164445466215;3737.159139193714;3766.6233376662112;3839.347310957144;9607.327487075978;9962.701118966443;9984.251181161297;10066.69799906004;10089.445080520494;9766.902330976742;9816.656417025513;9857.408555486694;9886.357386769838;9920.751981200383;7542;7609;7618.352440042659;7622.5045372051745;7633.847323053508;5169.81905633301;5180.792768289226;5191.61062920275;5252.343457067866;5479.780808767605;8943.955280223558;9090.090990900857;9177.449353038839;9232.076792320715;9345.392549484262;9135.954320228357;9205.861912071457;9296.58165382573;9366.250699943852;9376.57805398745;5486.423925487926;6603.570767900071;6703.631300278532;6710.932890671031;6719.496037797283;7415.592142432218;7725.381969442319;7755.302022817974;7963.084245311632;7967.442279040411;6981.581105133709;6997.1253593300835;7020.508564400443;7209.026781384446;7234.927650723636;6845.794626161223;6960.582365058112;6963.199232088375;7123.679434425359;7161.319674630901;6194.566380353332;6259.96870015647;6277.686115694289;6307.495400368051;6312.905306420499;4400.537943515885;4455.242608756584;4576.633869290382;4581.977090114528;4614.884627884329;4643.442786865598;4694.530546945278;4715.575598196178;4737.478877323423;4931.679440836478 16:18:43 INFO - PROCESS | 11426 | |4;prototype.html;294.24966335843607;299.72575093789186;300.5461752753342;304.306181905256;311.56069941382657;4580.47324557676;4744.268342055151;4762.119007983517;4782.258749893767;4785.330053792468;4580.862574122773;4689.695169813968;4700.224462963615;4710.788014539346;4719.244920812671;3743.457198706186;3744.8314825832767;3753.3806921858;3755.8497660093562;3762.9059273518105;3548.449990251515;3619.3666108431053;3762.680172185354;3766.1902690921447;3771.849126034955;3708.258348330331;3730.421784623374;3732.9253414931823;3733.6079711630296;3738.2336620992633;3712.6658600726064;3718.0890681783135;3719.888403347904;3725.646063623965;3737.4580685800543;3290.161008942728;3297.76915615907;3302.3230237801436;3304.6695330467;3308.7353011759;4463.464384273908;4615.676902616819;4629.189891768927;4630.652701047435;4642.51253618372;4204.66362690984;4210.663146948237;4231.640310573605;4234.5765423457715;4240.363945408182;4909.263610458424;4917.286993385939;4924.113659541281;4946.3817022872145;4949.678270912379;3419.9829000855084;3529.435290353557;3536.1159710072484;3549.7337699672626;3557.4663800429885;5136.332276804029;5170.767315470775;5177.948220517785;5183.870403239912;5193.870153246124;3605.4772058051435;3611.1694310308776;3613.187032917625;3614.222942067468;3617.439296908983;3485.860565577399;3655.433407821739;3655.5065066216252;3663.4871118043493;3669.9816500917323;4169.207850508455;4178.561251068655;4210.410542524049;4221.409002739619;4223.345381465878;4984.900301994012;4996.701517302082;5003.54968052877;5012.373453318335;5016.648834581544;1191.2078467818976;1214.3381856888018;1245.819356193365;1249.5501619417003;1264.8482182138202;846.6401779243797;856.5117882806865;875.4047247871381;878.6178012564554;880.0935036911991;4328.285832837547;4333.241682705514;4341.131773645284;4381.737095774264;4388.648908087409;5025.899482010413;5043.041822053798;5045.8738531537;5051.030098261681;5062.949370506321;4347.652187824967;4356.75832387768;4367;4370.475542934868;4386.342048692722;2299.9535211478737;2329.499157681073;2397.2739627468195;2403.471236328005;2406.89168987396;4176.955761059735;4207.116505533811;4208.410822484854;4226.309476209541;4264.187720218956;4575.313702944519;4600.585947264763;4603.240465323184;4616.330632058332;4629.213033784265;4032.6169013943627;4113.938290925638;4184.891003883912;4210.726302790309;4231.322988321854;4463.687541872037;4472.686911916135;4506.774661266989;4516.6160876325885;4517.751523666098;4435.711678740871;4436.467623885154;4439.755813430293;4443.622292105208;4450.198964186348;3890.844630864173;4189.895252618709;4190.539040705459;4204.306289462327;4209.515905670887;3294.5717056782464;3296.967030329762;3299.307145499472;3300.1914530940076;3317.8672853085604;4484.3318225655685;4494.415725955605;4504.3919070925;4505.5719706627815;4850.248211527078;4723.385959825201;4971.453140154653;4979.4065898912;4981.701097934136;5003.124453220745;4976.178930476431;4977.576905963034;4985.376827896513;4999.300097986212;5001.599872010303;4742.668013239178;4817.349657796152;4916.311716359641;4932.556069953722;4936.407631084293;4953.331300274417;4957.925631115464;4961.925571116364;4963.205887058053;4967.279744437109;4852.369192005017;4859.829905953275;4890.755462226945;4900.313956046085;4901.754912254444 16:18:43 INFO - PROCESS | 11426 | |5;yui.html;537.2049366936935;547.2284079447978;597.3787261248302;614.0543562913114;626.3736263736264;11788.17482776206;12314.076444266693;12650.114491985565;12707.23756574605;12960.546380876647;12358.567450139248;12498.93750531246;12548.435320410581;12584.874151258484;12591.496340146383;645.8579112595221;771.9729809456657;783.9451238413313;791.7703865878888;793.115676021235;763.6067425275987;789.8459800338936;790.1269097647104;794.8171920458298;796.303234669664;828.5153185386556;832.2509741232878;832.5712258187039;832.5837081459271;832.662771577509;668.4752469311616;826.5247482697473;826.574314228173;826.8511667899776;826.9503829770202;588.1618693361985;603.7373742422061;604.0606856338392;604.4771272848998;605.1044454207753;12171.0871684624;12233.266004039786;12251.69370765729;12256;12262.080343974238;12124.515019399214;12209.267443953391;12435.316057616827;12441.626751197478;12768.61694149177;11506.597269095626;11812.704682382922;11846.644600661994;11854.822177667256;12068.155229133965;4033.81847816846;4085.3667681509414;4128.6284234419045;4167.333226683746;4213.178430206111;59634.40365596332;60397.79204415931;61220.081698774105;62269;62478.37521624771;9660.855087173699;9800.11798938099;9946.900530994742;10125.240606954509;10190.898091019144;17758.46724598264;18028.819711802975;18046.63906721858;18058.458246252634;18074.63850722978;6340;6358.682065896686;6455.870882582322;6590.736370545221;6618.338166183439;3574.4995700601935;3600.6039335673054;3644.835982380799;3655.3968595182027;3693.113652723327;2544.4275038116275;2653.031643450127;2660.255128564005;2662.7470390312888;2663.3741070848337;453.0621613260585;453.150343106675;456.83325586161476;457.83517933543897;464.7374233558029;462.52048906342185;468.82184769787943;475.8263233919594;499.92751051098116;523.6596212461855;6529.477641788645;6632.336766323329;6703.832404189934;6961.37347638715;7118.430525557943;437.7570448401168;507.2619338861947;508.6032894342419;513.2429666242263;523.8847453560211;3273.8228184854543;3348.2968576599187;3372.1401042733946;3387.932241355159;3459.2562599040825;56562;57030;91413.08586914046;96726.51636741772;101472;75239;82462.58768706118;83445.16554834496;85044.57477712695;85594;68186.65906670534;69476.6526167376;73762.26237737661;75568.62215688886;76005;7874.173211812677;8058.194180581895;8149.171954959162;8695.86956195645;8751.781205469913;43023.354649679655;43325;43729;44501.33248001218;44869.775651121534;59359.40640593539;60893.69553152383;62086;62566.687166563876;64888.35111649012;10865.728356791144;11284.548618055185;11354.59759777775;11708.063354931415;11744.823827642422;11204.775904482029;11263.605773797877;11403.771924561297;11735.295882247092;12174.695632609255;11524.366159860952;11750.353730544903;11900.642980710247;12438.19151755133;12465.93767031159;11046.889531104585;11252.943735281271;11324.150688698215;11859.466324015268;11881;11118.110551155727;11175.329480231212;11233.494492747683;11348.432578371214;11556.47995840206;9331.113544213289;9362.539897187138;9860.852087218555;9887.456189909624;9906.554205060645;9557.61769529211;9876.654317098866;10013.136044811088;10072.446015469219;10094.24293178 16:18:43 INFO - PROCESS | 11426 | __end_tp_report 16:18:43 INFO - PROCESS | 11426 | __start_cc_report 16:18:43 INFO - PROCESS | 11426 | _x_x_mozilla_cycle_collect,1296 16:18:43 INFO - PROCESS | 11426 | __end_cc_report 16:18:43 INFO - PROCESS | 11426 | __startTimestamp1469143123530__endTimestamp 16:18:43 INFO - PROCESS | 11426 | 16:18:43 INFO - PROCESS | 11426 | ------- Summary: start ------- 16:18:43 INFO - PROCESS | 11426 | Number of tests: 6 16:18:43 INFO - PROCESS | 11426 | 16:18:43 INFO - PROCESS | 11426 | [#0] dojo.html Cycles:165 Average:4610.55 Median:4713.39 stddev:476.47 (10.1%) stddev-sans-first:449.71 16:18:43 INFO - PROCESS | 11426 | Values: 2551.5 2553.9 2591.1 2598.2 2601.3 4571.0 4637.6 4665.3 4701.1 4711.6 4540.6 4626.6 4668.0 4668.6 4692.3 4254.2 4310.1 4316.5 4326.6 4336.0 4297.1 4346.0 4362.1 4374.2 4423.1 4373.0 4387.2 4387.4 4406.6 4414.8 4199.5 4201.3 4210.0 4296.3 4354.3 4196.7 4231.2 4239.9 4248.0 4341.5 4639.2 4680.7 4695.1 4706.1 4773.3 4994.5 5012.7 5017.2 5041.8 5064.0 4846.2 4934.5 4961.7 4996.2 5073.7 4634.9 4665.0 4705.8 4709.0 4713.4 5077.3 5159.9 5164.5 5168.1 5169.6 4717.8 4803.8 4832.5 4851.1 4913.7 4967.1 4991.3 5001.8 5006.2 5007.4 4890.0 4897.6 4907.6 4918.1 4968.4 4793.5 4823.5 4827.4 4922.9 4927.2 4244.4 4249.7 4278.1 4282.9 4289.1 3700.6 3714.9 3732.3 3743.0 3848.4 4312.3 4319.0 4321.9 4360.8 4365.4 5028.7 5039.6 5039.9 5043.8 5085.9 4199.5 4267.9 4291.7 4311.4 4356.0 4554.5 4568.6 4606.8 4611.0 4611.6 4609.8 4618.3 4646.4 4655.9 4720.1 4867.7 4913.3 4984.1 5031.1 5040.9 4647.8 4652.4 4671.6 4696.8 4714.8 4949.5 4951.5 4951.6 4956.3 5006.3 4818.9 4879.9 4991.8 5026.0 5077.7 4756.6 4757.6 4765.2 4820.4 4864.4 4783.1 4808.0 4815.3 4817.8 4867.1 4613.3 4728.6 4762.4 4806.2 4812.7 4852.2 4887.3 4904.0 4929.5 4958.0 4814.1 4819.6 4823.3 4861.6 4866.2 16:18:43 INFO - PROCESS | 11426 | 16:18:43 INFO - PROCESS | 11426 | [#1] ext.html Cycles:180 Average:46842.92 Median:36788.91 stddev:85590.20 (232.7%) stddev-sans-first:85759.70 16:18:43 INFO - PROCESS | 11426 | Values: 540.2 584.4 590.7 593.9 595.6 38879.6 40073.8 40204.2 45144.0 45338.0 41626.8 42092.6 42140.0 42155.0 42157.4 7624.6 7802.1 7804.1 7812.1 7822.1 7792.1 7862.6 7866.3 7872.9 7873.0 7891.8 7917.0 7917.4 7922.3 7945.9 7825.8 7843.1 7877.0 7883.1 7896.5 7821.3 7866.8 7880.7 7886.5 7887.0 39604.4 39905.4 39910.4 39981.2 39996.4 2906.0 2949.6 2990.6 3003.6 3050.0 29453.0 29845.6 29942.4 30162.4 31121.8 3045.1 3172.1 3340.4 3359.4 3417.0 270798.0 272218.0 272685.0 272848.6 273437.0 32294.0 33096.7 33455.0 56964.4 57481.4 34565.0 34796.3 34916.1 34919.3 34953.3 12320.3 12350.6 12358.0 12359.3 12372.4 10763.9 10880.1 10890.8 10901.0 10902.2 7927.9 7949.3 7956.3 8026.3 8040.4 3077.7 3087.6 3130.7 3143.8 3171.3 855.8 895.4 902.5 953.4 974.0 28608.6 28778.3 28859.6 29383.0 29444.9 810.5 962.5 966.5 997.2 1016.1 14452.3 15239.4 15353.8 15501.7 15741.4 387226.0 388749.0 389385.0 390800.0 391535.0 50861.5 51271.5 51301.2 51417.0 51468.7 1029.4 1097.9 1122.6 1134.7 1156.3 26395.2 29809.9 30282.7 30517.0 30725.2 43992.3 44528.6 44560.6 44577.3 44722.0 299486.0 299709.0 301073.0 301255.0 302508.0 25482.4 26171.3 26246.3 26309.5 27993.6 40370.0 40395.4 42183.8 43002.4 43772.8 36333.3 36423.5 36562.5 36988.4 37439.1 36614.3 37102.3 37262.0 37387.6 38019.6 36714.8 36763.8 36769.0 36808.8 37788.6 31545.8 33789.0 34091.3 34155.5 34616.7 25876.5 25935.0 26178.0 26356.3 26575.7 16:18:43 INFO - PROCESS | 11426 | 16:18:43 INFO - PROCESS | 11426 | [#2] jquery.html Cycles:180 Average:24332.59 Median:5288.88 stddev:64097.65 (1211.9%) stddev-sans-first:64252.14 16:18:43 INFO - PROCESS | 11426 | Values: 336.5 355.5 363.0 369.7 382.7 12601.4 16211.0 16382.3 18270.4 18412.7 17290.9 17593.8 17715.0 17780.6 17788.3 5026.9 5267.5 5502.6 5529.5 5636.0 3506.7 5538.6 5571.2 5573.2 5588.7 5809.7 5876.1 5895.6 5904.5 5910.0 5467.9 5507.3 5525.1 5861.2 5967.9 5279.8 5281.4 5283.9 5287.7 5290.1 11944.5 11966.8 12302.8 12336.1 12494.4 1735.1 1798.2 1817.3 1961.9 2197.3 8778.7 9055.3 9065.7 9329.3 9562.0 1679.5 1686.9 1710.7 1748.1 1786.7 66262.0 71449.0 71523.0 71548.3 73138.6 8249.5 8529.3 8632.2 8684.0 9715.5 11572.1 11664.1 11667.6 11671.4 11675.0 6589.5 6668.5 6719.4 6782.3 6822.5 6504.8 6589.0 6736.2 6738.2 6787.3 3672.5 3729.8 3747.1 3838.6 3843.7 1721.5 1760.8 1769.5 1784.1 1804.5 798.9 811.2 871.2 871.7 925.5 16816.6 17008.4 17522.3 17655.0 18052.5 787.4 813.4 837.5 848.4 886.4 9383.3 9745.8 9794.7 9795.7 9900.6 368521.0 388893.0 389575.0 390683.0 390884.0 75753.0 80116.0 88179.0 91476.2 93849.0 884.5 933.1 934.1 940.5 946.0 15470.3 16099.8 16828.7 16927.3 17305.7 35567.0 38959.8 40307.2 41522.6 41527.6 52276.2 53882.7 54005.5 54347.7 54861.0 7158.8 7578.5 7652.3 7671.6 7784.5 9724.3 11892.2 12186.1 12351.1 12408.9 9564.5 9782.2 10148.8 10162.9 10582.4 10000.0 10321.7 10400.3 10887.2 10970.2 10056.8 10155.6 10233.7 10412.9 10536.2 7504.9 7695.8 7772.9 7799.6 7923.6 8937.8 9967.2 9977.2 10263.1 10525.9 16:18:43 INFO - PROCESS | 11426 | 16:18:43 INFO - PROCESS | 11426 | [#3] mootools.html Cycles:180 Average:5174.78 Median:5013.96 stddev:2984.10 (59.5%) stddev-sans-first:2978.01 16:18:43 INFO - PROCESS | 11426 | Values: 1265.8 1384.6 1385.0 1506.4 1729.1 6745.5 7689.2 7952.2 7959.9 8220.5 8027.2 8090.9 8092.1 8092.4 8100.5 2208.8 2236.9 2237.4 2237.5 2239.0 2146.5 2192.6 2222.5 2224.2 2224.6 2194.1 2195.8 2199.5 2203.1 2231.5 2144.2 2160.8 2165.8 2166.5 2172.7 2115.2 2137.5 2146.1 2152.3 2155.3 7911.0 8023.6 8041.5 8090.8 8122.6 4555.7 4751.6 4760.5 4767.9 4768.1 2749.9 2847.1 2882.5 2892.0 2899.1 841.1 860.6 869.3 874.9 885.7 10774.5 10835.9 10857.9 10874.7 10882.3 3888.1 4034.3 4105.6 4187.2 4195.1 8624.0 8627.3 8627.6 8627.7 8631.6 4579.5 4585.2 4594.1 4597.7 4603.8 3645.7 3727.9 4145.0 4245.8 4248.1 3286.7 3342.0 3427.7 3434.3 3435.9 967.6 1003.7 1005.0 1012.9 1021.5 660.8 709.4 794.8 814.6 843.7 5096.2 5199.6 5222.5 5312.7 5419.0 388.7 391.4 392.9 395.3 400.8 3631.5 3731.2 3737.2 3766.6 3839.3 9607.3 9962.7 9984.3 10066.7 10089.4 9766.9 9816.7 9857.4 9886.4 9920.8 7542.0 7609.0 7618.4 7622.5 7633.8 5169.8 5180.8 5191.6 5252.3 5479.8 8944.0 9090.1 9177.4 9232.1 9345.4 9136.0 9205.9 9296.6 9366.3 9376.6 5486.4 6603.6 6703.6 6710.9 6719.5 7415.6 7725.4 7755.3 7963.1 7967.4 6981.6 6997.1 7020.5 7209.0 7234.9 6845.8 6960.6 6963.2 7123.7 7161.3 6194.6 6260.0 6277.7 6307.5 6312.9 4400.5 4455.2 4576.6 4582.0 4614.9 4643.4 4694.5 4715.6 4737.5 4931.7 16:18:43 INFO - PROCESS | 11426 | 16:18:43 INFO - PROCESS | 11426 | [#4] prototype.html Cycles:180 Average:3988.28 Median:4368.74 stddev:1148.95 (26.3%) stddev-sans-first:1118.22 16:18:43 INFO - PROCESS | 11426 | Values: 294.2 299.7 300.5 304.3 311.6 4580.5 4744.3 4762.1 4782.3 4785.3 4580.9 4689.7 4700.2 4710.8 4719.2 3743.5 3744.8 3753.4 3755.8 3762.9 3548.4 3619.4 3762.7 3766.2 3771.8 3708.3 3730.4 3732.9 3733.6 3738.2 3712.7 3718.1 3719.9 3725.6 3737.5 3290.2 3297.8 3302.3 3304.7 3308.7 4463.5 4615.7 4629.2 4630.7 4642.5 4204.7 4210.7 4231.6 4234.6 4240.4 4909.3 4917.3 4924.1 4946.4 4949.7 3420.0 3529.4 3536.1 3549.7 3557.5 5136.3 5170.8 5177.9 5183.9 5193.9 3605.5 3611.2 3613.2 3614.2 3617.4 3485.9 3655.4 3655.5 3663.5 3670.0 4169.2 4178.6 4210.4 4221.4 4223.3 4984.9 4996.7 5003.5 5012.4 5016.6 1191.2 1214.3 1245.8 1249.6 1264.8 846.6 856.5 875.4 878.6 880.1 4328.3 4333.2 4341.1 4381.7 4388.6 5025.9 5043.0 5045.9 5051.0 5062.9 4347.7 4356.8 4367.0 4370.5 4386.3 2300.0 2329.5 2397.3 2403.5 2406.9 4177.0 4207.1 4208.4 4226.3 4264.2 4575.3 4600.6 4603.2 4616.3 4629.2 4032.6 4113.9 4184.9 4210.7 4231.3 4463.7 4472.7 4506.8 4516.6 4517.8 4435.7 4436.5 4439.8 4443.6 4450.2 3890.8 4189.9 4190.5 4204.3 4209.5 3294.6 3297.0 3299.3 3300.2 3317.9 4484.3 4494.4 4504.4 4505.6 4850.2 4723.4 4971.5 4979.4 4981.7 5003.1 4976.2 4977.6 4985.4 4999.3 5001.6 4742.7 4817.3 4916.3 4932.6 4936.4 4953.3 4957.9 4961.9 4963.2 4967.3 4852.4 4859.8 4890.8 4900.3 4901.8 16:18:43 INFO - PROCESS | 11426 | 16:18:43 INFO - PROCESS | 11426 | [#5] yui.html Cycles:180 Average:16957.75 Median:460.18 stddev:23859.68 (5184.9%) stddev-sans-first:23894.75 16:18:43 INFO - PROCESS | 11426 | Values: 537.2 547.2 597.4 614.1 626.4 11788.2 12314.1 12650.1 12707.2 12960.5 12358.6 12498.9 12548.4 12584.9 12591.5 645.9 772.0 783.9 791.8 793.1 763.6 789.8 790.1 794.8 796.3 828.5 832.3 832.6 832.6 832.7 668.5 826.5 826.6 826.9 827.0 588.2 603.7 604.1 604.5 605.1 12171.1 12233.3 12251.7 12256.0 12262.1 12124.5 12209.3 12435.3 12441.6 12768.6 11506.6 11812.7 11846.6 11854.8 12068.2 4033.8 4085.4 4128.6 4167.3 4213.2 59634.4 60397.8 61220.1 62269.0 62478.4 9660.9 9800.1 9946.9 10125.2 10190.9 17758.5 18028.8 18046.6 18058.5 18074.6 6340.0 6358.7 6455.9 6590.7 6618.3 3574.5 3600.6 3644.8 3655.4 3693.1 2544.4 2653.0 2660.3 2662.7 2663.4 453.1 453.2 456.8 457.8 464.7 462.5 468.8 475.8 499.9 523.7 6529.5 6632.3 6703.8 6961.4 7118.4 437.8 507.3 508.6 513.2 523.9 3273.8 3348.3 3372.1 3387.9 3459.3 56562.0 57030.0 91413.1 96726.5 101472.0 75239.0 82462.6 83445.2 85044.6 85594.0 68186.7 69476.7 73762.3 75568.6 76005.0 7874.2 8058.2 8149.2 8695.9 8751.8 43023.4 43325.0 43729.0 44501.3 44869.8 59359.4 60893.7 62086.0 62566.7 64888.4 10865.7 11284.5 11354.6 11708.1 11744.8 11204.8 11263.6 11403.8 11735.3 12174.7 11524.4 11750.4 11900.6 12438.2 12465.9 11046.9 11252.9 11324.2 11859.5 11881.0 11118.1 11175.3 11233.5 11348.4 11556.5 9331.1 9362.5 9860.9 9887.5 9906.6 9557.6 9876.7 10013.1 10072.4 10094.2 16:18:43 INFO - PROCESS | 11426 | -------- Summary: end -------- 16:18:43 INFO - PROCESS | 11426 | 16:18:44 INFO - TEST-INFO | 11426: exit 0 16:18:44 INFO - TEST-OK | dromaeo_css | took 1120177ms 16:18:44 INFO - TEST-START | kraken 16:18:44 INFO - operating with platform_type : linux_ 16:18:44 INFO - Initialising browser for kraken test... 16:18:44 INFO - TEST-INFO | started process 11613 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp_3hdtt/profile http://localhost:38010/getInfo.html) 16:18:48 INFO - PROCESS | 11613 | JavaScript error: , line 0: uncaught exception: undefined 16:18:48 INFO - PROCESS | 11613 | __metrics Screen width/height:1600/1200 16:18:48 INFO - PROCESS | 11613 | colorDepth:24 16:18:48 INFO - PROCESS | 11613 | Browser inner width/height: 1024/697 16:18:48 INFO - PROCESS | 11613 | __metrics 16:18:49 INFO - PROCESS | 11613 | 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" 16:18:49 INFO - PROCESS | 11613 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 58: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:18:50 INFO - TEST-INFO | 11613: exit 0 16:18:50 INFO - Browser initialized. 16:18:50 INFO - Running cycle 1/1 for kraken test... 16:18:50 INFO - TEST-INFO | started process 11695 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp_3hdtt/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:19:02 INFO - PROCESS | 11695 | RSS: Main: 159068160 16:19:02 INFO - PROCESS | 11695 | 16:19:05 INFO - PROCESS | 11695 | Cycle 1(1): loaded http://localhost:38010/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:38010/tests/kraken/driver.html?testName=audio-beat-detection) 16:19:06 INFO - PROCESS | 11695 | RSS: Main: 215801856 16:19:06 INFO - PROCESS | 11695 | 16:19:09 INFO - PROCESS | 11695 | Cycle 1(1): loaded http://localhost:38010/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:38010/tests/kraken/driver.html?testName=audio-dft) 16:19:09 INFO - PROCESS | 11695 | RSS: Main: 193175552 16:19:09 INFO - PROCESS | 11695 | 16:19:12 INFO - PROCESS | 11695 | Cycle 1(1): loaded http://localhost:38010/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:38010/tests/kraken/driver.html?testName=audio-fft) 16:19:13 INFO - PROCESS | 11695 | RSS: Main: 273559552 16:19:13 INFO - PROCESS | 11695 | 16:19:15 INFO - PROCESS | 11695 | Cycle 1(1): loaded http://localhost:38010/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:38010/tests/kraken/driver.html?testName=audio-oscillator) 16:19:16 INFO - PROCESS | 11695 | RSS: Main: 182251520 16:19:16 INFO - PROCESS | 11695 | 16:19:18 INFO - PROCESS | 11695 | Cycle 1(1): loaded http://localhost:38010/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:38010/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:19:19 INFO - PROCESS | 11695 | RSS: Main: 186400768 16:19:19 INFO - PROCESS | 11695 | 16:19:22 INFO - PROCESS | 11695 | Cycle 1(1): loaded http://localhost:38010/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:38010/tests/kraken/driver.html?testName=imaging-darkroom) 16:19:23 INFO - PROCESS | 11695 | RSS: Main: 223002624 16:19:23 INFO - PROCESS | 11695 | 16:19:26 INFO - PROCESS | 11695 | Cycle 1(1): loaded http://localhost:38010/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:38010/tests/kraken/driver.html?testName=imaging-desaturate) 16:19:27 INFO - PROCESS | 11695 | RSS: Main: 216821760 16:19:27 INFO - PROCESS | 11695 | 16:19:30 INFO - PROCESS | 11695 | Cycle 1(1): loaded http://localhost:38010/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:38010/tests/kraken/driver.html?testName=json-parse-financial) 16:19:30 INFO - PROCESS | 11695 | RSS: Main: 220147712 16:19:30 INFO - PROCESS | 11695 | 16:19:32 INFO - PROCESS | 11695 | Cycle 1(1): loaded http://localhost:38010/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:38010/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:19:32 INFO - PROCESS | 11695 | RSS: Main: 186388480 16:19:33 INFO - PROCESS | 11695 | 16:19:34 INFO - PROCESS | 11695 | Cycle 1(1): loaded http://localhost:38010/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:38010/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:19:35 INFO - PROCESS | 11695 | RSS: Main: 191488000 16:19:35 INFO - PROCESS | 11695 | 16:19:37 INFO - PROCESS | 11695 | Cycle 1(1): loaded http://localhost:38010/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:38010/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:19:38 INFO - PROCESS | 11695 | RSS: Main: 195477504 16:19:38 INFO - PROCESS | 11695 | 16:19:41 INFO - PROCESS | 11695 | Cycle 1(1): loaded http://localhost:38010/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:38010/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:19:41 INFO - PROCESS | 11695 | RSS: Main: 190582784 16:19:41 INFO - PROCESS | 11695 | 16:19:44 INFO - PROCESS | 11695 | Cycle 1(1): loaded http://localhost:38010/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:38010/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:19:45 INFO - PROCESS | 11695 | RSS: Main: 194146304 16:19:45 INFO - PROCESS | 11695 | 16:19:46 INFO - PROCESS | 11695 | Cycle 1(1): loaded http://localhost:38010/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:38010/tests/kraken/driver.html?testName=ai-astar) 16:19:47 INFO - PROCESS | 11695 | RSS: Main: 244871168 16:19:47 INFO - PROCESS | 11695 | 16:19:47 INFO - PROCESS | 11695 | __start_tp_report 16:19:47 INFO - PROCESS | 11695 | _x_x_mozilla_page_load 16:19:47 INFO - PROCESS | 11695 | _x_x_mozilla_page_load_details 16:19:47 INFO - PROCESS | 11695 | |i|pagename|runs| 16:19:47 INFO - PROCESS | 11695 | |0;ai-astar;104;114;112;114;114;113;113;112;112;112 16:19:47 INFO - PROCESS | 11695 | |1;audio-beat-detection;148;124;123;191;125;128;144;123;175;128 16:19:47 INFO - PROCESS | 11695 | |2;audio-dft;162;161;162;162;162;161;162;162;162;162 16:19:47 INFO - PROCESS | 11695 | |3;audio-fft;74;76;76;121;75;77;74;75;106;75 16:19:47 INFO - PROCESS | 11695 | |4;audio-oscillator;85;86;127;84;84;85;126;85;86;135 16:19:47 INFO - PROCESS | 11695 | |5;imaging-gaussian-blur;117;117;117;116;117;117;117;116;116;117 16:19:47 INFO - PROCESS | 11695 | |6;imaging-darkroom;146;146;146;145;146;147;146;147;146;146 16:19:47 INFO - PROCESS | 11695 | |7;imaging-desaturate;95;95;95;95;96;96;95;95;94;95 16:19:47 INFO - PROCESS | 11695 | |8;json-parse-financial;61;61;61;60;61;72;59;59;60;61 16:19:47 INFO - PROCESS | 11695 | |9;json-stringify-tinderbox;67;65;66;65;66;66;66;65;66;65 16:19:47 INFO - PROCESS | 11695 | |10;stanford-crypto-aes;80;80;79;82;84;80;78;84;80;83 16:19:47 INFO - PROCESS | 11695 | |11;stanford-crypto-ccm;131;131;128;132;127;128;131;130;174;133 16:19:47 INFO - PROCESS | 11695 | |12;stanford-crypto-pbkdf2;162;162;162;160;162;160;161;162;165;163 16:19:47 INFO - PROCESS | 11695 | |13;stanford-crypto-sha256-iterative;67;72;68;66;68;66;66;67;69;70 16:19:47 INFO - PROCESS | 11695 | __end_tp_report 16:19:47 INFO - PROCESS | 11695 | __start_cc_report 16:19:47 INFO - PROCESS | 11695 | _x_x_mozilla_cycle_collect,1970 16:19:47 INFO - PROCESS | 11695 | __end_cc_report 16:19:47 INFO - PROCESS | 11695 | __startTimestamp1469143187164__endTimestamp 16:19:47 INFO - PROCESS | 11695 | 16:19:47 INFO - PROCESS | 11695 | ------- Summary: start ------- 16:19:47 INFO - PROCESS | 11695 | Number of tests: 14 16:19:47 INFO - PROCESS | 11695 | 16:19:47 INFO - PROCESS | 11695 | [#0] ai-astar Cycles:10 Average:112.00 Median:113.00 stddev:2.94 (2.6%) stddev-sans-first:0.93 16:19:47 INFO - PROCESS | 11695 | Values: 104.0 114.0 112.0 114.0 114.0 113.0 113.0 112.0 112.0 112.0 16:19:47 INFO - PROCESS | 11695 | 16:19:47 INFO - PROCESS | 11695 | [#1] audio-beat-detection Cycles:10 Average:140.90 Median:136.00 stddev:24.14 (17.8%) stddev-sans-first:25.47 16:19:47 INFO - PROCESS | 11695 | Values: 148.0 124.0 123.0 191.0 125.0 128.0 144.0 123.0 175.0 128.0 16:19:47 INFO - PROCESS | 11695 | 16:19:47 INFO - PROCESS | 11695 | [#2] audio-dft Cycles:10 Average:161.80 Median:162.00 stddev:0.42 (0.3%) stddev-sans-first:0.44 16:19:47 INFO - PROCESS | 11695 | Values: 162.0 161.0 162.0 162.0 162.0 161.0 162.0 162.0 162.0 162.0 16:19:47 INFO - PROCESS | 11695 | 16:19:47 INFO - PROCESS | 11695 | [#3] audio-fft Cycles:10 Average:82.90 Median:75.00 stddev:16.54 (22.0%) stddev-sans-first:17.22 16:19:47 INFO - PROCESS | 11695 | Values: 74.0 76.0 76.0 121.0 75.0 77.0 74.0 75.0 106.0 75.0 16:19:47 INFO - PROCESS | 11695 | 16:19:47 INFO - PROCESS | 11695 | [#4] audio-oscillator Cycles:10 Average:98.30 Median:85.00 stddev:21.55 (25.4%) stddev-sans-first:22.31 16:19:47 INFO - PROCESS | 11695 | Values: 85.0 86.0 127.0 84.0 84.0 85.0 126.0 85.0 86.0 135.0 16:19:47 INFO - PROCESS | 11695 | 16:19:47 INFO - PROCESS | 11695 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 16:19:47 INFO - PROCESS | 11695 | Values: 117.0 117.0 117.0 116.0 117.0 117.0 117.0 116.0 116.0 117.0 16:19:47 INFO - PROCESS | 11695 | 16:19:47 INFO - PROCESS | 11695 | [#6] imaging-darkroom Cycles:10 Average:146.10 Median:146.00 stddev:0.57 (0.4%) stddev-sans-first:0.60 16:19:47 INFO - PROCESS | 11695 | Values: 146.0 146.0 146.0 145.0 146.0 147.0 146.0 147.0 146.0 146.0 16:19:47 INFO - PROCESS | 11695 | 16:19:47 INFO - PROCESS | 11695 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:95.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 16:19:47 INFO - PROCESS | 11695 | Values: 95.0 95.0 95.0 95.0 96.0 96.0 95.0 95.0 94.0 95.0 16:19:47 INFO - PROCESS | 11695 | 16:19:47 INFO - PROCESS | 11695 | [#8] json-parse-financial Cycles:10 Average:61.50 Median:61.00 stddev:3.78 (6.2%) stddev-sans-first:4.00 16:19:47 INFO - PROCESS | 11695 | Values: 61.0 61.0 61.0 60.0 61.0 72.0 59.0 59.0 60.0 61.0 16:19:47 INFO - PROCESS | 11695 | 16:19:47 INFO - PROCESS | 11695 | [#9] json-stringify-tinderbox Cycles:10 Average:65.70 Median:66.00 stddev:0.67 (1.0%) stddev-sans-first:0.53 16:19:47 INFO - PROCESS | 11695 | Values: 67.0 65.0 66.0 65.0 66.0 66.0 66.0 65.0 66.0 65.0 16:19:47 INFO - PROCESS | 11695 | 16:19:47 INFO - PROCESS | 11695 | [#10] stanford-crypto-aes Cycles:10 Average:81.00 Median:81.00 stddev:2.11 (2.6%) stddev-sans-first:2.20 16:19:47 INFO - PROCESS | 11695 | Values: 80.0 80.0 79.0 82.0 84.0 80.0 78.0 84.0 80.0 83.0 16:19:47 INFO - PROCESS | 11695 | 16:19:47 INFO - PROCESS | 11695 | [#11] stanford-crypto-ccm Cycles:10 Average:134.50 Median:131.00 stddev:14.01 (10.7%) stddev-sans-first:14.80 16:19:47 INFO - PROCESS | 11695 | Values: 131.0 131.0 128.0 132.0 127.0 128.0 131.0 130.0 174.0 133.0 16:19:47 INFO - PROCESS | 11695 | 16:19:47 INFO - PROCESS | 11695 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:161.90 Median:162.00 stddev:1.45 (0.9%) stddev-sans-first:1.54 16:19:47 INFO - PROCESS | 11695 | Values: 162.0 162.0 162.0 160.0 162.0 160.0 161.0 162.0 165.0 163.0 16:19:47 INFO - PROCESS | 11695 | 16:19:47 INFO - PROCESS | 11695 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.90 Median:68.00 stddev:1.97 (2.9%) stddev-sans-first:2.06 16:19:47 INFO - PROCESS | 11695 | Values: 67.0 72.0 68.0 66.0 68.0 66.0 66.0 67.0 69.0 70.0 16:19:47 INFO - PROCESS | 11695 | -------- Summary: end -------- 16:19:47 INFO - PROCESS | 11695 | 16:19:48 INFO - TEST-INFO | 11695: exit 0 16:19:48 INFO - TEST-OK | kraken | took 63859ms 16:19:48 INFO - SUITE-END | took 1184s 16:19:48 INFO - Completed test suite (00:19:44) 16:19:48 INFO - javascript benchmark 16:19:48 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2551.4897020595863, 2553.8978440862365, 2591.080166540877, 2598.1945596864975, 2601.3236558494787, 4570.97714511427, 4637.605803506706, 4665.276882083278, 4701.106789709957, 4711.646626503013, 4540.636749060075, 4626.606738427237, 4667.953320466794, 4668.603168730662, 4692.343071969918, 4254.234237837188, 4310.07333423314, 4316.460442444694, 4326.5673432656795, 4336.04606986464, 4297.076128632343, 4346.043870348518, 4362.105768317488, 4374.212641724488, 4423.0711550574415, 4372.97813510932, 4387.210302145612, 4387.3857659927635, 4406.647468202536, 4414.801333939964, 4199.475065616798, 4201.34879093741, 4210.010647497836, 4296.2696341621995, 4354.303311470149, 4196.706230563862, 4231.217224813419, 4239.915201695949, 4248.0, 4341.45731783527, 4639.211334073216, 4680.695754775928, 4695.131400690883, 4706.105839890444, 4773.260144677581, 4994.500549945013, 5012.72430016349, 5017.172166592508, 5041.773120209599, 5064.0, 4846.248831431134, 4934.5312195341385, 4961.727105009223, 4996.200607902718, 5073.6702114362815, 4634.86095417138, 4664.973705784722, 4705.811767529295, 4708.952910470852, 4713.387259656222, 5077.314562534085, 5159.871003224949, 5164.48355164488, 5168.121419358718, 5169.638125331266, 4717.8112875485285, 4803.831865884677, 4832.49258827818, 4851.102546028996, 4913.65604407688, 4967.103786431477, 4991.276264941564, 5001.849944501598, 5006.224035274466, 5007.4491805901325, 4890.0, 4897.583705385011, 4907.607391408679, 4918.139325618003, 4968.354113965162, 4793.49668284832, 4823.469418363977, 4827.396575428072, 4922.90154196914, 4927.211646136601, 4244.448221731217, 4249.660027197879, 4278.122984788111, 4282.914341713148, 4289.099289149313, 3700.611435799256, 3714.9071273218387, 3732.346839303111, 3742.9812850936114, 3848.4034974578426, 4312.310030395121, 4319.0, 4321.913561728811, 4360.7819609019425, 4365.388845561624, 5028.698278103326, 5039.647224694309, 5039.949600503948, 5043.84868453947, 5085.898282034339, 4199.475065616798, 4267.914641707211, 4291.721038132511, 4311.396404503372, 4356.041670832412, 4554.453465584151, 4568.588827005586, 4606.838760643362, 4610.985583171697, 4611.608013318905, 4609.7695115244105, 4618.3143152748335, 4646.395968524138, 4655.8603241903475, 4720.103180395714, 4867.683600565952, 4913.336699545658, 4984.053029924303, 5031.119554077948, 5040.949590504048, 4647.837325693585, 4652.395188625457, 4671.602913752233, 4696.765161741968, 4714.81140754366, 4949.529794669501, 4951.529604687549, 4951.579115775199, 4956.281339205867, 5006.299118123393, 4818.8795280118275, 4879.853604391874, 4991.825286114969, 5026.045051440215, 5077.720725359993, 4756.5956893664425, 4757.619390448686, 4765.189917713928, 4820.373351464287, 4864.416270047617, 4783.091212669582, 4808.0, 4815.3499277597075, 4817.807287708592, 4867.075255701406, 4613.284940834176, 4728.62171026324, 4762.404699412574, 4806.2310030394965, 4812.711237325771, 4852.2479015752615, 4887.315775791321, 4904.0, 4929.507049295042, 4957.975210123926, 4814.061258054646, 4819.638527110551, 4823.276508523609, 4861.635377346643, 4866.197077482176], "value": 4580.183458530262, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [540.1948198353646, 584.436019241432, 590.7194082810663, 593.8604427959426, 595.5682130455419, 38879.61120388795, 40073.79963100184, 40204.19591608166, 45144.0, 45338.0, 41626.79186604063, 42092.57907420925, 42140.0, 42155.0, 42157.367639485354, 7624.6187690615525, 7802.063752349726, 7804.063512378494, 7812.10160831506, 7822.100458447266, 7792.142864284924, 7862.567558784264, 7866.331361834251, 7872.921270787276, 7873.0, 7891.763247102596, 7917.049954005527, 7917.366610671162, 7922.326602238817, 7945.920540794605, 7825.843483130306, 7843.058832940055, 7876.960615196945, 7883.132855385932, 7896.486728362638, 7821.2960833524685, 7866.803329916741, 7880.684772609089, 7886.526808391457, 7887.0, 39604.40593391102, 39905.40141897874, 39910.401343979865, 39981.20037599232, 39996.40005399921, 2906.0, 2949.631296087989, 2990.626171728534, 3003.639563252402, 3050.0, 29453.0, 29845.552316715268, 29942.401151977057, 30162.396752065055, 31121.8443907779, 3045.1321373408473, 3172.080891060898, 3340.448825943716, 3359.4456914609304, 3417.026147547937, 270798.0, 272218.0, 272685.0, 272848.63575682195, 273437.0, 32294.0, 33096.66903330984, 33455.0, 56964.43035569674, 57481.425185747605, 34565.0, 34796.30407391889, 34916.127096822784, 34919.301613968084, 34953.300933981685, 12320.322382268881, 12350.62948111558, 12358.0, 12359.32023738685, 12372.381380930918, 10763.892361076445, 10880.12958963281, 10890.836637450444, 10900.96440838119, 10902.236843420884, 7927.8810817837775, 7949.284564389232, 7956.283934445928, 8026.277635012877, 8040.437169398203, 3077.6768439313996, 3087.6294844618787, 3130.733887619532, 3143.8113713176826, 3171.349873275973, 855.7774978505508, 895.394402289335, 902.4540153207215, 953.3755390219418, 974.0016483104732, 28608.570871436947, 28778.28054298659, 28859.567106493418, 29383.0, 29444.852775735984, 810.5217921426387, 962.499500259861, 966.4925913895261, 997.1922742578535, 1016.1159790981893, 14452.349644266033, 15239.390424383122, 15353.846461535464, 15501.689966200613, 15741.449049283448, 387226.0, 388749.0, 389385.0, 390800.0, 391535.0, 50861.49138508642, 51271.48728512742, 51301.23048154281, 51417.0, 51468.74265628723, 1029.4235228272191, 1097.9231453798318, 1122.5622007417114, 1134.7163209197702, 1156.2542160306555, 26395.208143755717, 29809.85095074511, 30282.697173028868, 30517.0, 30725.23186920345, 43992.34011489766, 44528.55471445244, 44560.55439445564, 44577.331340029275, 44722.0, 299486.0, 299709.0, 301073.0, 301255.0, 302508.0, 25482.362940926625, 26171.345716357242, 26246.34384140412, 26309.4738105233, 27993.580096298163, 40370.0, 40395.394069089576, 42183.78908105563, 43002.35496467493, 43772.78113609539, 36333.27333453369, 36423.453648194765, 36562.45156322711, 36988.44517332188, 37439.06889776534, 36614.26771464502, 37102.257954840214, 37262.0, 37387.6261237395, 38019.61980380161, 36714.8164259177, 36763.816180919996, 36769.0, 36808.81595592005, 37788.62211377961, 31545.8422707885, 33788.994390586115, 34091.31817363589, 34155.48766768451, 34616.65383346134, 25876.482470350864, 25935.0, 26178.0, 26356.341091472867, 26575.734242657327], "value": 16585.174561576856, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [336.53558089836025, 355.50762194360817, 362.9909252268693, 369.69684858416093, 382.6766382406867, 12601.369931503423, 16211.0, 16382.262798174066, 18270.360537381195, 18412.723809142874, 17290.913545432286, 17593.82406175941, 17715.0, 17780.64438711225, 17788.288468461247, 5026.924596131061, 5267.47325267474, 5502.642328248651, 5529.474699903523, 5636.04187288162, 3506.684398404143, 5538.556915446755, 5571.164325351209, 5573.164025396182, 5588.748506317205, 5809.651420914759, 5876.08920617305, 5895.587308888379, 5904.498117659982, 5909.9704501477645, 5467.917981230285, 5507.311586051744, 5525.0883604205255, 5861.238039054917, 5967.940320596804, 5279.841604751864, 5281.36623605166, 5283.947160528384, 5287.656302340336, 5290.100682883881, 11944.522219111224, 11966.82049769254, 12302.81545776814, 12336.074794390366, 12494.375281235902, 1735.1237624999294, 1798.2087881332302, 1817.318505560415, 1961.9411417657493, 2197.340797760681, 8778.736637900873, 9055.275577953747, 9065.728028159167, 9329.346945713734, 9561.952190239073, 1679.4793613979705, 1686.907220102894, 1710.6835235481476, 1748.0997286397514, 1786.7051936430473, 66262.0, 71449.0, 71523.0, 71548.28451715468, 73138.63430682813, 8249.505029698237, 8529.275011623957, 8632.223099921037, 8684.0, 9715.514224288758, 11572.074234061254, 11664.066874650007, 11667.59163429276, 11671.416429178507, 11675.007624351856, 6589.472842172615, 6668.466522678174, 6719.361660642231, 6782.253952065269, 6822.522423430411, 6504.772332968418, 6589.0, 6736.157980252468, 6738.157730283715, 6787.321267873173, 3672.4674922136674, 3729.776213427149, 3747.0819649186124, 3838.5777564467885, 3843.7117216208335, 1721.4749501402193, 1760.8063113057553, 1769.539919620882, 1784.0722824131637, 1804.5218017225445, 798.9161138080534, 811.2252798577463, 871.172386233081, 871.7384784564605, 925.5048549026238, 16816.57958551046, 17008.404705835237, 17522.299108035535, 17655.0, 18052.458426247234, 787.4409169489612, 813.4387272781762, 837.530982649706, 848.3552500099969, 886.4149661223562, 9383.343165978316, 9745.805083898282, 9794.706158815105, 9795.657151999645, 9900.55447504864, 368521.0, 388893.0, 389575.0, 390683.0, 390884.0, 75753.0, 80116.0, 88179.0, 91476.22452091513, 93849.0, 884.4958373726914, 933.1461712533091, 934.1032608695729, 940.4733349324264, 945.952702364879, 15470.303836327617, 16099.75850362269, 16828.66342673164, 16927.32290708407, 17305.65388692194, 35567.0, 38959.80520097382, 40307.193856122125, 41522.602061731784, 41527.58472415237, 52276.21585676142, 53882.73058634682, 54005.45994540004, 54347.72826135844, 54861.0, 7158.785236442915, 7578.469507134668, 7652.349550288288, 7671.61641917913, 7784.494007889469, 9724.27067969891, 11892.227005244631, 12186.146969712036, 12351.135420520477, 12408.875911240772, 9564.4739539326, 9782.168515676247, 10148.797024059626, 10162.89837101649, 10582.365058096539, 9999.950000249952, 10321.74195645115, 10400.27198096126, 10887.237893347707, 10970.177236707439, 10056.798864022825, 10155.64455244063, 10233.74415639615, 10412.947935260276, 10536.209784266057, 7504.887426688713, 7695.807604809925, 7772.883406749016, 7799.571023593757, 7923.603819809102, 8937.821243575223, 9967.202623790225, 9977.20182385393, 10263.076323130954, 10525.9473702631], "value": 8042.950682711443, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1265.784816581181, 1384.598466444731, 1384.9792253116204, 1506.4049700368355, 1729.1181497436312, 6745.494087943405, 7689.154193038768, 7952.165022672622, 7959.920400795991, 8220.506769593814, 8027.157148499411, 8090.878636820453, 8092.0694120176195, 8092.393070519705, 8100.4734692244965, 2208.8012078912893, 2236.944076398095, 2237.3959031061604, 2237.496563273267, 2238.9888050559725, 2146.5170336674278, 2192.550527141932, 2222.4777177363308, 2224.232639739287, 2224.555088982202, 2194.122351059573, 2195.769444208359, 2199.49411635324, 2203.063697928382, 2231.542533780579, 2144.2066435418997, 2160.8487405881597, 2165.7725938776357, 2166.458385403649, 2172.663237198237, 2115.1962254343407, 2137.487003119256, 2146.1308170191096, 2152.300502336747, 2155.3103007037753, 7910.960445197795, 8023.638936247883, 8041.477303975223, 8090.757277281691, 8122.553259570721, 4555.74943378114, 4751.619870410359, 4760.476347601796, 4767.880802979918, 4768.117898188846, 2749.931251718723, 2847.117393607988, 2882.4523340565434, 2892.0311695582004, 2899.130260921715, 841.142035124176, 860.5525126934022, 869.3088994249587, 874.9168828961239, 885.7342797160826, 10774.461276936201, 10835.945820270848, 10857.891421085767, 10874.72813179669, 10882.29265097766, 3888.125171836314, 4034.293998550242, 4105.568915263914, 4187.162567486454, 4195.098053918422, 8623.956880215685, 8627.266682331947, 8627.61175747093, 8627.741167764978, 8631.611577479029, 4579.473360563577, 4585.243434833282, 4594.104149690778, 4597.7241365518175, 4603.815847366068, 3645.708343332527, 3727.869524566627, 4144.979275103666, 4245.766482843412, 4248.065425606362, 3286.6713328666942, 3341.983290083534, 3427.691507764313, 3434.261633748756, 3435.879744208991, 967.5569187800859, 1003.6888564545014, 1004.9748756281006, 1012.8835183953793, 1021.4739409204266, 660.7687309441657, 709.4395427612128, 794.8410317936435, 814.5682788122305, 843.7300063979591, 5096.23556466533, 5199.610029247821, 5222.477752224747, 5312.73436328182, 5418.970395624898, 388.73177507520285, 391.44610376317195, 392.8840991907394, 395.2648074581277, 400.8156248125836, 3631.5097461842843, 3731.2164445466215, 3737.159139193714, 3766.6233376662112, 3839.347310957144, 9607.327487075978, 9962.701118966443, 9984.251181161297, 10066.69799906004, 10089.445080520494, 9766.902330976742, 9816.656417025513, 9857.408555486694, 9886.357386769838, 9920.751981200383, 7542.0, 7609.0, 7618.352440042659, 7622.5045372051745, 7633.847323053508, 5169.81905633301, 5180.792768289226, 5191.61062920275, 5252.343457067866, 5479.780808767605, 8943.955280223558, 9090.090990900857, 9177.449353038839, 9232.076792320715, 9345.392549484262, 9135.954320228357, 9205.861912071457, 9296.58165382573, 9366.250699943852, 9376.57805398745, 5486.423925487926, 6603.570767900071, 6703.631300278532, 6710.932890671031, 6719.496037797283, 7415.592142432218, 7725.381969442319, 7755.302022817974, 7963.084245311632, 7967.442279040411, 6981.581105133709, 6997.1253593300835, 7020.508564400443, 7209.026781384446, 7234.927650723636, 6845.794626161223, 6960.582365058112, 6963.199232088375, 7123.679434425359, 7161.319674630901, 6194.566380353332, 6259.96870015647, 6277.686115694289, 6307.495400368051, 6312.905306420499, 4400.537943515885, 4455.242608756584, 4576.633869290382, 4581.977090114528, 4614.884627884329, 4643.442786865598, 4694.530546945278, 4715.575598196178, 4737.478877323423, 4931.679440836478], "value": 4039.411608448127, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [294.24966335843607, 299.72575093789186, 300.5461752753342, 304.306181905256, 311.56069941382657, 4580.47324557676, 4744.268342055151, 4762.119007983517, 4782.258749893767, 4785.330053792468, 4580.862574122773, 4689.695169813968, 4700.224462963615, 4710.788014539346, 4719.244920812671, 3743.457198706186, 3744.8314825832767, 3753.3806921858, 3755.8497660093562, 3762.9059273518105, 3548.449990251515, 3619.3666108431053, 3762.680172185354, 3766.1902690921447, 3771.849126034955, 3708.258348330331, 3730.421784623374, 3732.9253414931823, 3733.6079711630296, 3738.2336620992633, 3712.6658600726064, 3718.0890681783135, 3719.888403347904, 3725.646063623965, 3737.4580685800543, 3290.161008942728, 3297.76915615907, 3302.3230237801436, 3304.6695330467, 3308.7353011759, 4463.464384273908, 4615.676902616819, 4629.189891768927, 4630.652701047435, 4642.51253618372, 4204.66362690984, 4210.663146948237, 4231.640310573605, 4234.5765423457715, 4240.363945408182, 4909.263610458424, 4917.286993385939, 4924.113659541281, 4946.3817022872145, 4949.678270912379, 3419.9829000855084, 3529.435290353557, 3536.1159710072484, 3549.7337699672626, 3557.4663800429885, 5136.332276804029, 5170.767315470775, 5177.948220517785, 5183.870403239912, 5193.870153246124, 3605.4772058051435, 3611.1694310308776, 3613.187032917625, 3614.222942067468, 3617.439296908983, 3485.860565577399, 3655.433407821739, 3655.5065066216252, 3663.4871118043493, 3669.9816500917323, 4169.207850508455, 4178.561251068655, 4210.410542524049, 4221.409002739619, 4223.345381465878, 4984.900301994012, 4996.701517302082, 5003.54968052877, 5012.373453318335, 5016.648834581544, 1191.2078467818976, 1214.3381856888018, 1245.819356193365, 1249.5501619417003, 1264.8482182138202, 846.6401779243797, 856.5117882806865, 875.4047247871381, 878.6178012564554, 880.0935036911991, 4328.285832837547, 4333.241682705514, 4341.131773645284, 4381.737095774264, 4388.648908087409, 5025.899482010413, 5043.041822053798, 5045.8738531537, 5051.030098261681, 5062.949370506321, 4347.652187824967, 4356.75832387768, 4367.0, 4370.475542934868, 4386.342048692722, 2299.9535211478737, 2329.499157681073, 2397.2739627468195, 2403.471236328005, 2406.89168987396, 4176.955761059735, 4207.116505533811, 4208.410822484854, 4226.309476209541, 4264.187720218956, 4575.313702944519, 4600.585947264763, 4603.240465323184, 4616.330632058332, 4629.213033784265, 4032.6169013943627, 4113.938290925638, 4184.891003883912, 4210.726302790309, 4231.322988321854, 4463.687541872037, 4472.686911916135, 4506.774661266989, 4516.6160876325885, 4517.751523666098, 4435.711678740871, 4436.467623885154, 4439.755813430293, 4443.622292105208, 4450.198964186348, 3890.844630864173, 4189.895252618709, 4190.539040705459, 4204.306289462327, 4209.515905670887, 3294.5717056782464, 3296.967030329762, 3299.307145499472, 3300.1914530940076, 3317.8672853085604, 4484.3318225655685, 4494.415725955605, 4504.3919070925, 4505.5719706627815, 4850.248211527078, 4723.385959825201, 4971.453140154653, 4979.4065898912, 4981.701097934136, 5003.124453220745, 4976.178930476431, 4977.576905963034, 4985.376827896513, 4999.300097986212, 5001.599872010303, 4742.668013239178, 4817.349657796152, 4916.311716359641, 4932.556069953722, 4936.407631084293, 4953.331300274417, 4957.925631115464, 4961.925571116364, 4963.205887058053, 4967.279744437109, 4852.369192005017, 4859.829905953275, 4890.755462226945, 4900.313956046085, 4901.754912254444], "value": 3630.8556801338855, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [537.2049366936935, 547.2284079447978, 597.3787261248302, 614.0543562913114, 626.3736263736264, 11788.17482776206, 12314.076444266693, 12650.114491985565, 12707.23756574605, 12960.546380876647, 12358.567450139248, 12498.93750531246, 12548.435320410581, 12584.874151258484, 12591.496340146383, 645.8579112595221, 771.9729809456657, 783.9451238413313, 791.7703865878888, 793.115676021235, 763.6067425275987, 789.8459800338936, 790.1269097647104, 794.8171920458298, 796.303234669664, 828.5153185386556, 832.2509741232878, 832.5712258187039, 832.5837081459271, 832.662771577509, 668.4752469311616, 826.5247482697473, 826.574314228173, 826.8511667899776, 826.9503829770202, 588.1618693361985, 603.7373742422061, 604.0606856338392, 604.4771272848998, 605.1044454207753, 12171.0871684624, 12233.266004039786, 12251.69370765729, 12256.0, 12262.080343974238, 12124.515019399214, 12209.267443953391, 12435.316057616827, 12441.626751197478, 12768.61694149177, 11506.597269095626, 11812.704682382922, 11846.644600661994, 11854.822177667256, 12068.155229133965, 4033.81847816846, 4085.3667681509414, 4128.6284234419045, 4167.333226683746, 4213.178430206111, 59634.40365596332, 60397.79204415931, 61220.081698774105, 62269.0, 62478.37521624771, 9660.855087173699, 9800.11798938099, 9946.900530994742, 10125.240606954509, 10190.898091019144, 17758.46724598264, 18028.819711802975, 18046.63906721858, 18058.458246252634, 18074.63850722978, 6340.0, 6358.682065896686, 6455.870882582322, 6590.736370545221, 6618.338166183439, 3574.4995700601935, 3600.6039335673054, 3644.835982380799, 3655.3968595182027, 3693.113652723327, 2544.4275038116275, 2653.031643450127, 2660.255128564005, 2662.7470390312888, 2663.3741070848337, 453.0621613260585, 453.150343106675, 456.83325586161476, 457.83517933543897, 464.7374233558029, 462.52048906342185, 468.82184769787943, 475.8263233919594, 499.92751051098116, 523.6596212461855, 6529.477641788645, 6632.336766323329, 6703.832404189934, 6961.37347638715, 7118.430525557943, 437.7570448401168, 507.2619338861947, 508.6032894342419, 513.2429666242263, 523.8847453560211, 3273.8228184854543, 3348.2968576599187, 3372.1401042733946, 3387.932241355159, 3459.2562599040825, 56562.0, 57030.0, 91413.08586914046, 96726.51636741772, 101472.0, 75239.0, 82462.58768706118, 83445.16554834496, 85044.57477712695, 85594.0, 68186.65906670534, 69476.6526167376, 73762.26237737661, 75568.62215688886, 76005.0, 7874.173211812677, 8058.194180581895, 8149.171954959162, 8695.86956195645, 8751.781205469913, 43023.354649679655, 43325.0, 43729.0, 44501.33248001218, 44869.775651121534, 59359.40640593539, 60893.69553152383, 62086.0, 62566.687166563876, 64888.35111649012, 10865.728356791144, 11284.548618055185, 11354.59759777775, 11708.063354931415, 11744.823827642422, 11204.775904482029, 11263.605773797877, 11403.771924561297, 11735.295882247092, 12174.695632609255, 11524.366159860952, 11750.353730544903, 11900.642980710247, 12438.19151755133, 12465.93767031159, 11046.889531104585, 11252.943735281271, 11324.150688698215, 11859.466324015268, 11881.0, 11118.110551155727, 11175.329480231212, 11233.494492747683, 11348.432578371214, 11556.47995840206, 9331.113544213289, 9362.539897187138, 9860.852087218555, 9887.456189909624, 9906.554205060645, 9557.61769529211, 9876.654317098866, 10013.136044811088, 10072.446015469219, 10094.24293178], "value": 6299.506264122244, "unit": "score"}], "name": "dromaeo_css", "value": 6193.703046485653, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 114.0, 112.0, 114.0, 114.0, 113.0, 113.0, 112.0, 112.0, 112.0], "value": 112.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 124.0, 123.0, 191.0, 125.0, 128.0, 144.0, 123.0, 175.0, 128.0], "value": 140.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 161.0, 162.0, 162.0, 162.0, 161.0, 162.0, 162.0, 162.0, 162.0], "value": 161.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 76.0, 76.0, 121.0, 75.0, 77.0, 74.0, 75.0, 106.0, 75.0], "value": 82.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 86.0, 127.0, 84.0, 84.0, 85.0, 126.0, 85.0, 86.0, 135.0], "value": 98.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 116.0, 117.0, 117.0, 117.0, 116.0, 116.0, 117.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 145.0, 146.0, 147.0, 146.0, 147.0, 146.0, 146.0], "value": 146.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 96.0, 96.0, 95.0, 95.0, 94.0, 95.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 61.0, 61.0, 60.0, 61.0, 72.0, 59.0, 59.0, 60.0, 61.0], "value": 61.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 65.0, 66.0, 65.0, 66.0, 66.0, 66.0, 65.0, 66.0, 65.0], "value": 65.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 80.0, 79.0, 82.0, 84.0, 80.0, 78.0, 84.0, 80.0, 83.0], "value": 81.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 131.0, 128.0, 132.0, 127.0, 128.0, 131.0, 130.0, 174.0, 133.0], "value": 134.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 162.0, 162.0, 160.0, 162.0, 160.0, 161.0, 162.0, 165.0, 163.0], "value": 161.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 72.0, 68.0, 66.0, 68.0, 66.0, 66.0, 67.0, 69.0, 70.0], "value": 67.9, "unit": "score"}], "name": "kraken", "value": 1526.3000000000002, "alertThreshold": 2.0}]} 16:19:48 INFO - Return code: 0 16:19:48 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:19:48 INFO - # TBPL SUCCESS # 16:19:48 INFO - Running post-action listener: _resource_record_post_action 16:19:48 INFO - [mozharness: 2016-07-21 23:19:48.958144Z] Finished run-tests step (success) 16:19:48 INFO - Running post-run listener: _resource_record_post_run 16:19:49 INFO - Total resource usage - Wall time: 1201s; CPU: 13.0%; Read bytes: 12288; Write bytes: 536211456; Read time: 136; Write time: 322636 16:19:49 INFO - TinderboxPrint: CPU usage
13.0% 16:19:49 INFO - TinderboxPrint: I/O read bytes / time
12,288 / 136 16:19:49 INFO - TinderboxPrint: I/O write bytes / time
536,211,456 / 322,636 16:19:49 INFO - TinderboxPrint: CPU idle
8,337.0 (87.0%) 16:19:49 INFO - TinderboxPrint: CPU user
1,204.3 (12.6%) 16:19:49 INFO - TinderboxPrint: Swap in / out
0 / 0 16:19:49 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 247119872; Read time: 0; Write time: 173560 16:19:49 INFO - run-tests - Wall time: 1185s; CPU: 13.0%; Read bytes: 8192; Write bytes: 289091584; Read time: 124; Write time: 149076 16:19:49 INFO - Running post-run listener: _upload_blobber_files 16:19:49 INFO - Blob upload gear active. 16:19:49 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:19:49 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 16:19:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:19:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:19:50 INFO - (blobuploader) - INFO - Open directory for files ... 16:19:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:19:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:19:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:19:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:19:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:19:50 INFO - (blobuploader) - INFO - Done attempting. 16:19:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:19:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:19:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:20:02 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:20:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:20:02 INFO - (blobuploader) - INFO - Done attempting. 16:20:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:20:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:20:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:20:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:20:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:20:03 INFO - (blobuploader) - INFO - Done attempting. 16:20:03 INFO - (blobuploader) - INFO - Iteration through files over. 16:20:03 INFO - Return code: 0 16:20:03 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:20:03 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:20:03 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/01877ad809a407f27f90ba472aaa5fed5f56b6c0aec8e6e1ee3fe8ad7497c7b7cb28b37a5039ff71debe2a74bff636a172eb63ab838a9d78eedae36b93077f9e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a462fb949caa0a4f4cea78ca4c1cb29981442879ea64d9358a20d8244fc611db76ad9fa5739c792fa093ffcea52e3c25e89e38e2600075265064eefe58f814ee", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/37545e4de386deaa2392adb74ef9608dda563b57ba0b6d87f1a82f2eb22cbf073c05e18fb6e50e0c687f7e53ad8ec35b828c980329864d7a00aae246b8638473"} 16:20:03 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:20:03 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:20:03 INFO - Contents: 16:20:03 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/01877ad809a407f27f90ba472aaa5fed5f56b6c0aec8e6e1ee3fe8ad7497c7b7cb28b37a5039ff71debe2a74bff636a172eb63ab838a9d78eedae36b93077f9e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a462fb949caa0a4f4cea78ca4c1cb29981442879ea64d9358a20d8244fc611db76ad9fa5739c792fa093ffcea52e3c25e89e38e2600075265064eefe58f814ee", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/37545e4de386deaa2392adb74ef9608dda563b57ba0b6d87f1a82f2eb22cbf073c05e18fb6e50e0c687f7e53ad8ec35b828c980329864d7a00aae246b8638473"} 16:20:03 INFO - Running post-run listener: copy_logs_to_upload_dir 16:20:03 INFO - Copying logs to upload dir... 16:20:03 INFO - mkdir: /builds/slave/test/build/upload/logs 16:20:03 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1264.954464 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2016-07-21 16:20:03.080713) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-21 16:20:03.083715) ========= 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=00204705a9daa57ed339a427000001b9-1469140399.960441-1326953982 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/01877ad809a407f27f90ba472aaa5fed5f56b6c0aec8e6e1ee3fe8ad7497c7b7cb28b37a5039ff71debe2a74bff636a172eb63ab838a9d78eedae36b93077f9e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a462fb949caa0a4f4cea78ca4c1cb29981442879ea64d9358a20d8244fc611db76ad9fa5739c792fa093ffcea52e3c25e89e38e2600075265064eefe58f814ee", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/37545e4de386deaa2392adb74ef9608dda563b57ba0b6d87f1a82f2eb22cbf073c05e18fb6e50e0c687f7e53ad8ec35b828c980329864d7a00aae246b8638473"} build_url:https://queue.taskcluster.net/v1/task/JYRAzwcmSci5-mG6CSINfg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011536 build_url: 'https://queue.taskcluster.net/v1/task/JYRAzwcmSci5-mG6CSINfg/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/Firefox-Non-PGO/sha512/01877ad809a407f27f90ba472aaa5fed5f56b6c0aec8e6e1ee3fe8ad7497c7b7cb28b37a5039ff71debe2a74bff636a172eb63ab838a9d78eedae36b93077f9e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a462fb949caa0a4f4cea78ca4c1cb29981442879ea64d9358a20d8244fc611db76ad9fa5739c792fa093ffcea52e3c25e89e38e2600075265064eefe58f814ee", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/37545e4de386deaa2392adb74ef9608dda563b57ba0b6d87f1a82f2eb22cbf073c05e18fb6e50e0c687f7e53ad8ec35b828c980329864d7a00aae246b8638473"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-21 16:20:03.108795) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 16:20:03.109090) ========= 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=00204705a9daa57ed339a427000001b9-1469140399.960441-1326953982 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004516 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 16:20:03.192084) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-21 16:20:03.192416) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-21 16:20:03.192696) ========= ========= Total master_lag: 0.18 =========