builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-020 starttime: 1468500511.18 results: success (0) buildid: 20160714030208 builduid: 092f3aad2b1b4eaf98c514ea48b51436 revision: 08f8a5aacd8308a73f6040fe522be7ba38497561 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-14 05:48:31.183833) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-14 05:48:31.184283) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-14 05:48:31.184568) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-14 05:48:31.200723) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-14 05:48:31.201034) ========= 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=af724c981e2a0f424dbabf99000001e7-1468500511.38650-944110783 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-14 05:48:31-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.6M=0s 2016-07-14 05:48:31 (46.6 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.091033 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-14 05:48:31.306671) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-14 05:48:31.306983) ========= 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=af724c981e2a0f424dbabf99000001e7-1468500511.38650-944110783 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014823 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-14 05:48:31.356580) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-14 05:48:31.356928) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 08f8a5aacd8308a73f6040fe522be7ba38497561 --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 08f8a5aacd8308a73f6040fe522be7ba38497561 --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=af724c981e2a0f424dbabf99000001e7-1468500511.38650-944110783 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-14 05:48:32,079 truncating revision to first 12 chars 2016-07-14 05:48:32,079 Setting DEBUG logging. 2016-07-14 05:48:32,080 attempt 1/10 2016-07-14 05:48:32,080 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/08f8a5aacd83?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-14 05:48:33,389 unpacking tar archive at: mozilla-central-08f8a5aacd83/testing/mozharness/ program finished with exit code 0 elapsedTime=1.686700 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-14 05:48:33.057944) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-14 05:48:33.058287) ========= script_repo_revision: 08f8a5aacd8308a73f6040fe522be7ba38497561 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-14 05:48:33.058714) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-14 05:48:33.058983) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-14 05:48:33.069938) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 31 secs) (at 2016-07-14 05:48:33.070348) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] 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=af724c981e2a0f424dbabf99000001e7-1468500511.38650-944110783 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:48:33 INFO - MultiFileLogger online at 20160714 05:48:33 in /builds/slave/test 05:48:33 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 05:48:33 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:48:33 INFO - {'append_to_log': False, 05:48:33 INFO - 'base_work_dir': '/builds/slave/test', 05:48:33 INFO - 'blob_upload_branch': 'Firefox', 05:48:33 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:48:33 INFO - 'branch': 'Firefox', 05:48:33 INFO - 'buildbot_json_path': 'buildprops.json', 05:48:33 INFO - 'config_files': ('talos/linux_config.py',), 05:48:33 INFO - 'default_actions': ('clobber', 05:48:33 INFO - 'read-buildbot-config', 05:48:33 INFO - 'download-and-extract', 05:48:33 INFO - 'populate-webroot', 05:48:33 INFO - 'create-virtualenv', 05:48:33 INFO - 'install', 05:48:33 INFO - 'run-tests'), 05:48:33 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:48:33 INFO - 'download_minidump_stackwalk': True, 05:48:33 INFO - 'download_symbols': 'ondemand', 05:48:33 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:48:33 INFO - 'tooltool.py': '/tools/tooltool.py', 05:48:33 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:48:33 INFO - '/tools/misc-python/virtualenv.py')}, 05:48:33 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:48:33 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:48:33 INFO - 'installer_path': 'installer.exe', 05:48:33 INFO - 'log_level': 'info', 05:48:33 INFO - 'log_name': 'talos', 05:48:33 INFO - 'log_to_console': True, 05:48:33 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:48:33 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:48:33 INFO - 'opt_config_files': (), 05:48:33 INFO - 'pip_index': False, 05:48:33 INFO - 'sps_profile': False, 05:48:33 INFO - 'sps_profile_interval': 0, 05:48:33 INFO - 'suite': 'dromaeojs', 05:48:33 INFO - 'system_bits': '32', 05:48:33 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:48:33 INFO - 'title': 'talos-linux64-ix-020', 05:48:33 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:48:33 INFO - 'use_talos_json': True, 05:48:33 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:48:33 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:48:33 INFO - 'work_dir': 'build'} 05:48:33 INFO - ##### 05:48:33 INFO - ##### Running clobber step. 05:48:33 INFO - ##### 05:48:33 INFO - Running pre-action listener: _resource_record_pre_action 05:48:33 INFO - Running main action method: clobber 05:48:33 INFO - rmtree: /builds/slave/test/build 05:48:33 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:48:34 INFO - Running post-action listener: _resource_record_post_action 05:48:34 INFO - ##### 05:48:34 INFO - ##### Finished clobber step (success) 05:48:34 INFO - ##### 05:48:34 INFO - ##### 05:48:34 INFO - ##### Running read-buildbot-config step. 05:48:34 INFO - ##### 05:48:34 INFO - Running pre-action listener: _resource_record_pre_action 05:48:34 INFO - Running main action method: read_buildbot_config 05:48:34 INFO - Using buildbot properties: 05:48:34 INFO - { 05:48:34 INFO - "project": "", 05:48:34 INFO - "product": "firefox", 05:48:34 INFO - "script_repo_revision": "production", 05:48:34 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 05:48:34 INFO - "repository": "", 05:48:34 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 05:48:34 INFO - "buildid": "20160714030208", 05:48:34 INFO - "stage_platform": "linux64-pgo", 05:48:34 INFO - "basedir": "/builds/slave/test", 05:48:34 INFO - "nightly_build": "True", 05:48:34 INFO - "buildnumber": 387, 05:48:34 INFO - "slavename": "talos-linux64-ix-020", 05:48:34 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 05:48:34 INFO - "platform": "ubuntu64_hw", 05:48:34 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 05:48:34 INFO - "branch": "mozilla-central", 05:48:34 INFO - "repo_path": "mozilla-central", 05:48:34 INFO - "revision": "08f8a5aacd8308a73f6040fe522be7ba38497561", 05:48:34 INFO - "pgo_build": "True", 05:48:34 INFO - "builduid": "092f3aad2b1b4eaf98c514ea48b51436", 05:48:34 INFO - "slavebuilddir": "test" 05:48:34 INFO - } 05:48:34 INFO - Found installer url https://queue.taskcluster.net/v1/task/cEl2NsG-Qh6_8WYWHMNqqA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 05:48:34 INFO - Running post-action listener: _resource_record_post_action 05:48:34 INFO - ##### 05:48:34 INFO - ##### Finished read-buildbot-config step (success) 05:48:34 INFO - ##### 05:48:34 INFO - ##### 05:48:34 INFO - ##### Running download-and-extract step. 05:48:34 INFO - ##### 05:48:34 INFO - Running pre-action listener: _resource_record_pre_action 05:48:34 INFO - Running main action method: download_and_extract 05:48:34 INFO - mkdir: /builds/slave/test/build/tests 05:48:34 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')]} 05:48:34 INFO - https://queue.taskcluster.net/v1/task/cEl2NsG-Qh6_8WYWHMNqqA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 05:48:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cEl2NsG-Qh6_8WYWHMNqqA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 05:48:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cEl2NsG-Qh6_8WYWHMNqqA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 05:48:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cEl2NsG-Qh6_8WYWHMNqqA/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 05:48:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cEl2NsG-Qh6_8WYWHMNqqA/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 05:48:35 INFO - Downloaded 1361 bytes. 05:48:35 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 05:48:35 INFO - Using the following test package requirements: 05:48:35 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 05:48:35 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 05:48:35 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 05:48:35 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 05:48:35 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 05:48:35 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 05:48:35 INFO - u'jsshell-linux-x86_64.zip'], 05:48:35 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 05:48:35 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 05:48:35 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 05:48:35 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 05:48:35 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 05:48:35 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 05:48:35 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 05:48:35 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 05:48:35 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 05:48:35 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 05:48:35 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 05:48:35 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 05:48:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 05:48:35 INFO - https://queue.taskcluster.net/v1/task/cEl2NsG-Qh6_8WYWHMNqqA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:48:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cEl2NsG-Qh6_8WYWHMNqqA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 05:48:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cEl2NsG-Qh6_8WYWHMNqqA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 05:48:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cEl2NsG-Qh6_8WYWHMNqqA/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 05:48:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cEl2NsG-Qh6_8WYWHMNqqA/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 05:48:36 INFO - Downloaded 24326411 bytes. 05:48:36 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'] 05:48:36 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 05:48:37 INFO - Return code: 0 05:48:37 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 05:48:37 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')]} 05:48:37 INFO - https://queue.taskcluster.net/v1/task/cEl2NsG-Qh6_8WYWHMNqqA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:48:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cEl2NsG-Qh6_8WYWHMNqqA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 05:48:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cEl2NsG-Qh6_8WYWHMNqqA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 05:48:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cEl2NsG-Qh6_8WYWHMNqqA/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 05:48:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cEl2NsG-Qh6_8WYWHMNqqA/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 05:48:38 INFO - Downloaded 24326411 bytes. 05:48:38 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'] 05:48:38 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 05:48:39 INFO - Return code: 0 05:48: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')]} 05:48:39 INFO - https://queue.taskcluster.net/v1/task/cEl2NsG-Qh6_8WYWHMNqqA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 05:48:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cEl2NsG-Qh6_8WYWHMNqqA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 05:48:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cEl2NsG-Qh6_8WYWHMNqqA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 05:48:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cEl2NsG-Qh6_8WYWHMNqqA/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 05:48:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cEl2NsG-Qh6_8WYWHMNqqA/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 05:48:41 INFO - Downloaded 14403317 bytes. 05:48:41 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'] 05:48:41 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 05:48:41 INFO - Return code: 0 05:48:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 05:48:41 INFO - https://queue.taskcluster.net/v1/task/cEl2NsG-Qh6_8WYWHMNqqA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 05:48:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cEl2NsG-Qh6_8WYWHMNqqA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 05:48:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cEl2NsG-Qh6_8WYWHMNqqA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 05:48:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cEl2NsG-Qh6_8WYWHMNqqA/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 05:48:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cEl2NsG-Qh6_8WYWHMNqqA/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 05:48:43 INFO - Downloaded 60792860 bytes. 05:48:43 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/cEl2NsG-Qh6_8WYWHMNqqA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 05:48:43 INFO - mkdir: /builds/slave/test/properties 05:48:43 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:48:43 INFO - Writing to file /builds/slave/test/properties/build_url 05:48:43 INFO - Contents: 05:48:43 INFO - build_url:https://queue.taskcluster.net/v1/task/cEl2NsG-Qh6_8WYWHMNqqA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 05:48:44 INFO - Running post-action listener: _resource_record_post_action 05:48:44 INFO - Running post-action listener: set_extra_try_arguments 05:48:44 INFO - ##### 05:48:44 INFO - ##### Finished download-and-extract step (success) 05:48:44 INFO - ##### 05:48:44 INFO - ##### 05:48:44 INFO - ##### Running populate-webroot step. 05:48:44 INFO - ##### 05:48:44 INFO - Running pre-action listener: _resource_record_pre_action 05:48:44 INFO - Running main action method: populate_webroot 05:48:44 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:48:44 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:48:44 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:48:44 INFO - '--tppagecycles', 05:48:44 INFO - '7'], 05:48:44 INFO - 'tests': ['tsvgm']}}, 05:48:44 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:48:44 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:48:44 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:48:44 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:48:44 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:48:44 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:48:44 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:48:44 INFO - 'talos_options': ['--disable-e10s'], 05:48:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:48:44 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:48:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:48:44 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:48:44 INFO - 'talos_options': ['--disable-e10s'], 05:48:44 INFO - 'tests': ['damp', 'tps']}, 05:48:44 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:48:44 INFO - 'tests': ['damp', 'tps']}, 05:48:44 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:48:44 INFO - 'tests': ['dromaeo_dom']}, 05:48:44 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:48:44 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:48:44 INFO - 'tests': ['basic_compositor_video']}, 05:48:44 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:48:44 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:48:44 INFO - 'tests': ['a11yr', 05:48:44 INFO - 'ts_paint', 05:48:44 INFO - 'tpaint', 05:48:44 INFO - 'sessionrestore', 05:48:44 INFO - 'sessionrestore_no_auto_restore', 05:48:44 INFO - 'tabpaint']}, 05:48:44 INFO - 'other-e10s': {'tests': ['a11yr', 05:48:44 INFO - 'ts_paint', 05:48:44 INFO - 'tpaint', 05:48:44 INFO - 'sessionrestore', 05:48:44 INFO - 'sessionrestore_no_auto_restore', 05:48:44 INFO - 'tabpaint']}, 05:48:44 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:48:44 INFO - 'ts_paint', 05:48:44 INFO - 'tpaint', 05:48:44 INFO - 'sessionrestore', 05:48:44 INFO - 'sessionrestore_no_auto_restore', 05:48:44 INFO - 'tabpaint']}, 05:48:44 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:48:44 INFO - 'ts_paint', 05:48:44 INFO - 'tpaint', 05:48:44 INFO - 'sessionrestore', 05:48:44 INFO - 'sessionrestore_no_auto_restore', 05:48:44 INFO - 'tabpaint']}, 05:48:44 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:48:44 INFO - 'tests': ['a11yr', 05:48:44 INFO - 'ts_paint', 05:48:44 INFO - 'tpaint', 05:48:44 INFO - 'sessionrestore', 05:48:44 INFO - 'sessionrestore_no_auto_restore', 05:48:44 INFO - 'tabpaint']}, 05:48:44 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:48:44 INFO - 'tests': ['a11yr', 05:48:44 INFO - 'ts_paint', 05:48:44 INFO - 'tpaint', 05:48:44 INFO - 'sessionrestore', 05:48:44 INFO - 'sessionrestore_no_auto_restore', 05:48:44 INFO - 'tabpaint']}, 05:48:44 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:48:44 INFO - 'tests': ['tsvgx', 05:48:44 INFO - 'tsvgr_opacity', 05:48:44 INFO - 'tart', 05:48:44 INFO - 'tscrollx', 05:48:44 INFO - 'cart']}, 05:48:44 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:48:44 INFO - 'tsvgr_opacity', 05:48:44 INFO - 'tart', 05:48:44 INFO - 'tscrollx', 05:48:44 INFO - 'cart']}, 05:48:44 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:48:44 INFO - 'talos_options': ['--disable-e10s'], 05:48:44 INFO - 'tests': ['tp5o']}, 05:48:44 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:48:44 INFO - 'tests': ['tp5o']}, 05:48:44 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:48:44 INFO - 'talos_options': ['--disable-e10s', 05:48:44 INFO - '--xperf_path', 05:48:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:48:44 INFO - 'tests': ['tp5n']}, 05:48:44 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:48:44 INFO - 'talos_options': ['--xperf_path', 05:48:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:48:44 INFO - 'tests': ['tp5n']}}, 05:48:44 INFO - 'talos.zip': {'path': '', 05:48:44 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:48:44 INFO - Running post-action listener: _resource_record_post_action 05:48:44 INFO - ##### 05:48:44 INFO - ##### Finished populate-webroot step (success) 05:48:44 INFO - ##### 05:48:44 INFO - ##### 05:48:44 INFO - ##### Running create-virtualenv step. 05:48:44 INFO - ##### 05:48:44 INFO - Running pre-action listener: _resource_record_pre_action 05:48:44 INFO - Running main action method: create_virtualenv 05:48:44 INFO - Creating virtualenv /builds/slave/test/build/venv 05:48:44 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 05:48:44 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:48:44 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:48:44 INFO - Using real prefix '/usr' 05:48:44 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:48:45 INFO - Installing distribute.............................................................................................................................................................................................done. 05:48:47 INFO - Installing pip.................done. 05:48:47 INFO - Return code: 0 05:48:47 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:48: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')]} 05:48:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:48:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:48: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')]} 05:48:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:48:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:48: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2369030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24cc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x262a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26283b0>, '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 0x26292b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2629740>, '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': 'af724c981e2a0f424dbabf99000001e7-1468500511.38650-944110783', '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 05:48: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', 'pip>=1.5'] in /builds/slave/test/build 05:48: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 pip>=1.5 05:48:47 INFO - Using env: {'DISPLAY': ':0', 05:48:47 INFO - 'HOME': '/home/cltbld', 05:48:47 INFO - 'LANG': 'en_US.UTF-8', 05:48:47 INFO - 'LANGUAGE': 'en_US:en', 05:48:47 INFO - 'LOGNAME': 'cltbld', 05:48:47 INFO - 'MAIL': '/var/mail/cltbld', 05:48:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:48:47 INFO - 'MOZ_NO_REMOTE': '1', 05:48:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:48:47 INFO - 'NO_EM_RESTART': '1', 05:48:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:48:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:48:47 INFO - 'PWD': '/builds/slave/test', 05:48:47 INFO - 'SHELL': '/bin/bash', 05:48:47 INFO - 'SHLVL': '1', 05:48:47 INFO - 'TERM': 'linux', 05:48:47 INFO - 'TMOUT': '86400', 05:48:47 INFO - 'USER': 'cltbld', 05:48:47 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1468500511.38650-944110783', 05:48:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:48:47 INFO - '_': '/tools/buildbot/bin/python'} 05:48:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:48:47 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 05:48:47 INFO - Cleaning up... 05:48:47 INFO - Return code: 0 05:48:47 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:48: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')]} 05:48:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:48:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:48: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')]} 05:48:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:48:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:48: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', '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 0x2369030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24cc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x262a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26283b0>, '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 0x26292b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2629740>, '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': 'af724c981e2a0f424dbabf99000001e7-1468500511.38650-944110783', '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 05:48: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', 'psutil>=3.1.1'] in /builds/slave/test/build 05:48: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 psutil>=3.1.1 05:48:47 INFO - Using env: {'DISPLAY': ':0', 05:48:47 INFO - 'HOME': '/home/cltbld', 05:48:47 INFO - 'LANG': 'en_US.UTF-8', 05:48:47 INFO - 'LANGUAGE': 'en_US:en', 05:48:47 INFO - 'LOGNAME': 'cltbld', 05:48:47 INFO - 'MAIL': '/var/mail/cltbld', 05:48:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:48:47 INFO - 'MOZ_NO_REMOTE': '1', 05:48:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:48:47 INFO - 'NO_EM_RESTART': '1', 05:48:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:48:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:48:47 INFO - 'PWD': '/builds/slave/test', 05:48:47 INFO - 'SHELL': '/bin/bash', 05:48:47 INFO - 'SHLVL': '1', 05:48:47 INFO - 'TERM': 'linux', 05:48:47 INFO - 'TMOUT': '86400', 05:48:47 INFO - 'USER': 'cltbld', 05:48:47 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1468500511.38650-944110783', 05:48:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:48:47 INFO - '_': '/tools/buildbot/bin/python'} 05:48:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:48:47 INFO - Downloading/unpacking psutil>=3.1.1 05:48:47 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 05:48:47 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 05:48:47 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 05:48:47 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 05:48:47 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 05:48:47 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 05:48:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:48:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:48:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:48:49 INFO - Installing collected packages: psutil 05:48:49 INFO - Running setup.py install for psutil 05:48:49 INFO - building 'psutil._psutil_linux' extension 05:48:49 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 05:48:49 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 05:48:49 INFO - building 'psutil._psutil_posix' extension 05:48:49 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 05:48:49 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 05:48:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:48:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:48:50 INFO - Successfully installed psutil 05:48:50 INFO - Cleaning up... 05:48:50 INFO - Return code: 0 05:48:50 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:48:50 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')]} 05:48:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:48:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:48:50 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')]} 05:48:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:48:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:48:50 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 0x2369030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24cc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x262a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26283b0>, '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 0x26292b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2629740>, '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': 'af724c981e2a0f424dbabf99000001e7-1468500511.38650-944110783', '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 05:48:50 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 05:48:50 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 05:48:50 INFO - Using env: {'DISPLAY': ':0', 05:48:50 INFO - 'HOME': '/home/cltbld', 05:48:50 INFO - 'LANG': 'en_US.UTF-8', 05:48:50 INFO - 'LANGUAGE': 'en_US:en', 05:48:50 INFO - 'LOGNAME': 'cltbld', 05:48:50 INFO - 'MAIL': '/var/mail/cltbld', 05:48:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:48:50 INFO - 'MOZ_NO_REMOTE': '1', 05:48:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:48:50 INFO - 'NO_EM_RESTART': '1', 05:48:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:48:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:48:50 INFO - 'PWD': '/builds/slave/test', 05:48:50 INFO - 'SHELL': '/bin/bash', 05:48:50 INFO - 'SHLVL': '1', 05:48:50 INFO - 'TERM': 'linux', 05:48:50 INFO - 'TMOUT': '86400', 05:48:50 INFO - 'USER': 'cltbld', 05:48:50 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1468500511.38650-944110783', 05:48:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:48:50 INFO - '_': '/tools/buildbot/bin/python'} 05:48:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:48:50 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:48:50 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 05:48:50 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 05:48:50 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 05:48:50 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 05:48:50 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 05:48:50 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 05:48:52 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:48:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:48:52 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:48:52 INFO - Installing collected packages: mozsystemmonitor 05:48:52 INFO - Running setup.py install for mozsystemmonitor 05:48:52 INFO - Successfully installed mozsystemmonitor 05:48:52 INFO - Cleaning up... 05:48:52 INFO - Return code: 0 05:48:52 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:48:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 05:48:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:48:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:48:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 05:48:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:48:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:48:52 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 0x2369030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24cc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x262a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26283b0>, '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 0x26292b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2629740>, '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': 'af724c981e2a0f424dbabf99000001e7-1468500511.38650-944110783', '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 05:48:52 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 05:48:52 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 05:48:52 INFO - Using env: {'DISPLAY': ':0', 05:48:52 INFO - 'HOME': '/home/cltbld', 05:48:52 INFO - 'LANG': 'en_US.UTF-8', 05:48:52 INFO - 'LANGUAGE': 'en_US:en', 05:48:52 INFO - 'LOGNAME': 'cltbld', 05:48:52 INFO - 'MAIL': '/var/mail/cltbld', 05:48:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:48:52 INFO - 'MOZ_NO_REMOTE': '1', 05:48:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:48:52 INFO - 'NO_EM_RESTART': '1', 05:48:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:48:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:48:52 INFO - 'PWD': '/builds/slave/test', 05:48:52 INFO - 'SHELL': '/bin/bash', 05:48:52 INFO - 'SHLVL': '1', 05:48:52 INFO - 'TERM': 'linux', 05:48:52 INFO - 'TMOUT': '86400', 05:48:52 INFO - 'USER': 'cltbld', 05:48:52 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1468500511.38650-944110783', 05:48:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:48:52 INFO - '_': '/tools/buildbot/bin/python'} 05:48:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:48:52 INFO - Downloading/unpacking jsonschema==2.5.1 05:48:52 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 05:48:52 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 05:48:52 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 05:48:52 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 05:48:52 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 05:48:52 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 05:48:54 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:48:54 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:48:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:48:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:48:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:48:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:48:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:48:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:48:54 INFO - Downloading functools32-3.2.3-2.tar.gz 05:48:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:48:54 INFO - warning: no files found matching '*.txt' 05:48:54 INFO - no previously-included directories found matching 'build' 05:48:54 INFO - no previously-included directories found matching 'dist' 05:48:54 INFO - no previously-included directories found matching '.git*' 05:48:54 INFO - Installing collected packages: jsonschema, functools32 05:48:54 INFO - Running setup.py install for functools32 05:48:54 INFO - warning: no files found matching '*.txt' 05:48:54 INFO - no previously-included directories found matching 'build' 05:48:54 INFO - no previously-included directories found matching 'dist' 05:48:54 INFO - no previously-included directories found matching '.git*' 05:48:55 INFO - Successfully installed jsonschema functools32 05:48:55 INFO - Cleaning up... 05:48:55 INFO - Return code: 0 05:48:55 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:48:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:48:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:48:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:48:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:48:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:48:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:48:55 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 0x2369030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24cc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x262a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26283b0>, '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 0x26292b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2629740>, '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': 'af724c981e2a0f424dbabf99000001e7-1468500511.38650-944110783', '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 05:48:55 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 05:48:55 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 05:48:55 INFO - Using env: {'DISPLAY': ':0', 05:48:55 INFO - 'HOME': '/home/cltbld', 05:48:55 INFO - 'LANG': 'en_US.UTF-8', 05:48:55 INFO - 'LANGUAGE': 'en_US:en', 05:48:55 INFO - 'LOGNAME': 'cltbld', 05:48:55 INFO - 'MAIL': '/var/mail/cltbld', 05:48:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:48:55 INFO - 'MOZ_NO_REMOTE': '1', 05:48:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:48:55 INFO - 'NO_EM_RESTART': '1', 05:48:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:48:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:48:55 INFO - 'PWD': '/builds/slave/test', 05:48:55 INFO - 'SHELL': '/bin/bash', 05:48:55 INFO - 'SHLVL': '1', 05:48:55 INFO - 'TERM': 'linux', 05:48:55 INFO - 'TMOUT': '86400', 05:48:55 INFO - 'USER': 'cltbld', 05:48:55 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1468500511.38650-944110783', 05:48:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:48:55 INFO - '_': '/tools/buildbot/bin/python'} 05:48:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:48:55 INFO - Downloading/unpacking blobuploader==1.2.4 05:48:55 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 05:48:55 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 05:48:55 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 05:48:55 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 05:48:55 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 05:48:55 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 05:48:57 INFO - Downloading blobuploader-1.2.4.tar.gz 05:48:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:48:57 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:48:57 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 05:48:57 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 05:48:57 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 05:48:57 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 05:48:57 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 05:48:57 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 05:48:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:48:57 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:48:57 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 05:48:57 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 05:48:57 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 05:48:57 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 05:48:57 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 05:48:57 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 05:48:57 INFO - Downloading docopt-0.6.1.tar.gz 05:48:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:48:57 INFO - Installing collected packages: blobuploader, requests, docopt 05:48:57 INFO - Running setup.py install for blobuploader 05:48:58 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:48:58 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:48:58 INFO - Running setup.py install for requests 05:48:58 INFO - Running setup.py install for docopt 05:48:58 INFO - Successfully installed blobuploader requests docopt 05:48:58 INFO - Cleaning up... 05:48:58 INFO - Return code: 0 05:48:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:48: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')]} 05:48:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:48:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:48: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')]} 05:48:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:48:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:48:58 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 0x2369030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24cc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x262a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26283b0>, '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 0x26292b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2629740>, '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': 'af724c981e2a0f424dbabf99000001e7-1468500511.38650-944110783', '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 05:48:58 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 05:48:58 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 05:48:58 INFO - Using env: {'DISPLAY': ':0', 05:48:58 INFO - 'HOME': '/home/cltbld', 05:48:58 INFO - 'LANG': 'en_US.UTF-8', 05:48:58 INFO - 'LANGUAGE': 'en_US:en', 05:48:58 INFO - 'LOGNAME': 'cltbld', 05:48:58 INFO - 'MAIL': '/var/mail/cltbld', 05:48:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:48:58 INFO - 'MOZ_NO_REMOTE': '1', 05:48:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:48:58 INFO - 'NO_EM_RESTART': '1', 05:48:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:48:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:48:58 INFO - 'PWD': '/builds/slave/test', 05:48:58 INFO - 'SHELL': '/bin/bash', 05:48:58 INFO - 'SHLVL': '1', 05:48:58 INFO - 'TERM': 'linux', 05:48:58 INFO - 'TMOUT': '86400', 05:48:58 INFO - 'USER': 'cltbld', 05:48:58 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1468500511.38650-944110783', 05:48:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:48:58 INFO - '_': '/tools/buildbot/bin/python'} 05:48:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:48:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:48:58 INFO - Running setup.py (path:/tmp/pip-RZsdft-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:48:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:48:58 INFO - Running setup.py (path:/tmp/pip-7rRdfh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:48:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:48:58 INFO - Running setup.py (path:/tmp/pip-Z96HL0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:48:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:48:58 INFO - Running setup.py (path:/tmp/pip-PPOJQ2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:48:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:48:59 INFO - Running setup.py (path:/tmp/pip-BpbaWP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:48:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:48:59 INFO - Running setup.py (path:/tmp/pip-gCJJII-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:48:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:48:59 INFO - Running setup.py (path:/tmp/pip-5J6T6V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:48:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:48:59 INFO - Running setup.py (path:/tmp/pip-Qvv1Xo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:48:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:48:59 INFO - Running setup.py (path:/tmp/pip-mlAcev-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:48:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:48:59 INFO - Running setup.py (path:/tmp/pip-E0FgFA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:48:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:48:59 INFO - Running setup.py (path:/tmp/pip-RCnU0Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:48:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:48:59 INFO - Running setup.py (path:/tmp/pip-UmUeMt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:48:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:48:59 INFO - Running setup.py (path:/tmp/pip-LH2xUz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:48:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:48:59 INFO - Running setup.py (path:/tmp/pip-Ws6rHs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:48:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:48:59 INFO - Running setup.py (path:/tmp/pip-ieilbf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:48:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:48:59 INFO - Running setup.py (path:/tmp/pip-RCnkHn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:48:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:48:59 INFO - Running setup.py (path:/tmp/pip-YPR0xi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:49:00 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:49:00 INFO - Running setup.py install for manifestparser 05:49:00 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:49:00 INFO - Running setup.py install for mozcrash 05:49:00 INFO - Running setup.py install for mozdebug 05:49:00 INFO - Running setup.py install for mozdevice 05:49:00 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:49:00 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:49:00 INFO - Running setup.py install for mozfile 05:49:00 INFO - Running setup.py install for mozhttpd 05:49:00 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:49:00 INFO - Running setup.py install for mozinfo 05:49:00 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:49:00 INFO - Running setup.py install for mozInstall 05:49:00 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:49:00 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:49:00 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:49:00 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:49:00 INFO - Running setup.py install for mozleak 05:49:00 INFO - Running setup.py install for mozlog 05:49:01 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:49:01 INFO - Running setup.py install for moznetwork 05:49:01 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:49:01 INFO - Running setup.py install for mozprocess 05:49:01 INFO - Running setup.py install for mozprofile 05:49:01 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:49:01 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:49:01 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:49:01 INFO - Running setup.py install for mozrunner 05:49:01 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:49:01 INFO - Running setup.py install for mozscreenshot 05:49:01 INFO - Running setup.py install for moztest 05:49:01 INFO - Running setup.py install for mozversion 05:49:01 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:49:01 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:49:01 INFO - Cleaning up... 05:49:02 INFO - Return code: 0 05:49:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:49:02 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')]} 05:49:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:49:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:49:02 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')]} 05:49:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:49:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:49:02 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 0x2369030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24cc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x262a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26283b0>, '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 0x26292b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2629740>, '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': 'af724c981e2a0f424dbabf99000001e7-1468500511.38650-944110783', '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 05:49:02 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 05:49:02 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 05:49:02 INFO - Using env: {'DISPLAY': ':0', 05:49:02 INFO - 'HOME': '/home/cltbld', 05:49:02 INFO - 'LANG': 'en_US.UTF-8', 05:49:02 INFO - 'LANGUAGE': 'en_US:en', 05:49:02 INFO - 'LOGNAME': 'cltbld', 05:49:02 INFO - 'MAIL': '/var/mail/cltbld', 05:49:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:49:02 INFO - 'MOZ_NO_REMOTE': '1', 05:49:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:49:02 INFO - 'NO_EM_RESTART': '1', 05:49:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:49:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:49:02 INFO - 'PWD': '/builds/slave/test', 05:49:02 INFO - 'SHELL': '/bin/bash', 05:49:02 INFO - 'SHLVL': '1', 05:49:02 INFO - 'TERM': 'linux', 05:49:02 INFO - 'TMOUT': '86400', 05:49:02 INFO - 'USER': 'cltbld', 05:49:02 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1468500511.38650-944110783', 05:49:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:49:02 INFO - '_': '/tools/buildbot/bin/python'} 05:49:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:49:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:49:02 INFO - Running setup.py (path:/tmp/pip-0wMgsM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:49:02 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)) 05:49:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:49:02 INFO - Running setup.py (path:/tmp/pip-QrRN2e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:49:02 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)) 05:49:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:49:02 INFO - Running setup.py (path:/tmp/pip-SuxahU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:49:02 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)) 05:49:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:49:02 INFO - Running setup.py (path:/tmp/pip-cv5piC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:49:02 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)) 05:49:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:49:02 INFO - Running setup.py (path:/tmp/pip-CMRpyh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:49:02 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)) 05:49:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:49:02 INFO - Running setup.py (path:/tmp/pip-Xf8Hmq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:49:02 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)) 05:49:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:49:02 INFO - Running setup.py (path:/tmp/pip-Q_Anfo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:49:02 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)) 05:49:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:49:02 INFO - Running setup.py (path:/tmp/pip-qigMpq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:49:02 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)) 05:49:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:49:02 INFO - Running setup.py (path:/tmp/pip-RfTN2X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:49:02 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)) 05:49:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:49:02 INFO - Running setup.py (path:/tmp/pip-uQSryk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:49:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 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)) 05:49:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:49:02 INFO - Running setup.py (path:/tmp/pip-wnQs8Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:49:03 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)) 05:49:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:49:03 INFO - Running setup.py (path:/tmp/pip-BvnK5p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:49:03 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)) 05:49:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:49:03 INFO - Running setup.py (path:/tmp/pip-r3X5Dz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:49:03 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)) 05:49:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:49:03 INFO - Running setup.py (path:/tmp/pip-3ahvDu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:49:03 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)) 05:49:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:49:03 INFO - Running setup.py (path:/tmp/pip-KFhB75-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:49:03 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)) 05:49:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:49:03 INFO - Running setup.py (path:/tmp/pip-1yHe_a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:49:03 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)) 05:49:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:49:03 INFO - Running setup.py (path:/tmp/pip-JMws3b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:49:03 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)) 05:49:03 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)) 05:49:03 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)) 05:49:03 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)) 05:49:03 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)) 05:49:03 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)) 05:49:03 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:49:03 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 05:49:03 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 05:49:03 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 05:49:03 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 05:49:03 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 05:49:03 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 05:49:05 INFO - Downloading blessings-1.6.tar.gz 05:49:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:49:05 INFO - Installing collected packages: blessings 05:49:05 INFO - Running setup.py install for blessings 05:49:05 INFO - Successfully installed blessings 05:49:05 INFO - Cleaning up... 05:49:05 INFO - Return code: 0 05:49:05 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:49:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:49:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:49:05 INFO - Reading from file tmpfile_stdout 05:49:05 INFO - Current package versions: 05:49:05 INFO - argparse == 1.2.1 05:49:05 INFO - blessings == 1.6 05:49:05 INFO - blobuploader == 1.2.4 05:49:05 INFO - docopt == 0.6.1 05:49:05 INFO - functools32 == 3.2.3-2 05:49:05 INFO - jsonschema == 2.5.1 05:49:05 INFO - manifestparser == 1.1 05:49:05 INFO - mozInstall == 1.12 05:49:05 INFO - mozcrash == 0.17 05:49:05 INFO - mozdebug == 0.1 05:49:05 INFO - mozdevice == 0.48 05:49:05 INFO - mozfile == 1.2 05:49:05 INFO - mozhttpd == 0.7 05:49:05 INFO - mozinfo == 0.9 05:49:05 INFO - mozleak == 0.1 05:49:05 INFO - mozlog == 3.2 05:49:05 INFO - moznetwork == 0.27 05:49:05 INFO - mozprocess == 0.23 05:49:05 INFO - mozprofile == 0.28 05:49:05 INFO - mozrunner == 6.12 05:49:05 INFO - mozscreenshot == 0.1 05:49:05 INFO - mozsystemmonitor == 0.3 05:49:05 INFO - moztest == 0.7 05:49:05 INFO - mozversion == 1.4 05:49:05 INFO - psutil == 3.1.1 05:49:05 INFO - requests == 1.2.3 05:49:05 INFO - wsgiref == 0.1.2 05:49:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:49:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 05:49:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:49:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:49:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 05:49:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:49:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:49:05 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 0x2369030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24cc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x262a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26283b0>, '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 0x26292b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2629740>, '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': 'af724c981e2a0f424dbabf99000001e7-1468500511.38650-944110783', '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 05:49:05 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 05:49:05 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 05:49:05 INFO - Using env: {'DISPLAY': ':0', 05:49:05 INFO - 'HOME': '/home/cltbld', 05:49:05 INFO - 'LANG': 'en_US.UTF-8', 05:49:05 INFO - 'LANGUAGE': 'en_US:en', 05:49:05 INFO - 'LOGNAME': 'cltbld', 05:49:05 INFO - 'MAIL': '/var/mail/cltbld', 05:49:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:49:05 INFO - 'MOZ_NO_REMOTE': '1', 05:49:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:49:05 INFO - 'NO_EM_RESTART': '1', 05:49:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:49:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:49:05 INFO - 'PWD': '/builds/slave/test', 05:49:05 INFO - 'SHELL': '/bin/bash', 05:49:05 INFO - 'SHLVL': '1', 05:49:05 INFO - 'TERM': 'linux', 05:49:05 INFO - 'TMOUT': '86400', 05:49:05 INFO - 'USER': 'cltbld', 05:49:05 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1468500511.38650-944110783', 05:49:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:49:05 INFO - '_': '/tools/buildbot/bin/python'} 05:49:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:49:06 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)) 05:49:06 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)) 05:49:06 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)) 05:49:06 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)) 05:49:06 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)) 05:49:06 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)) 05:49:06 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)) 05:49:06 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)) 05:49:06 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)) 05:49:06 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)) 05:49:06 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)) 05:49:06 INFO - Cleaning up... 05:49:06 INFO - Return code: 0 05:49:06 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:49:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:49:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:49:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:49:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:49:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:49:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:49:06 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 0x2369030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24cc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x262a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26283b0>, '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 0x26292b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2629740>, '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': 'af724c981e2a0f424dbabf99000001e7-1468500511.38650-944110783', '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 05:49:06 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 05:49:06 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 05:49:06 INFO - Using env: {'DISPLAY': ':0', 05:49:06 INFO - 'HOME': '/home/cltbld', 05:49:06 INFO - 'LANG': 'en_US.UTF-8', 05:49:06 INFO - 'LANGUAGE': 'en_US:en', 05:49:06 INFO - 'LOGNAME': 'cltbld', 05:49:06 INFO - 'MAIL': '/var/mail/cltbld', 05:49:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:49:06 INFO - 'MOZ_NO_REMOTE': '1', 05:49:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:49:06 INFO - 'NO_EM_RESTART': '1', 05:49:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:49:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:49:06 INFO - 'PWD': '/builds/slave/test', 05:49:06 INFO - 'SHELL': '/bin/bash', 05:49:06 INFO - 'SHLVL': '1', 05:49:06 INFO - 'TERM': 'linux', 05:49:06 INFO - 'TMOUT': '86400', 05:49:06 INFO - 'USER': 'cltbld', 05:49:06 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1468500511.38650-944110783', 05:49:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:49:06 INFO - '_': '/tools/buildbot/bin/python'} 05:49:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:49:06 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:49:06 INFO - Cleaning up... 05:49:06 INFO - Return code: 0 05:49:06 INFO - Running post-action listener: _resource_record_post_action 05:49:06 INFO - Running post-action listener: _start_resource_monitoring 05:49:06 INFO - Starting resource monitoring. 05:49:06 INFO - ##### 05:49:06 INFO - ##### Finished create-virtualenv step (success) 05:49:06 INFO - ##### 05:49:06 INFO - ##### 05:49:06 INFO - ##### Running install step. 05:49:06 INFO - ##### 05:49:06 INFO - Running pre-action listener: _resource_record_pre_action 05:49:06 INFO - Running main action method: install 05:49:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:49:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:49:06 INFO - Reading from file tmpfile_stdout 05:49:06 INFO - Detecting whether we're running mozinstall >=1.0... 05:49:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:49:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:49:06 INFO - Reading from file tmpfile_stdout 05:49:06 INFO - Output received: 05:49:06 INFO - Usage: mozinstall [options] installer 05:49:06 INFO - Options: 05:49:06 INFO - -h, --help show this help message and exit 05:49:06 INFO - -d DEST, --destination=DEST 05:49:06 INFO - Directory to install application into. [default: 05:49:06 INFO - "/builds/slave/test"] 05:49:06 INFO - --app=APP Application being installed. [default: firefox] 05:49:06 INFO - mkdir: /builds/slave/test/build/application 05:49:06 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'] 05:49:06 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 05:49:23 INFO - Reading from file tmpfile_stdout 05:49:23 INFO - Output received: 05:49:23 INFO - /builds/slave/test/build/application/firefox/firefox 05:49:23 INFO - Running post-action listener: _resource_record_post_action 05:49:23 INFO - ##### 05:49:23 INFO - ##### Finished install step (success) 05:49:23 INFO - ##### 05:49:23 INFO - ##### 05:49:23 INFO - ##### Running run-tests step. 05:49:23 INFO - ##### 05:49:23 INFO - Running pre-action listener: _resource_record_pre_action 05:49:23 INFO - Running main action method: run_tests 05:49:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:49:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:49:23 INFO - Python 2.7.3 05:49:23 INFO - Return code: 0 05:49:23 INFO - grabbing minidump binary from tooltool 05:49: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')]} 05:49:23 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 0x26283b0>, '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 0x26292b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2629740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:49:23 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 05:49:23 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 05:49:23 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 05:49:23 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:49:23 INFO - Return code: 0 05:49:23 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 05:49:23 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:49:23 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:49:23 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 05:49:23 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:49:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/cEl2NsG-Qh6_8WYWHMNqqA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-020', '--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 05:49:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/cEl2NsG-Qh6_8WYWHMNqqA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-020 --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 05:49:23 INFO - Using env: {'DISPLAY': ':0', 05:49:23 INFO - 'HOME': '/home/cltbld', 05:49:23 INFO - 'LANG': 'en_US.UTF-8', 05:49:23 INFO - 'LANGUAGE': 'en_US:en', 05:49:23 INFO - 'LOGNAME': 'cltbld', 05:49:23 INFO - 'MAIL': '/var/mail/cltbld', 05:49:23 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:49:23 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 05:49:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:49:23 INFO - 'MOZ_NO_REMOTE': '1', 05:49:23 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:49:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:49:23 INFO - 'NO_EM_RESTART': '1', 05:49:23 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:49:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:49:23 INFO - 'PWD': '/builds/slave/test', 05:49:23 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:49:23 INFO - 'SHELL': '/bin/bash', 05:49:23 INFO - 'SHLVL': '1', 05:49:23 INFO - 'TERM': 'linux', 05:49:23 INFO - 'TMOUT': '86400', 05:49:23 INFO - 'USER': 'cltbld', 05:49:23 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1468500511.38650-944110783', 05:49:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:49:23 INFO - '_': '/tools/buildbot/bin/python'} 05:49:23 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/cEl2NsG-Qh6_8WYWHMNqqA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-020', '--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 05:49:24 INFO - mozversion application_buildid: 20160714030208 05:49:24 INFO - mozversion application_changeset: 08f8a5aacd8308a73f6040fe522be7ba38497561 05:49:24 INFO - mozversion application_display_name: Nightly 05:49:24 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:49:24 INFO - mozversion application_name: Firefox 05:49:24 INFO - mozversion application_remotingname: firefox 05:49:24 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:49:24 INFO - mozversion application_vendor: Mozilla 05:49:24 INFO - mozversion application_version: 50.0a1 05:49:24 INFO - mozversion platform_buildid: 20160714030208 05:49:24 INFO - mozversion platform_changeset: 08f8a5aacd8308a73f6040fe522be7ba38497561 05:49:24 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:49:24 INFO - mozversion platform_version: 50.0a1 05:49:24 INFO - using testdate: 1468500564 05:49:24 INFO - actual date: 1468500564 05:49:24 INFO - starting webserver on 'localhost:34455' 05:49:24 INFO - SUITE-START | Running 2 tests 05:49:24 INFO - TEST-START | dromaeo_css 05:49:24 INFO - operating with platform_type : linux_ 05:49:24 INFO - Initialising browser for dromaeo_css test... 05:49:24 INFO - TEST-INFO | started process 3363 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpUR8gu3/profile http://localhost:34455/getInfo.html) 05:49:28 INFO - PROCESS | 3363 | __metrics Screen width/height:1600/1200 05:49:28 INFO - PROCESS | 3363 | colorDepth:24 05:49:28 INFO - PROCESS | 3363 | Browser inner width/height: 1024/697 05:49:28 INFO - PROCESS | 3363 | __metrics 05:49:29 INFO - PROCESS | 3363 | 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" 05:49:29 INFO - PROCESS | 3363 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:49:30 INFO - TEST-INFO | 3363: exit 0 05:49:30 INFO - Browser initialized. 05:49:30 INFO - Running cycle 1/1 for dromaeo_css test... 05:49:30 INFO - TEST-INFO | started process 3446 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpUR8gu3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:49:42 INFO - PROCESS | 3446 | RSS: Main: 157769728 05:49:42 INFO - PROCESS | 3446 | 05:50:33 INFO - PROCESS | 3446 | 1468500633772 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] 05:52:41 INFO - PROCESS | 3446 | Cycle 1(1): loaded http://localhost:34455/tests/dromaeo/cssquery-dojo.html (next: http://localhost:34455/tests/dromaeo/cssquery-ext.html) 05:52:42 INFO - PROCESS | 3446 | RSS: Main: 246398976 05:52:42 INFO - PROCESS | 3446 | 05:55:45 INFO - PROCESS | 3446 | Cycle 1(1): loaded http://localhost:34455/tests/dromaeo/cssquery-ext.html (next: http://localhost:34455/tests/dromaeo/cssquery-jquery.html) 05:55:45 INFO - PROCESS | 3446 | RSS: Main: 248508416 05:55:45 INFO - PROCESS | 3446 | 05:58:47 INFO - PROCESS | 3446 | Cycle 1(1): loaded http://localhost:34455/tests/dromaeo/cssquery-jquery.html (next: http://localhost:34455/tests/dromaeo/cssquery-mootools.html) 05:58:48 INFO - PROCESS | 3446 | RSS: Main: 244125696 05:58:48 INFO - PROCESS | 3446 | 06:01:50 INFO - PROCESS | 3446 | Cycle 1(1): loaded http://localhost:34455/tests/dromaeo/cssquery-mootools.html (next: http://localhost:34455/tests/dromaeo/cssquery-prototype.html) 06:01:51 INFO - PROCESS | 3446 | RSS: Main: 245403648 06:01:51 INFO - PROCESS | 3446 | 06:04:53 INFO - PROCESS | 3446 | Cycle 1(1): loaded http://localhost:34455/tests/dromaeo/cssquery-prototype.html (next: http://localhost:34455/tests/dromaeo/cssquery-yui.html) 06:04:54 INFO - PROCESS | 3446 | RSS: Main: 228044800 06:04:54 INFO - PROCESS | 3446 | 06:07:57 INFO - PROCESS | 3446 | Cycle 1(1): loaded http://localhost:34455/tests/dromaeo/cssquery-yui.html (next: http://localhost:34455/tests/dromaeo/cssquery-dojo.html) 06:07:57 INFO - PROCESS | 3446 | RSS: Main: 276803584 06:07:57 INFO - PROCESS | 3446 | 06:07:57 INFO - PROCESS | 3446 | __start_tp_report 06:07:57 INFO - PROCESS | 3446 | _x_x_mozilla_page_load 06:07:57 INFO - PROCESS | 3446 | _x_x_mozilla_page_load_details 06:07:57 INFO - PROCESS | 3446 | |i|pagename|runs| 06:07:57 INFO - PROCESS | 3446 | |0;dojo.html;3060.066679662703;3072.7388172005362;3094.334718035622;3116.6571677115526;3134.294783673674;5243.632945693803;5318.946810531894;5391.838244852651;5496.148097044959;5512.338519377675;5304.336957880265;5310.256564081032;5412.485813847678;5412.702301373423;5420.268263784398;4775.737334446612;4851;4888.853334399973;4894.119058569456;5056.266841308008;4980.452150263468;4980.9252861207115;5012.273220383042;5040.16837221858;5046.066477701637;4783.115123702126;4851.22380419133;4876.195427754434;4949.505049495057;4969.875753106201;4907.092187945242;4915.115279249733;4921.261810728383;4927.827526036572;4976.900461990776;4931.309616653672;4954.826581069681;4962.057209130254;4979.975100124476;4988.800447982077;5314.760835762361;5327.627066105374;5376.650517716375;5393.5954803389905;5504.311961004874;5759.452851979056;5766.336871259817;5769.163471296681;5803.332616749043;5816.447437493432;5844.415558444164;5855.795047173371;5858.502027327682;5865.970670146665;5868.706564671792;5259.316288882421;5361.651492652965;5405.324334458192;5409.972950135263;5491.395946445888;6005.909911351333;6014.548908831855;6016.09758536223;6018.849528761729;6019.097135429721;5558.944410555923;5756.42435756421;5763.077907534775;5765.596408251466;5768.4808367246305;5723.713814309268;5740.282464691913;5752.022156233366;5878.823635290948;5879.059350503899;5742.368339482654;5788.102844059177;5794.0439827428845;5794.362620111784;5798.8550286242335;5647.068233741469;5690.857728556819;5757.942420575824;5817;5820.534357251495;4406.977965110218;4420.359047938031;4421.867343979686;4459.866204013885;4460;3925.097227637659;3946.7039972002217;3950.664193543581;3953.150072734318;3982.7012974026484;5364.946350536523;5409.78360865561;5448.863778405493;5495.636876143217;5499.230107784913;5772.220750198753;5811.738471768781;5822.359540450547;5826.504747096459;5879.382664820132;5489.39616642169;5501.202325662756;5501.889962200734;5502.394736578973;5505.421930697299;4934.580560652313;4978.502149785065;5003.474635163256;5062.7468626568525;5105.387353517602;5947.821565353046;5965.940340596625;5967.104934259895;5974.074018527135;6001.969990150022;5810.477057064715;5829.970850145722;5831.7375718091935;5839.416058394127;5849.064149736022;5857.94142058591;5967.403259673998;5974.492168165754;6010.87978240424;6018.428249316308;5685.061964775766;5714.314282286152;5726.627769194989;5732.684702341411;5746;5848.941510584839;5850.7074646266665;5858.091995740667;5861.35525092231;5874.559408044499;5515.889682206413;5663.405342438985;5694.4590263924865;5721;5762.394948530344;5648.745806438638;5698.886022279614;5701.458361455655;5705.686187259741;5721.313442386941;5425.83722488326;5646.576506762091;5647.322321321303;5677.091665333527;5678.971605141948;5655.4344565543115;5704.543636508986;5708.200851880657;5733.799317023884;5788.681622510802;5549.500544950974;5556.916646250229;5566.415526369673;5577.693226872561;5594.748236329457 06:07:57 INFO - PROCESS | 3446 | |1;ext.html;614.2322097378277;614.505323214812;629.28261781569;634.3212762344293;643.1317721076547;42167.57832421679;44020.55979440205;44098.118037639266;47849.52150478494;48226.51773482264;46137.53862461374;46192.3071153933;46354.76822615882;46443.53556464434;46645;9082.455052696827;9202.40184388016;9227.630894764201;9240.399374040666;9245.768855778591;9055.641653751956;9141.177294043535;9147.17675409213;9152.130547597968;9154.359194856363;9180.036096209902;9222.077792220758;9224.40041397307;9228.584713687867;9238.445693258393;9133.315001374891;9167.908320916838;9184.586693598803;9191.908080919238;9192.540372981324;9069.54652267384;9110.453372797654;9118;9124.45253284805;9126.315526335551;43776.124477510275;44190.33714494285;44196.55803441956;44244.55755442436;44262;3692.8892133236045;3814.771113733157;3964.345882929313;4068.6338229559187;4489.483709373399;33506.32987340264;33777.15557111092;33882.83058584716;34780.652193477996;35320;4338.132373525307;4374.934375984363;4443.800028998703;4446.488653804821;4465.240909045438;262282.9999999981;264021;264434;264821;264911;51738.22392664113;60955.39044609586;61219;61306.38693613007;61405.07892381618;51977.220341694905;56622;56763.71618141883;58642.82714345689;61354.38645613576;16791.664166716597;17074.146292685316;17203.741943870853;17204.91397543004;17226.138693065295;13409.664758380923;13425.462981480827;13492.460301587827;13507.729845403233;13509;14992.175430351435;16759.2458339375;17018.574535636708;17051.829481705274;17137.314507419564;6135.96932015346;6370.331115232926;6430.196225471816;6599.967000164968;6676.46588272937;949.7293271417612;986.1371300112402;1033.8811036730722;1045.7385653586603;1084.9909584086718;33088.56975761896;33571.32857342888;33718.15704607405;33763.49354759681;33881.15297117543;1083.8320060390652;1161.802493576094;1168.7019809948579;1169.5497233565138;1225.5596838613033;17082.572935676708;17645.7353139703;18164.40829272911;18312.450626481226;18811.717824232648;545479;546400;548538;551798;553532;58377.70811145917;60458;61026.69486652538;61093.69453152706;61095.38904610897;1369.7260547890462;1403.9508617198348;1405.213080674805;1412.3009110490168;1434.9139051656934;31895.521567176045;33862.492062619574;33873.32253354968;34016.65983340135;34155.48766768451;45997.54002460066;46224.7688761554;46240.768796155804;46253;46317.53682463132;362737;363292;363557;363863;365253;24621.261362158446;27393.589096163174;46386.76806615946;48351;48551.27173092477;45772.54227457683;51175.744121280644;51451;52339.21491177712;52731.47268527419;43489.56510434855;43582;43847.56152438562;44447.55552444563;44872;42802.78598606987;42998.785006074766;43275;43967;44198;42994;43925.56074439343;43940.12119757651;44117.55882441263;44429.555704442544;37681.62318376781;38846.4173037399;39132.80433597814;39509;39846.800765995984;29220.415591687623;29492.410151796415;29726.70273297326;29831.254218644706;29845.55231671483 06:07:57 INFO - PROCESS | 3446 | |2;jquery.html;373.4603497945468;383.9270538597667;390.8827351794461;398.7487882633941;411.998842811967;19459.708104378427;21454;21577.784222157774;21905.890470547645;21923.561528769416;19420.417387478363;19582.11880465379;20097.69853452196;20204.59590808183;20278.29025984091;5275;5789.39211382805;6011.398860113975;6014.248218972629;6022.427869352427;5906.438888305625;5911.733971971272;5943.108533719955;5960.582759206857;5967.313758917715;5678.914816277759;6057.151998720183;6057.454829065383;6143.600665956698;6146.692665366736;5878.735456904449;6055.182550355712;6084.239470066242;6087.665178415185;6099.786507472217;5854.326752423483;5871.9412805871825;5891.469767720926;5908.763649454017;5911.586188966774;14007;21166.5766684667;23759.287221383387;24758.133465328807;24843.87578062098;2303.447172678562;2323.7211534615953;2353.3042322643455;2553.6041913503436;2560.8335458195156;9928.652497162564;10200.642977495827;10243.539040743184;10665.680029599125;11102.722431939186;1949.9025048747505;1957.3345062678616;2005.398380485863;2046.4883779055235;2094.1623350659856;83648;86736.56631716865;87319;88230.11769882348;92064.53967730119;10626.840597391045;11274.098072154207;11453.770924581462;11638.243514171552;12106.092043096627;15440.922795385952;15638.765418518731;15790;15797.68404631901;15820.683586328209;8258.298044666151;8391.790205244795;8451.492910425393;8579.528125953133;8602.655893764304;8186.795330116675;8202.83594328122;8306.210909963665;8387.245147936716;8437.789055273544;4668.436073915209;4704.976475117603;4722;4817.879553011133;4886.60907127429;1918.1176658736858;1940.951476213106;1969.7439332886634;1969.8621096523107;2014.113789932425;877.7937184761576;933.6172169410509;946.1768261612441;969.767255858603;1003.0721582536125;19168.233270669018;19576;20674.689879651818;20986.47533811667;21992.670109948365;960.7165886063628;993.8956409577034;1004.7186787699455;1018.954147063384;1040.2406243442178;10291.485425728833;11637.127215458875;11686.76626467466;11727;11727.589534366256;507713;519378;520516;522208;522293;70747;89153;93032.53483732538;100783;101409;1029.3206483720708;1031.6183012285353;1064.5688496159069;1070.010240527509;1070.5931745936493;18347.35784247545;18543.814561854208;19049.523761906064;19128.904355478135;19235.4229373119;31042.068737937898;33708.49437258394;42758.78620606877;43748.56251437572;44051.33922991222;60159.39840601538;62379.37620623736;64191.358086418535;64403.35596644161;64913.67543162254;7608.467407281437;7730.884036739341;8092.6762929482165;8709.520976346108;8952.641894324153;10175.796484070424;13922.164670119826;14083.225422601445;14154.716905662035;14482.927585362006;12021.278723276282;12253.142280040669;12288.139830212156;12378.133530652767;12593.244405335709;11868.347240901834;11927.821082683593;12181.33002684861;12440.440180192094;12650.05124615676;12289.938550307548;12297.938510307391;12305.323207223688;12568;12706.555270565486;7458.21688722698;7799.922000780147;7836.412269079728;8118.472299300526;8352.331813454788;11968.16222864391;12236.388180590757;12241.326727030098;12414.751704966031;12441.191322564004 06:07:57 INFO - PROCESS | 3446 | |3;mootools.html;1501.2943916359307;1502.2789061250598;1685.7555654430107;1804.9368272110462;1807.6475087357962;8831.337649676276;9356.25149988001;9421.293402994768;9427.434353938768;9443.386179898302;7779.338756205715;8597.1402859714;8848.424852384607;8867.822643547126;9215.354925155241;2381.4641705616273;2401.267613377919;2404.7354790972977;2407.903683852644;2408.397900524869;2373.5134297468976;2386;2439.4755127647554;2441.926742197737;2443.755624437551;2050.8769473831544;2112.1656945506516;2286.6912966749524;2363.314638754759;2366.3374255208482;2347.7886990170796;2354.30547988343;2384.9284521464297;2393.521295740859;2396.8801559921935;2412.3848418653306;2415.2995631266913;2416.7583241675693;2422.4428381472185;2423.5274121546304;9223.815523689556;9273.58268877902;9294.535273236312;9297;9298.349115561914;5431.592630552725;5549.639273447213;5564.8052318169075;5569.10894256921;5570.526505247059;3322.3853587086223;3398.4732366482986;3507.526483924663;3512.877049303262;3546.078019714867;1070.2090652423442;1118.6196693124377;1157.7742340243656;1169.8713141554422;1185.0697202695842;13057.412416441282;13094.083414160918;13100.08299419041;13105;13112.409941552653;4763.4522029967;4844.249141383091;4894.070126675921;4910.533499317559;5008.273800298936;11067.446627668583;11089.778204435866;11090.22368434218;11093.1125509959;11112.833307500394;5480.561555075585;5511.889762204733;5515.4760297771645;5521.806736764194;5522.337319521683;4929.408470983433;5050.3434553507805;5057.772400241998;5189.766460509286;5207.427183009866;4147.854825081107;4151.107511884959;4163.708540402143;4237.512686041083;4272.16692744918;1141.5833220874488;1258.192129606683;1291.4575878131207;1295.799151131576;1299.6101169649066;926.1063074133493;1005.8591797148405;1008.2287050406445;1043.0351924469835;1048.145761204631;6409.102725618417;6422.903656445157;6450.709718062699;6497.707603157869;6542.411182993554;955.6273053509137;1009.8535712321673;1019.3985548526405;1025.7230547752215;1034.1003327105466;4394.813400381879;4473.642108631302;4596.103759766814;4659.324397962277;4749.976250118796;11057.723556911142;11332;11506.252093613888;11599.246049006788;11604.94197529007;10632.734181645521;11047.613333533287;11049.226554141133;11464.885351146382;11489.885101149048;7406.8888966665545;8215.603347430831;8445.282151017233;8464.57677116154;8626.5255410953;6349.365063493614;6556.63938483388;6580.473562114924;6609.008648702733;6636.369544893228;10128.442935638315;10562.577496900038;10774.676759697222;10786.784264314827;10789.136869050613;10271.897281027093;10585.04734573892;10604.469776511298;10740.355578665305;11024.889751102386;8925;9028.18746312835;9095.590698418455;9109.18017378439;9109.863352049857;8760.868586971073;8823;8831.955840221015;9106.95446522763;9116.225120864801;7080.752173673896;7539.246075392417;7985.441019128838;8436.692312691543;8442.915570844212;8271.296939760188;8291.668333266844;8328.08391076969;8346.958265208877;8392.87410688828;7415.703371865281;7480.102387713509;7524.887126693214;7567.545947243183;7578.924210758042;5306.495882891119;5453.182022696627;5464.699441530595;5550.417206193291;5657.094864821609;5735.713214339183;5765.250517432707;5821.505172060422;5825.097109947965;5827.825165245049 06:07:57 INFO - PROCESS | 3446 | |4;prototype.html;321.8262138445239;323.50342224685113;327.09884268839346;333.83975691667985;335.5872277099168;5272.894542109156;5309.787608495661;5320.600954928376;5325.547328477084;5353.518183363496;5267.341582302212;5268;5295.9672863791575;5337.973310133444;5340.652857564256;3969.801509924492;3971.6624086952643;4159.646430053449;4162.791860406983;4164.604362585549;4104.015036391274;4107.404426358176;4135.524414692319;4154.8130334134885;4165.604267594574;3874.5737968823546;4088.4889388826396;4093.3041382964966;4096.938545921814;4137.793110344486;4010.8997275068064;4103.651189648884;4127.40152677863;4136.9864383226;4137.275976704064;3646.4348026055736;3652.086978255436;3683.37382644951;3691.53855768029;3703.962960370389;5121.718305493197;5153.536181743623;5165.793368265265;5169.327987361657;5178.948210517884;4502.392177056121;4760.523947605247;4784.6889952153;4789.233722604366;4799.13615549201;5448.210009548593;5481.342238931314;5508.4491550845;5543.168524721284;5550.389457159709;3906;4021.859234926793;4033.4151548025666;4034.636882680573;4070.979645101785;5701;5706.77172913083;5744.827655170351;5746.138079288098;5759.675274686803;4131.132462182975;4131.194417088639;4134.214499245134;4136.296829538986;4142.343062774914;4150.91698166035;4159.729617574848;4185.309423945076;4207.0113523322;4232.470941132358;4710.62315014798;4716.764161791897;4728.787204575802;4730.905381892343;4740.834070807505;5492.5605951524585;5496.148097044965;5504.394516603171;5508.091164957817;5519.144532597454;1378.1593228130832;1431.4202747887123;1448.8478709735537;1451.3033743803035;1454.403694485256;940.7930255343813;956.4596003258276;957.8371676814958;980.7450062983676;1009.5658866687395;5225.242339860699;5254.374481607679;5289.641077915551;5311.2033195020285;5317.441668624816;5569.913866795936;5583.469570390806;5588.636738612058;5597.608167428241;5604.467575580314;5184.637075404686;5305.708186049805;5331.293985924467;5420.864478387993;5432.972835135878;2987.9402411951637;3001.849907504616;3023;3026.0921723482866;3036.817790932551;5703.51896924839;5906.852328691817;5911;5923.378045305267;5924.496417804448;5780.421957804186;5806.825795226066;5807.94192058074;5828.679422631796;5844.970775146097;5763.798267060801;5906.911396329058;5919.467247947706;5923.585349025613;5933.406659334032;5343;5649.774009039593;5669.518090962314;5676;5677.602567820213;5525.850265348713;5531.806386776443;5535.8892822144135;5581.5255703265675;5589.776408943597;5724.341700704473;5762.94237057624;5776.884462310646;5813.970930145491;5857.853553661193;4050.5139383274195;4051.3517837145914;4053.695972801993;4056.79716014204;4057.89855253621;5626.662400255997;5650.971745141413;5659.207710920557;5741.827745167651;5762.4525670061275;5454.318210223722;5561.277033985718;5574.86062848432;5603.271574695264;5619.297587801525;5472.699001554953;5518.641288316247;5528.889422211614;5537.1140617501005;5544.7782088716;5216.686998780086;5332.200169974535;5462.644928079662;5463.153211252262;5486.204500347348;5532;5556.249906262765;5570.582206334622;5607.691576963306;5619.297587801525;5461.208124821958;5461.426550212171;5471.945280547303;5484.698341591251;5504.779808807602 06:07:57 INFO - PROCESS | 3446 | |5;yui.html;744.657457569518;746.1568427676727;753.1865585168019;801.6913488307002;822.4243029879084;11976.461059252333;13450.596482105528;15986;16896;16943.322267109317;14552.854471455283;14948.551543453714;14973.176475293854;14985.77521337178;15577.454789082385;678.4436761855256;695.1831597872505;698.3296035805607;811.7321283976273;833.9499630022187;806.7418426103649;830.754927296446;834.7996480844614;839.1189251286156;839.9034111077243;872.7905302727363;874.208840998897;874.4578361415931;874.8731433942074;876.3077169036453;868.4311775786806;869.0744357259498;869.074435725953;869.0874581689233;869.3436455476075;632.3076231526471;632.9113924050637;633.1990032608708;633.2369494758805;633.4299130782287;15572.221388930508;15580.532584022498;15644.139572323518;15652.373905043785;15668.843311566852;14534;15129;15393.076415415111;15410.152441615706;16129.77038481442;12396.194247373893;12694.428750706147;12756.681082972907;12851.807222891664;13113.86886131136;3988.162485877969;4004.3993400990084;4021.98699501109;4166.458360413158;4205.873823785291;82411.58794205991;83149.58425207896;83972;84196.57901710513;84409;11708.180427370002;11746;11815;11828.23116497425;11883.643490695293;22165.22421715232;22188.1124755008;22189.556208875732;22192.334229973127;22203.888980554995;7703.229677032252;7887.250711182429;7922.7227047053075;7939.087004994384;7959.164287749819;4531.657304630807;4790.664653474223;4794.208955522369;4852.8544143675745;4901.289313049588;3629.183433727443;3728.2357116790995;3731.738778285494;3739.3456145174487;3740.6446387593137;630.7413960276265;646.802725168828;649.5355820588302;664.7806223946086;679.78926532775;587.5798803855264;628.5537268539388;638.9808305750835;650.1125963060389;671.6708812681751;9379.577918993662;9747.561359738687;9763.218942484584;9858.65494707696;9865.309428339948;613.016109144825;685.3181084820635;699.3181647893264;765.3494529649753;766.1342682768436;4541.409616749801;4615.192341340319;4628;4630.745309008037;4832.685875418087;116317;122829;123596.38201808934;126695;127338.36330818286;104788.47605762075;107210.46394768132;108616;108683;108925;87916;89332.5533372342;89919.55040224757;90054.54972725094;92463.5376823125;8988.910110898807;9190.126937940884;9331.16019558243;9425.469159566435;9457.527123643928;52171.73914130557;52609.47390526199;53100.73449632727;53387.19919201293;53934.73032634969;74207;79987.22312614186;81253.59373203096;82280;82453;11712.238704484182;11757.529698811952;12379.381030948236;12711.364431778558;13069.934650326688;12255;12326.938365308117;12653.746925061263;12667.80998285008;12674.556390526288;11864.940675296568;12006.10784538779;12306.261624302571;12556.246625202517;12585.181963172365;11739.647810565697;12098.03215742721;12373.195742276723;12383.938080309541;13391.330433478483;11839.28964262147;11881.82177267359;11927.642170734545;11931.701707457036;12385.814212786996;9799.755006124618;10311.587536498457;10325.967403259614;10417.42704151279;10564.524596393334;10575.418351990715;10718.517666705173;10776.784464310827;11443.313401195956;12088.758224835277 06:07:57 INFO - PROCESS | 3446 | __end_tp_report 06:07:57 INFO - PROCESS | 3446 | __start_cc_report 06:07:57 INFO - PROCESS | 3446 | _x_x_mozilla_cycle_collect,973 06:07:57 INFO - PROCESS | 3446 | __end_cc_report 06:07:57 INFO - PROCESS | 3446 | __startTimestamp1468501677477__endTimestamp 06:07:57 INFO - PROCESS | 3446 | 06:07:57 INFO - PROCESS | 3446 | ------- Summary: start ------- 06:07:57 INFO - PROCESS | 3446 | Number of tests: 6 06:07:57 INFO - PROCESS | 3446 | 06:07:57 INFO - PROCESS | 3446 | [#0] dojo.html Cycles:165 Average:5388.00 Median:5594.75 stddev:619.61 (11.1%) stddev-sans-first:593.99 06:07:57 INFO - PROCESS | 3446 | Values: 3060.1 3072.7 3094.3 3116.7 3134.3 5243.6 5318.9 5391.8 5496.1 5512.3 5304.3 5310.3 5412.5 5412.7 5420.3 4775.7 4851.0 4888.9 4894.1 5056.3 4980.5 4980.9 5012.3 5040.2 5046.1 4783.1 4851.2 4876.2 4949.5 4969.9 4907.1 4915.1 4921.3 4927.8 4976.9 4931.3 4954.8 4962.1 4980.0 4988.8 5314.8 5327.6 5376.7 5393.6 5504.3 5759.5 5766.3 5769.2 5803.3 5816.4 5844.4 5855.8 5858.5 5866.0 5868.7 5259.3 5361.7 5405.3 5410.0 5491.4 6005.9 6014.5 6016.1 6018.8 6019.1 5558.9 5756.4 5763.1 5765.6 5768.5 5723.7 5740.3 5752.0 5878.8 5879.1 5742.4 5788.1 5794.0 5794.4 5798.9 5647.1 5690.9 5757.9 5817.0 5820.5 4407.0 4420.4 4421.9 4459.9 4460.0 3925.1 3946.7 3950.7 3953.2 3982.7 5364.9 5409.8 5448.9 5495.6 5499.2 5772.2 5811.7 5822.4 5826.5 5879.4 5489.4 5501.2 5501.9 5502.4 5505.4 4934.6 4978.5 5003.5 5062.7 5105.4 5947.8 5965.9 5967.1 5974.1 6002.0 5810.5 5830.0 5831.7 5839.4 5849.1 5857.9 5967.4 5974.5 6010.9 6018.4 5685.1 5714.3 5726.6 5732.7 5746.0 5848.9 5850.7 5858.1 5861.4 5874.6 5515.9 5663.4 5694.5 5721.0 5762.4 5648.7 5698.9 5701.5 5705.7 5721.3 5425.8 5646.6 5647.3 5677.1 5679.0 5655.4 5704.5 5708.2 5733.8 5788.7 5549.5 5556.9 5566.4 5577.7 5594.7 06:07:57 INFO - PROCESS | 3446 | 06:07:57 INFO - PROCESS | 3446 | [#1] ext.html Cycles:180 Average:57139.15 Median:44153.95 stddev:108597.99 (246.0%) stddev-sans-first:108819.71 06:07:57 INFO - PROCESS | 3446 | Values: 614.2 614.5 629.3 634.3 643.1 42167.6 44020.6 44098.1 47849.5 48226.5 46137.5 46192.3 46354.8 46443.5 46645.0 9082.5 9202.4 9227.6 9240.4 9245.8 9055.6 9141.2 9147.2 9152.1 9154.4 9180.0 9222.1 9224.4 9228.6 9238.4 9133.3 9167.9 9184.6 9191.9 9192.5 9069.5 9110.5 9118.0 9124.5 9126.3 43776.1 44190.3 44196.6 44244.6 44262.0 3692.9 3814.8 3964.3 4068.6 4489.5 33506.3 33777.2 33882.8 34780.7 35320.0 4338.1 4374.9 4443.8 4446.5 4465.2 262283.0 264021.0 264434.0 264821.0 264911.0 51738.2 60955.4 61219.0 61306.4 61405.1 51977.2 56622.0 56763.7 58642.8 61354.4 16791.7 17074.1 17203.7 17204.9 17226.1 13409.7 13425.5 13492.5 13507.7 13509.0 14992.2 16759.2 17018.6 17051.8 17137.3 6136.0 6370.3 6430.2 6600.0 6676.5 949.7 986.1 1033.9 1045.7 1085.0 33088.6 33571.3 33718.2 33763.5 33881.2 1083.8 1161.8 1168.7 1169.5 1225.6 17082.6 17645.7 18164.4 18312.5 18811.7 545479.0 546400.0 548538.0 551798.0 553532.0 58377.7 60458.0 61026.7 61093.7 61095.4 1369.7 1404.0 1405.2 1412.3 1434.9 31895.5 33862.5 33873.3 34016.7 34155.5 45997.5 46224.8 46240.8 46253.0 46317.5 362737.0 363292.0 363557.0 363863.0 365253.0 24621.3 27393.6 46386.8 48351.0 48551.3 45772.5 51175.7 51451.0 52339.2 52731.5 43489.6 43582.0 43847.6 44447.6 44872.0 42802.8 42998.8 43275.0 43967.0 44198.0 42994.0 43925.6 43940.1 44117.6 44429.6 37681.6 38846.4 39132.8 39509.0 39846.8 29220.4 29492.4 29726.7 29831.3 29845.6 06:07:57 INFO - PROCESS | 3446 | 06:07:57 INFO - PROCESS | 3446 | [#2] jquery.html Cycles:180 Average:30284.15 Median:24258.71 stddev:85453.89 (352.3%) stddev-sans-first:85664.10 06:07:57 INFO - PROCESS | 3446 | Values: 373.5 383.9 390.9 398.7 412.0 19459.7 21454.0 21577.8 21905.9 21923.6 19420.4 19582.1 20097.7 20204.6 20278.3 5275.0 5789.4 6011.4 6014.2 6022.4 5906.4 5911.7 5943.1 5960.6 5967.3 5678.9 6057.2 6057.5 6143.6 6146.7 5878.7 6055.2 6084.2 6087.7 6099.8 5854.3 5871.9 5891.5 5908.8 5911.6 14007.0 21166.6 23759.3 24758.1 24843.9 2303.4 2323.7 2353.3 2553.6 2560.8 9928.7 10200.6 10243.5 10665.7 11102.7 1949.9 1957.3 2005.4 2046.5 2094.2 83648.0 86736.6 87319.0 88230.1 92064.5 10626.8 11274.1 11453.8 11638.2 12106.1 15440.9 15638.8 15790.0 15797.7 15820.7 8258.3 8391.8 8451.5 8579.5 8602.7 8186.8 8202.8 8306.2 8387.2 8437.8 4668.4 4705.0 4722.0 4817.9 4886.6 1918.1 1941.0 1969.7 1969.9 2014.1 877.8 933.6 946.2 969.8 1003.1 19168.2 19576.0 20674.7 20986.5 21992.7 960.7 993.9 1004.7 1019.0 1040.2 10291.5 11637.1 11686.8 11727.0 11727.6 507713.0 519378.0 520516.0 522208.0 522293.0 70747.0 89153.0 93032.5 100783.0 101409.0 1029.3 1031.6 1064.6 1070.0 1070.6 18347.4 18543.8 19049.5 19128.9 19235.4 31042.1 33708.5 42758.8 43748.6 44051.3 60159.4 62379.4 64191.4 64403.4 64913.7 7608.5 7730.9 8092.7 8709.5 8952.6 10175.8 13922.2 14083.2 14154.7 14482.9 12021.3 12253.1 12288.1 12378.1 12593.2 11868.3 11927.8 12181.3 12440.4 12650.1 12289.9 12297.9 12305.3 12568.0 12706.6 7458.2 7799.9 7836.4 8118.5 8352.3 11968.2 12236.4 12241.3 12414.8 12441.2 06:07:57 INFO - PROCESS | 3446 | 06:07:57 INFO - PROCESS | 3446 | [#3] mootools.html Cycles:180 Average:6128.83 Median:4627.71 stddev:3482.15 (75.2%) stddev-sans-first:3474.55 06:07:57 INFO - PROCESS | 3446 | Values: 1501.3 1502.3 1685.8 1804.9 1807.6 8831.3 9356.3 9421.3 9427.4 9443.4 7779.3 8597.1 8848.4 8867.8 9215.4 2381.5 2401.3 2404.7 2407.9 2408.4 2373.5 2386.0 2439.5 2441.9 2443.8 2050.9 2112.2 2286.7 2363.3 2366.3 2347.8 2354.3 2384.9 2393.5 2396.9 2412.4 2415.3 2416.8 2422.4 2423.5 9223.8 9273.6 9294.5 9297.0 9298.3 5431.6 5549.6 5564.8 5569.1 5570.5 3322.4 3398.5 3507.5 3512.9 3546.1 1070.2 1118.6 1157.8 1169.9 1185.1 13057.4 13094.1 13100.1 13105.0 13112.4 4763.5 4844.2 4894.1 4910.5 5008.3 11067.4 11089.8 11090.2 11093.1 11112.8 5480.6 5511.9 5515.5 5521.8 5522.3 4929.4 5050.3 5057.8 5189.8 5207.4 4147.9 4151.1 4163.7 4237.5 4272.2 1141.6 1258.2 1291.5 1295.8 1299.6 926.1 1005.9 1008.2 1043.0 1048.1 6409.1 6422.9 6450.7 6497.7 6542.4 955.6 1009.9 1019.4 1025.7 1034.1 4394.8 4473.6 4596.1 4659.3 4750.0 11057.7 11332.0 11506.3 11599.2 11604.9 10632.7 11047.6 11049.2 11464.9 11489.9 7406.9 8215.6 8445.3 8464.6 8626.5 6349.4 6556.6 6580.5 6609.0 6636.4 10128.4 10562.6 10774.7 10786.8 10789.1 10271.9 10585.0 10604.5 10740.4 11024.9 8925.0 9028.2 9095.6 9109.2 9109.9 8760.9 8823.0 8832.0 9107.0 9116.2 7080.8 7539.2 7985.4 8436.7 8442.9 8271.3 8291.7 8328.1 8347.0 8392.9 7415.7 7480.1 7524.9 7567.5 7578.9 5306.5 5453.2 5464.7 5550.4 5657.1 5735.7 5765.3 5821.5 5825.1 5827.8 06:07:57 INFO - PROCESS | 3446 | 06:07:57 INFO - PROCESS | 3446 | [#4] prototype.html Cycles:180 Average:4669.54 Median:5328.42 stddev:1362.40 (25.6%) stddev-sans-first:1326.56 06:07:57 INFO - PROCESS | 3446 | Values: 321.8 323.5 327.1 333.8 335.6 5272.9 5309.8 5320.6 5325.5 5353.5 5267.3 5268.0 5296.0 5338.0 5340.7 3969.8 3971.7 4159.6 4162.8 4164.6 4104.0 4107.4 4135.5 4154.8 4165.6 3874.6 4088.5 4093.3 4096.9 4137.8 4010.9 4103.7 4127.4 4137.0 4137.3 3646.4 3652.1 3683.4 3691.5 3704.0 5121.7 5153.5 5165.8 5169.3 5178.9 4502.4 4760.5 4784.7 4789.2 4799.1 5448.2 5481.3 5508.4 5543.2 5550.4 3906.0 4021.9 4033.4 4034.6 4071.0 5701.0 5706.8 5744.8 5746.1 5759.7 4131.1 4131.2 4134.2 4136.3 4142.3 4150.9 4159.7 4185.3 4207.0 4232.5 4710.6 4716.8 4728.8 4730.9 4740.8 5492.6 5496.1 5504.4 5508.1 5519.1 1378.2 1431.4 1448.8 1451.3 1454.4 940.8 956.5 957.8 980.7 1009.6 5225.2 5254.4 5289.6 5311.2 5317.4 5569.9 5583.5 5588.6 5597.6 5604.5 5184.6 5305.7 5331.3 5420.9 5433.0 2987.9 3001.8 3023.0 3026.1 3036.8 5703.5 5906.9 5911.0 5923.4 5924.5 5780.4 5806.8 5807.9 5828.7 5845.0 5763.8 5906.9 5919.5 5923.6 5933.4 5343.0 5649.8 5669.5 5676.0 5677.6 5525.9 5531.8 5535.9 5581.5 5589.8 5724.3 5762.9 5776.9 5814.0 5857.9 4050.5 4051.4 4053.7 4056.8 4057.9 5626.7 5651.0 5659.2 5741.8 5762.5 5454.3 5561.3 5574.9 5603.3 5619.3 5472.7 5518.6 5528.9 5537.1 5544.8 5216.7 5332.2 5462.6 5463.2 5486.2 5532.0 5556.2 5570.6 5607.7 5619.3 5461.2 5461.4 5471.9 5484.7 5504.8 06:07:57 INFO - PROCESS | 3446 | 06:07:57 INFO - PROCESS | 3446 | [#5] yui.html Cycles:180 Average:21641.88 Median:4578.30 stddev:32284.16 (705.2%) stddev-sans-first:32336.60 06:07:57 INFO - PROCESS | 3446 | Values: 744.7 746.2 753.2 801.7 822.4 11976.5 13450.6 15986.0 16896.0 16943.3 14552.9 14948.6 14973.2 14985.8 15577.5 678.4 695.2 698.3 811.7 833.9 806.7 830.8 834.8 839.1 839.9 872.8 874.2 874.5 874.9 876.3 868.4 869.1 869.1 869.1 869.3 632.3 632.9 633.2 633.2 633.4 15572.2 15580.5 15644.1 15652.4 15668.8 14534.0 15129.0 15393.1 15410.2 16129.8 12396.2 12694.4 12756.7 12851.8 13113.9 3988.2 4004.4 4022.0 4166.5 4205.9 82411.6 83149.6 83972.0 84196.6 84409.0 11708.2 11746.0 11815.0 11828.2 11883.6 22165.2 22188.1 22189.6 22192.3 22203.9 7703.2 7887.3 7922.7 7939.1 7959.2 4531.7 4790.7 4794.2 4852.9 4901.3 3629.2 3728.2 3731.7 3739.3 3740.6 630.7 646.8 649.5 664.8 679.8 587.6 628.6 639.0 650.1 671.7 9379.6 9747.6 9763.2 9858.7 9865.3 613.0 685.3 699.3 765.3 766.1 4541.4 4615.2 4628.0 4630.7 4832.7 116317.0 122829.0 123596.4 126695.0 127338.4 104788.5 107210.5 108616.0 108683.0 108925.0 87916.0 89332.6 89919.6 90054.5 92463.5 8988.9 9190.1 9331.2 9425.5 9457.5 52171.7 52609.5 53100.7 53387.2 53934.7 74207.0 79987.2 81253.6 82280.0 82453.0 11712.2 11757.5 12379.4 12711.4 13069.9 12255.0 12326.9 12653.7 12667.8 12674.6 11864.9 12006.1 12306.3 12556.2 12585.2 11739.6 12098.0 12373.2 12383.9 13391.3 11839.3 11881.8 11927.6 11931.7 12385.8 9799.8 10311.6 10326.0 10417.4 10564.5 10575.4 10718.5 10776.8 11443.3 12088.8 06:07:57 INFO - PROCESS | 3446 | -------- Summary: end -------- 06:07:57 INFO - PROCESS | 3446 | 06:07:58 INFO - TEST-INFO | 3446: exit 0 06:07:58 INFO - TEST-OK | dromaeo_css | took 1114145ms 06:07:58 INFO - TEST-START | kraken 06:07:58 INFO - operating with platform_type : linux_ 06:07:58 INFO - Initialising browser for kraken test... 06:07:58 INFO - TEST-INFO | started process 3636 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpISPLEZ/profile http://localhost:34455/getInfo.html) 06:08:02 INFO - PROCESS | 3636 | __metrics Screen width/height:1600/1200 06:08:02 INFO - PROCESS | 3636 | colorDepth:24 06:08:02 INFO - PROCESS | 3636 | Browser inner width/height: 1024/697 06:08:02 INFO - PROCESS | 3636 | __metrics 06:08:03 INFO - PROCESS | 3636 | 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" 06:08:03 INFO - PROCESS | 3636 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:08:03 INFO - PROCESS | 3636 | console.error: PushService: 06:08:03 INFO - PROCESS | 3636 | stateChangeProcessEnqueue: Error transitioning state 06:08:03 INFO - PROCESS | 3636 | UnknownError 06:08:04 INFO - TEST-INFO | 3636: exit 0 06:08:04 INFO - Browser initialized. 06:08:04 INFO - Running cycle 1/1 for kraken test... 06:08:04 INFO - TEST-INFO | started process 3717 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpISPLEZ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:08:16 INFO - PROCESS | 3717 | RSS: Main: 157868032 06:08:16 INFO - PROCESS | 3717 | 06:08:18 INFO - PROCESS | 3717 | Cycle 1(1): loaded http://localhost:34455/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:34455/tests/kraken/driver.html?testName=audio-beat-detection) 06:08:19 INFO - PROCESS | 3717 | RSS: Main: 215715840 06:08:19 INFO - PROCESS | 3717 | 06:08:22 INFO - PROCESS | 3717 | Cycle 1(1): loaded http://localhost:34455/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:34455/tests/kraken/driver.html?testName=audio-dft) 06:08:22 INFO - PROCESS | 3717 | RSS: Main: 203919360 06:08:22 INFO - PROCESS | 3717 | 06:08:25 INFO - PROCESS | 3717 | Cycle 1(1): loaded http://localhost:34455/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:34455/tests/kraken/driver.html?testName=audio-fft) 06:08:26 INFO - PROCESS | 3717 | RSS: Main: 269365248 06:08:26 INFO - PROCESS | 3717 | 06:08:28 INFO - PROCESS | 3717 | Cycle 1(1): loaded http://localhost:34455/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:34455/tests/kraken/driver.html?testName=audio-oscillator) 06:08:28 INFO - PROCESS | 3717 | RSS: Main: 180613120 06:08:28 INFO - PROCESS | 3717 | 06:08:31 INFO - PROCESS | 3717 | Cycle 1(1): loaded http://localhost:34455/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:34455/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:08:31 INFO - PROCESS | 3717 | RSS: Main: 184840192 06:08:31 INFO - PROCESS | 3717 | 06:08:34 INFO - PROCESS | 3717 | Cycle 1(1): loaded http://localhost:34455/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:34455/tests/kraken/driver.html?testName=imaging-darkroom) 06:08:35 INFO - PROCESS | 3717 | RSS: Main: 220942336 06:08:35 INFO - PROCESS | 3717 | 06:08:38 INFO - PROCESS | 3717 | Cycle 1(1): loaded http://localhost:34455/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:34455/tests/kraken/driver.html?testName=imaging-desaturate) 06:08:39 INFO - PROCESS | 3717 | RSS: Main: 221118464 06:08:39 INFO - PROCESS | 3717 | 06:08:42 INFO - PROCESS | 3717 | Cycle 1(1): loaded http://localhost:34455/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:34455/tests/kraken/driver.html?testName=json-parse-financial) 06:08:42 INFO - PROCESS | 3717 | RSS: Main: 221769728 06:08:42 INFO - PROCESS | 3717 | 06:08:44 INFO - PROCESS | 3717 | Cycle 1(1): loaded http://localhost:34455/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:34455/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:08:44 INFO - PROCESS | 3717 | RSS: Main: 208916480 06:08:44 INFO - PROCESS | 3717 | 06:08:46 INFO - PROCESS | 3717 | Cycle 1(1): loaded http://localhost:34455/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:34455/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:08:47 INFO - PROCESS | 3717 | RSS: Main: 212324352 06:08:47 INFO - PROCESS | 3717 | 06:08:49 INFO - PROCESS | 3717 | Cycle 1(1): loaded http://localhost:34455/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:34455/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:08:49 INFO - PROCESS | 3717 | RSS: Main: 215195648 06:08:49 INFO - PROCESS | 3717 | 06:08:52 INFO - PROCESS | 3717 | Cycle 1(1): loaded http://localhost:34455/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:34455/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:08:53 INFO - PROCESS | 3717 | RSS: Main: 224047104 06:08:53 INFO - PROCESS | 3717 | 06:08:55 INFO - PROCESS | 3717 | Cycle 1(1): loaded http://localhost:34455/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:34455/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:08:56 INFO - PROCESS | 3717 | RSS: Main: 224247808 06:08:56 INFO - PROCESS | 3717 | 06:08:57 INFO - PROCESS | 3717 | Cycle 1(1): loaded http://localhost:34455/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:34455/tests/kraken/driver.html?testName=ai-astar) 06:08:58 INFO - PROCESS | 3717 | RSS: Main: 266903552 06:08:58 INFO - PROCESS | 3717 | 06:08:58 INFO - PROCESS | 3717 | __start_tp_report 06:08:58 INFO - PROCESS | 3717 | _x_x_mozilla_page_load 06:08:58 INFO - PROCESS | 3717 | _x_x_mozilla_page_load_details 06:08:58 INFO - PROCESS | 3717 | |i|pagename|runs| 06:08:58 INFO - PROCESS | 3717 | |0;ai-astar;102;111;110;110;111;111;110;111;113;109 06:08:58 INFO - PROCESS | 3717 | |1;audio-beat-detection;151;116;119;169;122;120;147;120;151;115 06:08:58 INFO - PROCESS | 3717 | |2;audio-dft;161;159;161;160;159;160;162;162;162;160 06:08:58 INFO - PROCESS | 3717 | |3;audio-fft;75;76;75;108;75;76;74;75;103;75 06:08:58 INFO - PROCESS | 3717 | |4;audio-oscillator;83;83;113;83;84;84;114;84;85;134 06:08:58 INFO - PROCESS | 3717 | |5;imaging-gaussian-blur;116;116;116;117;117;117;117;117;118;117 06:08:58 INFO - PROCESS | 3717 | |6;imaging-darkroom;147;146;146;145;146;145;146;146;145;146 06:08:58 INFO - PROCESS | 3717 | |7;imaging-desaturate;95;95;94;94;95;95;95;94;95;95 06:08:58 INFO - PROCESS | 3717 | |8;json-parse-financial;52;52;52;51;51;52;52;52;51;52 06:08:58 INFO - PROCESS | 3717 | |9;json-stringify-tinderbox;64;64;64;63;63;64;65;64;64;64 06:08:58 INFO - PROCESS | 3717 | |10;stanford-crypto-aes;75;84;74;74;90;83;78;76;74;75 06:08:58 INFO - PROCESS | 3717 | |11;stanford-crypto-ccm;123;125;118;126;117;124;138;120;204;120 06:08:58 INFO - PROCESS | 3717 | |12;stanford-crypto-pbkdf2;154;153;155;152;157;159;158;153;153;153 06:08:58 INFO - PROCESS | 3717 | |13;stanford-crypto-sha256-iterative;62;64;62;61;62;61;60;61;64;62 06:08:58 INFO - PROCESS | 3717 | __end_tp_report 06:08:58 INFO - PROCESS | 3717 | __start_cc_report 06:08:58 INFO - PROCESS | 3717 | _x_x_mozilla_cycle_collect,1654 06:08:58 INFO - PROCESS | 3717 | __end_cc_report 06:08:58 INFO - PROCESS | 3717 | __startTimestamp1468501738117__endTimestamp 06:08:58 INFO - PROCESS | 3717 | 06:08:58 INFO - PROCESS | 3717 | ------- Summary: start ------- 06:08:58 INFO - PROCESS | 3717 | Number of tests: 14 06:08:58 INFO - PROCESS | 3717 | 06:08:58 INFO - PROCESS | 3717 | [#0] ai-astar Cycles:10 Average:109.80 Median:111.00 stddev:2.94 (2.6%) stddev-sans-first:1.12 06:08:58 INFO - PROCESS | 3717 | Values: 102.0 111.0 110.0 110.0 111.0 111.0 110.0 111.0 113.0 109.0 06:08:58 INFO - PROCESS | 3717 | 06:08:58 INFO - PROCESS | 3717 | [#1] audio-beat-detection Cycles:10 Average:133.00 Median:134.50 stddev:19.46 (14.5%) stddev-sans-first:19.52 06:08:58 INFO - PROCESS | 3717 | Values: 151.0 116.0 119.0 169.0 122.0 120.0 147.0 120.0 151.0 115.0 06:08:58 INFO - PROCESS | 3717 | 06:08:58 INFO - PROCESS | 3717 | [#2] audio-dft Cycles:10 Average:160.60 Median:161.00 stddev:1.17 (0.7%) stddev-sans-first:1.24 06:08:58 INFO - PROCESS | 3717 | Values: 161.0 159.0 161.0 160.0 159.0 160.0 162.0 162.0 162.0 160.0 06:08:58 INFO - PROCESS | 3717 | 06:08:58 INFO - PROCESS | 3717 | [#3] audio-fft Cycles:10 Average:81.20 Median:75.00 stddev:12.87 (17.2%) stddev-sans-first:13.46 06:08:58 INFO - PROCESS | 3717 | Values: 75.0 76.0 75.0 108.0 75.0 76.0 74.0 75.0 103.0 75.0 06:08:58 INFO - PROCESS | 3717 | 06:08:58 INFO - PROCESS | 3717 | [#4] audio-oscillator Cycles:10 Average:94.70 Median:83.50 stddev:18.56 (22.2%) stddev-sans-first:19.20 06:08:58 INFO - PROCESS | 3717 | Values: 83.0 83.0 113.0 83.0 84.0 84.0 114.0 84.0 85.0 134.0 06:08:58 INFO - PROCESS | 3717 | 06:08:58 INFO - PROCESS | 3717 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.63 (0.5%) stddev-sans-first:0.60 06:08:58 INFO - PROCESS | 3717 | Values: 116.0 116.0 116.0 117.0 117.0 117.0 117.0 117.0 118.0 117.0 06:08:58 INFO - PROCESS | 3717 | 06:08:58 INFO - PROCESS | 3717 | [#6] imaging-darkroom Cycles:10 Average:145.80 Median:146.00 stddev:0.63 (0.4%) stddev-sans-first:0.50 06:08:58 INFO - PROCESS | 3717 | Values: 147.0 146.0 146.0 145.0 146.0 145.0 146.0 146.0 145.0 146.0 06:08:58 INFO - PROCESS | 3717 | 06:08:58 INFO - PROCESS | 3717 | [#7] imaging-desaturate Cycles:10 Average:94.70 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 06:08:58 INFO - PROCESS | 3717 | Values: 95.0 95.0 94.0 94.0 95.0 95.0 95.0 94.0 95.0 95.0 06:08:58 INFO - PROCESS | 3717 | 06:08:58 INFO - PROCESS | 3717 | [#8] json-parse-financial Cycles:10 Average:51.70 Median:52.00 stddev:0.48 (0.9%) stddev-sans-first:0.50 06:08:58 INFO - PROCESS | 3717 | Values: 52.0 52.0 52.0 51.0 51.0 52.0 52.0 52.0 51.0 52.0 06:08:58 INFO - PROCESS | 3717 | 06:08:58 INFO - PROCESS | 3717 | [#9] json-stringify-tinderbox Cycles:10 Average:63.90 Median:64.00 stddev:0.57 (0.9%) stddev-sans-first:0.60 06:08:58 INFO - PROCESS | 3717 | Values: 64.0 64.0 64.0 63.0 63.0 64.0 65.0 64.0 64.0 64.0 06:08:58 INFO - PROCESS | 3717 | 06:08:58 INFO - PROCESS | 3717 | [#10] stanford-crypto-aes Cycles:10 Average:78.30 Median:77.00 stddev:5.52 (7.2%) stddev-sans-first:5.72 06:08:58 INFO - PROCESS | 3717 | Values: 75.0 84.0 74.0 74.0 90.0 83.0 78.0 76.0 74.0 75.0 06:08:58 INFO - PROCESS | 3717 | 06:08:58 INFO - PROCESS | 3717 | [#11] stanford-crypto-ccm Cycles:10 Average:131.50 Median:124.50 stddev:26.15 (21.0%) stddev-sans-first:27.56 06:08:58 INFO - PROCESS | 3717 | Values: 123.0 125.0 118.0 126.0 117.0 124.0 138.0 120.0 204.0 120.0 06:08:58 INFO - PROCESS | 3717 | 06:08:58 INFO - PROCESS | 3717 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:154.70 Median:154.50 stddev:2.45 (1.6%) stddev-sans-first:2.59 06:08:58 INFO - PROCESS | 3717 | Values: 154.0 153.0 155.0 152.0 157.0 159.0 158.0 153.0 153.0 153.0 06:08:58 INFO - PROCESS | 3717 | 06:08:58 INFO - PROCESS | 3717 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.90 Median:62.00 stddev:1.29 (2.1%) stddev-sans-first:1.36 06:08:58 INFO - PROCESS | 3717 | Values: 62.0 64.0 62.0 61.0 62.0 61.0 60.0 61.0 64.0 62.0 06:08:58 INFO - PROCESS | 3717 | -------- Summary: end -------- 06:08:58 INFO - PROCESS | 3717 | 06:08:59 INFO - TEST-INFO | 3717: exit 0 06:08:59 INFO - TEST-OK | kraken | took 60788ms 06:08:59 INFO - SUITE-END | took 1174s 06:08:59 INFO - Completed test suite (00:19:35) 06:08:59 INFO - javascript benchmark 06:08:59 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3060.066679662703, 3072.7388172005362, 3094.334718035622, 3116.6571677115526, 3134.294783673674, 5243.632945693803, 5318.946810531894, 5391.838244852651, 5496.148097044959, 5512.338519377675, 5304.336957880265, 5310.256564081032, 5412.485813847678, 5412.702301373423, 5420.268263784398, 4775.737334446612, 4851.0, 4888.853334399973, 4894.119058569456, 5056.266841308008, 4980.452150263468, 4980.9252861207115, 5012.273220383042, 5040.16837221858, 5046.066477701637, 4783.115123702126, 4851.22380419133, 4876.195427754434, 4949.505049495057, 4969.875753106201, 4907.092187945242, 4915.115279249733, 4921.261810728383, 4927.827526036572, 4976.900461990776, 4931.309616653672, 4954.826581069681, 4962.057209130254, 4979.975100124476, 4988.800447982077, 5314.760835762361, 5327.627066105374, 5376.650517716375, 5393.5954803389905, 5504.311961004874, 5759.452851979056, 5766.336871259817, 5769.163471296681, 5803.332616749043, 5816.447437493432, 5844.415558444164, 5855.795047173371, 5858.502027327682, 5865.970670146665, 5868.706564671792, 5259.316288882421, 5361.651492652965, 5405.324334458192, 5409.972950135263, 5491.395946445888, 6005.909911351333, 6014.548908831855, 6016.09758536223, 6018.849528761729, 6019.097135429721, 5558.944410555923, 5756.42435756421, 5763.077907534775, 5765.596408251466, 5768.4808367246305, 5723.713814309268, 5740.282464691913, 5752.022156233366, 5878.823635290948, 5879.059350503899, 5742.368339482654, 5788.102844059177, 5794.0439827428845, 5794.362620111784, 5798.8550286242335, 5647.068233741469, 5690.857728556819, 5757.942420575824, 5817.0, 5820.534357251495, 4406.977965110218, 4420.359047938031, 4421.867343979686, 4459.866204013885, 4460.0, 3925.097227637659, 3946.7039972002217, 3950.664193543581, 3953.150072734318, 3982.7012974026484, 5364.946350536523, 5409.78360865561, 5448.863778405493, 5495.636876143217, 5499.230107784913, 5772.220750198753, 5811.738471768781, 5822.359540450547, 5826.504747096459, 5879.382664820132, 5489.39616642169, 5501.202325662756, 5501.889962200734, 5502.394736578973, 5505.421930697299, 4934.580560652313, 4978.502149785065, 5003.474635163256, 5062.7468626568525, 5105.387353517602, 5947.821565353046, 5965.940340596625, 5967.104934259895, 5974.074018527135, 6001.969990150022, 5810.477057064715, 5829.970850145722, 5831.7375718091935, 5839.416058394127, 5849.064149736022, 5857.94142058591, 5967.403259673998, 5974.492168165754, 6010.87978240424, 6018.428249316308, 5685.061964775766, 5714.314282286152, 5726.627769194989, 5732.684702341411, 5746.0, 5848.941510584839, 5850.7074646266665, 5858.091995740667, 5861.35525092231, 5874.559408044499, 5515.889682206413, 5663.405342438985, 5694.4590263924865, 5721.0, 5762.394948530344, 5648.745806438638, 5698.886022279614, 5701.458361455655, 5705.686187259741, 5721.313442386941, 5425.83722488326, 5646.576506762091, 5647.322321321303, 5677.091665333527, 5678.971605141948, 5655.4344565543115, 5704.543636508986, 5708.200851880657, 5733.799317023884, 5788.681622510802, 5549.500544950974, 5556.916646250229, 5566.415526369673, 5577.693226872561, 5594.748236329457], "value": 5345.404135015224, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [614.2322097378277, 614.505323214812, 629.28261781569, 634.3212762344293, 643.1317721076547, 42167.57832421679, 44020.55979440205, 44098.118037639266, 47849.52150478494, 48226.51773482264, 46137.53862461374, 46192.3071153933, 46354.76822615882, 46443.53556464434, 46645.0, 9082.455052696827, 9202.40184388016, 9227.630894764201, 9240.399374040666, 9245.768855778591, 9055.641653751956, 9141.177294043535, 9147.17675409213, 9152.130547597968, 9154.359194856363, 9180.036096209902, 9222.077792220758, 9224.40041397307, 9228.584713687867, 9238.445693258393, 9133.315001374891, 9167.908320916838, 9184.586693598803, 9191.908080919238, 9192.540372981324, 9069.54652267384, 9110.453372797654, 9118.0, 9124.45253284805, 9126.315526335551, 43776.124477510275, 44190.33714494285, 44196.55803441956, 44244.55755442436, 44262.0, 3692.8892133236045, 3814.771113733157, 3964.345882929313, 4068.6338229559187, 4489.483709373399, 33506.32987340264, 33777.15557111092, 33882.83058584716, 34780.652193477996, 35320.0, 4338.132373525307, 4374.934375984363, 4443.800028998703, 4446.488653804821, 4465.240909045438, 262282.9999999981, 264021.0, 264434.0, 264821.0, 264911.0, 51738.22392664113, 60955.39044609586, 61219.0, 61306.38693613007, 61405.07892381618, 51977.220341694905, 56622.0, 56763.71618141883, 58642.82714345689, 61354.38645613576, 16791.664166716597, 17074.146292685316, 17203.741943870853, 17204.91397543004, 17226.138693065295, 13409.664758380923, 13425.462981480827, 13492.460301587827, 13507.729845403233, 13509.0, 14992.175430351435, 16759.2458339375, 17018.574535636708, 17051.829481705274, 17137.314507419564, 6135.96932015346, 6370.331115232926, 6430.196225471816, 6599.967000164968, 6676.46588272937, 949.7293271417612, 986.1371300112402, 1033.8811036730722, 1045.7385653586603, 1084.9909584086718, 33088.56975761896, 33571.32857342888, 33718.15704607405, 33763.49354759681, 33881.15297117543, 1083.8320060390652, 1161.802493576094, 1168.7019809948579, 1169.5497233565138, 1225.5596838613033, 17082.572935676708, 17645.7353139703, 18164.40829272911, 18312.450626481226, 18811.717824232648, 545479.0, 546400.0, 548538.0, 551798.0, 553532.0, 58377.70811145917, 60458.0, 61026.69486652538, 61093.69453152706, 61095.38904610897, 1369.7260547890462, 1403.9508617198348, 1405.213080674805, 1412.3009110490168, 1434.9139051656934, 31895.521567176045, 33862.492062619574, 33873.32253354968, 34016.65983340135, 34155.48766768451, 45997.54002460066, 46224.7688761554, 46240.768796155804, 46253.0, 46317.53682463132, 362737.0, 363292.0, 363557.0, 363863.0, 365253.0, 24621.261362158446, 27393.589096163174, 46386.76806615946, 48351.0, 48551.27173092477, 45772.54227457683, 51175.744121280644, 51451.0, 52339.21491177712, 52731.47268527419, 43489.56510434855, 43582.0, 43847.56152438562, 44447.55552444563, 44872.0, 42802.78598606987, 42998.785006074766, 43275.0, 43967.0, 44198.0, 42994.0, 43925.56074439343, 43940.12119757651, 44117.55882441263, 44429.555704442544, 37681.62318376781, 38846.4173037399, 39132.80433597814, 39509.0, 39846.800765995984, 29220.415591687623, 29492.410151796415, 29726.70273297326, 29831.254218644706, 29845.55231671483], "value": 20549.46915337842, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [373.4603497945468, 383.9270538597667, 390.8827351794461, 398.7487882633941, 411.998842811967, 19459.708104378427, 21454.0, 21577.784222157774, 21905.890470547645, 21923.561528769416, 19420.417387478363, 19582.11880465379, 20097.69853452196, 20204.59590808183, 20278.29025984091, 5275.0, 5789.39211382805, 6011.398860113975, 6014.248218972629, 6022.427869352427, 5906.438888305625, 5911.733971971272, 5943.108533719955, 5960.582759206857, 5967.313758917715, 5678.914816277759, 6057.151998720183, 6057.454829065383, 6143.600665956698, 6146.692665366736, 5878.735456904449, 6055.182550355712, 6084.239470066242, 6087.665178415185, 6099.786507472217, 5854.326752423483, 5871.9412805871825, 5891.469767720926, 5908.763649454017, 5911.586188966774, 14007.0, 21166.5766684667, 23759.287221383387, 24758.133465328807, 24843.87578062098, 2303.447172678562, 2323.7211534615953, 2353.3042322643455, 2553.6041913503436, 2560.8335458195156, 9928.652497162564, 10200.642977495827, 10243.539040743184, 10665.680029599125, 11102.722431939186, 1949.9025048747505, 1957.3345062678616, 2005.398380485863, 2046.4883779055235, 2094.1623350659856, 83648.0, 86736.56631716865, 87319.0, 88230.11769882348, 92064.53967730119, 10626.840597391045, 11274.098072154207, 11453.770924581462, 11638.243514171552, 12106.092043096627, 15440.922795385952, 15638.765418518731, 15790.0, 15797.68404631901, 15820.683586328209, 8258.298044666151, 8391.790205244795, 8451.492910425393, 8579.528125953133, 8602.655893764304, 8186.795330116675, 8202.83594328122, 8306.210909963665, 8387.245147936716, 8437.789055273544, 4668.436073915209, 4704.976475117603, 4722.0, 4817.879553011133, 4886.60907127429, 1918.1176658736858, 1940.951476213106, 1969.7439332886634, 1969.8621096523107, 2014.113789932425, 877.7937184761576, 933.6172169410509, 946.1768261612441, 969.767255858603, 1003.0721582536125, 19168.233270669018, 19576.0, 20674.689879651818, 20986.47533811667, 21992.670109948365, 960.7165886063628, 993.8956409577034, 1004.7186787699455, 1018.954147063384, 1040.2406243442178, 10291.485425728833, 11637.127215458875, 11686.76626467466, 11727.0, 11727.589534366256, 507713.0, 519378.0, 520516.0, 522208.0, 522293.0, 70747.0, 89153.0, 93032.53483732538, 100783.0, 101409.0, 1029.3206483720708, 1031.6183012285353, 1064.5688496159069, 1070.010240527509, 1070.5931745936493, 18347.35784247545, 18543.814561854208, 19049.523761906064, 19128.904355478135, 19235.4229373119, 31042.068737937898, 33708.49437258394, 42758.78620606877, 43748.56251437572, 44051.33922991222, 60159.39840601538, 62379.37620623736, 64191.358086418535, 64403.35596644161, 64913.67543162254, 7608.467407281437, 7730.884036739341, 8092.6762929482165, 8709.520976346108, 8952.641894324153, 10175.796484070424, 13922.164670119826, 14083.225422601445, 14154.716905662035, 14482.927585362006, 12021.278723276282, 12253.142280040669, 12288.139830212156, 12378.133530652767, 12593.244405335709, 11868.347240901834, 11927.821082683593, 12181.33002684861, 12440.440180192094, 12650.05124615676, 12289.938550307548, 12297.938510307391, 12305.323207223688, 12568.0, 12706.555270565486, 7458.21688722698, 7799.922000780147, 7836.412269079728, 8118.472299300526, 8352.331813454788, 11968.16222864391, 12236.388180590757, 12241.326727030098, 12414.751704966031, 12441.191322564004], "value": 9474.619086499695, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1501.2943916359307, 1502.2789061250598, 1685.7555654430107, 1804.9368272110462, 1807.6475087357962, 8831.337649676276, 9356.25149988001, 9421.293402994768, 9427.434353938768, 9443.386179898302, 7779.338756205715, 8597.1402859714, 8848.424852384607, 8867.822643547126, 9215.354925155241, 2381.4641705616273, 2401.267613377919, 2404.7354790972977, 2407.903683852644, 2408.397900524869, 2373.5134297468976, 2386.0, 2439.4755127647554, 2441.926742197737, 2443.755624437551, 2050.8769473831544, 2112.1656945506516, 2286.6912966749524, 2363.314638754759, 2366.3374255208482, 2347.7886990170796, 2354.30547988343, 2384.9284521464297, 2393.521295740859, 2396.8801559921935, 2412.3848418653306, 2415.2995631266913, 2416.7583241675693, 2422.4428381472185, 2423.5274121546304, 9223.815523689556, 9273.58268877902, 9294.535273236312, 9297.0, 9298.349115561914, 5431.592630552725, 5549.639273447213, 5564.8052318169075, 5569.10894256921, 5570.526505247059, 3322.3853587086223, 3398.4732366482986, 3507.526483924663, 3512.877049303262, 3546.078019714867, 1070.2090652423442, 1118.6196693124377, 1157.7742340243656, 1169.8713141554422, 1185.0697202695842, 13057.412416441282, 13094.083414160918, 13100.08299419041, 13105.0, 13112.409941552653, 4763.4522029967, 4844.249141383091, 4894.070126675921, 4910.533499317559, 5008.273800298936, 11067.446627668583, 11089.778204435866, 11090.22368434218, 11093.1125509959, 11112.833307500394, 5480.561555075585, 5511.889762204733, 5515.4760297771645, 5521.806736764194, 5522.337319521683, 4929.408470983433, 5050.3434553507805, 5057.772400241998, 5189.766460509286, 5207.427183009866, 4147.854825081107, 4151.107511884959, 4163.708540402143, 4237.512686041083, 4272.16692744918, 1141.5833220874488, 1258.192129606683, 1291.4575878131207, 1295.799151131576, 1299.6101169649066, 926.1063074133493, 1005.8591797148405, 1008.2287050406445, 1043.0351924469835, 1048.145761204631, 6409.102725618417, 6422.903656445157, 6450.709718062699, 6497.707603157869, 6542.411182993554, 955.6273053509137, 1009.8535712321673, 1019.3985548526405, 1025.7230547752215, 1034.1003327105466, 4394.813400381879, 4473.642108631302, 4596.103759766814, 4659.324397962277, 4749.976250118796, 11057.723556911142, 11332.0, 11506.252093613888, 11599.246049006788, 11604.94197529007, 10632.734181645521, 11047.613333533287, 11049.226554141133, 11464.885351146382, 11489.885101149048, 7406.8888966665545, 8215.603347430831, 8445.282151017233, 8464.57677116154, 8626.5255410953, 6349.365063493614, 6556.63938483388, 6580.473562114924, 6609.008648702733, 6636.369544893228, 10128.442935638315, 10562.577496900038, 10774.676759697222, 10786.784264314827, 10789.136869050613, 10271.897281027093, 10585.04734573892, 10604.469776511298, 10740.355578665305, 11024.889751102386, 8925.0, 9028.18746312835, 9095.590698418455, 9109.18017378439, 9109.863352049857, 8760.868586971073, 8823.0, 8831.955840221015, 9106.95446522763, 9116.225120864801, 7080.752173673896, 7539.246075392417, 7985.441019128838, 8436.692312691543, 8442.915570844212, 8271.296939760188, 8291.668333266844, 8328.08391076969, 8346.958265208877, 8392.87410688828, 7415.703371865281, 7480.102387713509, 7524.887126693214, 7567.545947243183, 7578.924210758042, 5306.495882891119, 5453.182022696627, 5464.699441530595, 5550.417206193291, 5657.094864821609, 5735.713214339183, 5765.250517432707, 5821.505172060422, 5825.097109947965, 5827.825165245049], "value": 4884.189659409931, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [321.8262138445239, 323.50342224685113, 327.09884268839346, 333.83975691667985, 335.5872277099168, 5272.894542109156, 5309.787608495661, 5320.600954928376, 5325.547328477084, 5353.518183363496, 5267.341582302212, 5268.0, 5295.9672863791575, 5337.973310133444, 5340.652857564256, 3969.801509924492, 3971.6624086952643, 4159.646430053449, 4162.791860406983, 4164.604362585549, 4104.015036391274, 4107.404426358176, 4135.524414692319, 4154.8130334134885, 4165.604267594574, 3874.5737968823546, 4088.4889388826396, 4093.3041382964966, 4096.938545921814, 4137.793110344486, 4010.8997275068064, 4103.651189648884, 4127.40152677863, 4136.9864383226, 4137.275976704064, 3646.4348026055736, 3652.086978255436, 3683.37382644951, 3691.53855768029, 3703.962960370389, 5121.718305493197, 5153.536181743623, 5165.793368265265, 5169.327987361657, 5178.948210517884, 4502.392177056121, 4760.523947605247, 4784.6889952153, 4789.233722604366, 4799.13615549201, 5448.210009548593, 5481.342238931314, 5508.4491550845, 5543.168524721284, 5550.389457159709, 3906.0, 4021.859234926793, 4033.4151548025666, 4034.636882680573, 4070.979645101785, 5701.0, 5706.77172913083, 5744.827655170351, 5746.138079288098, 5759.675274686803, 4131.132462182975, 4131.194417088639, 4134.214499245134, 4136.296829538986, 4142.343062774914, 4150.91698166035, 4159.729617574848, 4185.309423945076, 4207.0113523322, 4232.470941132358, 4710.62315014798, 4716.764161791897, 4728.787204575802, 4730.905381892343, 4740.834070807505, 5492.5605951524585, 5496.148097044965, 5504.394516603171, 5508.091164957817, 5519.144532597454, 1378.1593228130832, 1431.4202747887123, 1448.8478709735537, 1451.3033743803035, 1454.403694485256, 940.7930255343813, 956.4596003258276, 957.8371676814958, 980.7450062983676, 1009.5658866687395, 5225.242339860699, 5254.374481607679, 5289.641077915551, 5311.2033195020285, 5317.441668624816, 5569.913866795936, 5583.469570390806, 5588.636738612058, 5597.608167428241, 5604.467575580314, 5184.637075404686, 5305.708186049805, 5331.293985924467, 5420.864478387993, 5432.972835135878, 2987.9402411951637, 3001.849907504616, 3023.0, 3026.0921723482866, 3036.817790932551, 5703.51896924839, 5906.852328691817, 5911.0, 5923.378045305267, 5924.496417804448, 5780.421957804186, 5806.825795226066, 5807.94192058074, 5828.679422631796, 5844.970775146097, 5763.798267060801, 5906.911396329058, 5919.467247947706, 5923.585349025613, 5933.406659334032, 5343.0, 5649.774009039593, 5669.518090962314, 5676.0, 5677.602567820213, 5525.850265348713, 5531.806386776443, 5535.8892822144135, 5581.5255703265675, 5589.776408943597, 5724.341700704473, 5762.94237057624, 5776.884462310646, 5813.970930145491, 5857.853553661193, 4050.5139383274195, 4051.3517837145914, 4053.695972801993, 4056.79716014204, 4057.89855253621, 5626.662400255997, 5650.971745141413, 5659.207710920557, 5741.827745167651, 5762.4525670061275, 5454.318210223722, 5561.277033985718, 5574.86062848432, 5603.271574695264, 5619.297587801525, 5472.699001554953, 5518.641288316247, 5528.889422211614, 5537.1140617501005, 5544.7782088716, 5216.686998780086, 5332.200169974535, 5462.644928079662, 5463.153211252262, 5486.204500347348, 5532.0, 5556.249906262765, 5570.582206334622, 5607.691576963306, 5619.297587801525, 5461.208124821958, 5461.426550212171, 5471.945280547303, 5484.698341591251, 5504.779808807602], "value": 4235.885279066615, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [744.657457569518, 746.1568427676727, 753.1865585168019, 801.6913488307002, 822.4243029879084, 11976.461059252333, 13450.596482105528, 15986.0, 16896.0, 16943.322267109317, 14552.854471455283, 14948.551543453714, 14973.176475293854, 14985.77521337178, 15577.454789082385, 678.4436761855256, 695.1831597872505, 698.3296035805607, 811.7321283976273, 833.9499630022187, 806.7418426103649, 830.754927296446, 834.7996480844614, 839.1189251286156, 839.9034111077243, 872.7905302727363, 874.208840998897, 874.4578361415931, 874.8731433942074, 876.3077169036453, 868.4311775786806, 869.0744357259498, 869.074435725953, 869.0874581689233, 869.3436455476075, 632.3076231526471, 632.9113924050637, 633.1990032608708, 633.2369494758805, 633.4299130782287, 15572.221388930508, 15580.532584022498, 15644.139572323518, 15652.373905043785, 15668.843311566852, 14534.0, 15129.0, 15393.076415415111, 15410.152441615706, 16129.77038481442, 12396.194247373893, 12694.428750706147, 12756.681082972907, 12851.807222891664, 13113.86886131136, 3988.162485877969, 4004.3993400990084, 4021.98699501109, 4166.458360413158, 4205.873823785291, 82411.58794205991, 83149.58425207896, 83972.0, 84196.57901710513, 84409.0, 11708.180427370002, 11746.0, 11815.0, 11828.23116497425, 11883.643490695293, 22165.22421715232, 22188.1124755008, 22189.556208875732, 22192.334229973127, 22203.888980554995, 7703.229677032252, 7887.250711182429, 7922.7227047053075, 7939.087004994384, 7959.164287749819, 4531.657304630807, 4790.664653474223, 4794.208955522369, 4852.8544143675745, 4901.289313049588, 3629.183433727443, 3728.2357116790995, 3731.738778285494, 3739.3456145174487, 3740.6446387593137, 630.7413960276265, 646.802725168828, 649.5355820588302, 664.7806223946086, 679.78926532775, 587.5798803855264, 628.5537268539388, 638.9808305750835, 650.1125963060389, 671.6708812681751, 9379.577918993662, 9747.561359738687, 9763.218942484584, 9858.65494707696, 9865.309428339948, 613.016109144825, 685.3181084820635, 699.3181647893264, 765.3494529649753, 766.1342682768436, 4541.409616749801, 4615.192341340319, 4628.0, 4630.745309008037, 4832.685875418087, 116317.0, 122829.0, 123596.38201808934, 126695.0, 127338.36330818286, 104788.47605762075, 107210.46394768132, 108616.0, 108683.0, 108925.0, 87916.0, 89332.5533372342, 89919.55040224757, 90054.54972725094, 92463.5376823125, 8988.910110898807, 9190.126937940884, 9331.16019558243, 9425.469159566435, 9457.527123643928, 52171.73914130557, 52609.47390526199, 53100.73449632727, 53387.19919201293, 53934.73032634969, 74207.0, 79987.22312614186, 81253.59373203096, 82280.0, 82453.0, 11712.238704484182, 11757.529698811952, 12379.381030948236, 12711.364431778558, 13069.934650326688, 12255.0, 12326.938365308117, 12653.746925061263, 12667.80998285008, 12674.556390526288, 11864.940675296568, 12006.10784538779, 12306.261624302571, 12556.246625202517, 12585.181963172365, 11739.647810565697, 12098.03215742721, 12373.195742276723, 12383.938080309541, 13391.330433478483, 11839.28964262147, 11881.82177267359, 11927.642170734545, 11931.701707457036, 12385.814212786996, 9799.755006124618, 10311.587536498457, 10325.967403259614, 10417.42704151279, 10564.524596393334, 10575.418351990715, 10718.517666705173, 10776.784464310827, 11443.313401195956, 12088.758224835277], "value": 7548.49182708463, "unit": "score"}], "name": "dromaeo_css", "value": 7387.498033415237, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 111.0, 110.0, 110.0, 111.0, 111.0, 110.0, 111.0, 113.0, 109.0], "value": 109.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 116.0, 119.0, 169.0, 122.0, 120.0, 147.0, 120.0, 151.0, 115.0], "value": 133.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 159.0, 161.0, 160.0, 159.0, 160.0, 162.0, 162.0, 162.0, 160.0], "value": 160.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 75.0, 108.0, 75.0, 76.0, 74.0, 75.0, 103.0, 75.0], "value": 81.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 113.0, 83.0, 84.0, 84.0, 114.0, 84.0, 85.0, 134.0], "value": 94.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 116.0, 116.0, 117.0, 117.0, 117.0, 117.0, 117.0, 118.0, 117.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 146.0, 145.0, 146.0, 145.0, 146.0, 146.0, 145.0, 146.0], "value": 145.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 94.0, 94.0, 95.0, 95.0, 95.0, 94.0, 95.0, 95.0], "value": 94.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 52.0, 52.0, 51.0, 51.0, 52.0, 52.0, 52.0, 51.0, 52.0], "value": 51.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 64.0, 63.0, 63.0, 64.0, 65.0, 64.0, 64.0, 64.0], "value": 63.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 84.0, 74.0, 74.0, 90.0, 83.0, 78.0, 76.0, 74.0, 75.0], "value": 78.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [123.0, 125.0, 118.0, 126.0, 117.0, 124.0, 138.0, 120.0, 204.0, 120.0], "value": 131.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 153.0, 155.0, 152.0, 157.0, 159.0, 158.0, 153.0, 153.0, 153.0], "value": 154.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 64.0, 62.0, 61.0, 62.0, 61.0, 60.0, 61.0, 64.0, 62.0], "value": 61.9, "unit": "score"}], "name": "kraken", "value": 1478.6000000000001, "alertThreshold": 2.0}]} 06:08:59 INFO - Return code: 0 06:08:59 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:08:59 INFO - # TBPL SUCCESS # 06:08:59 INFO - Running post-action listener: _resource_record_post_action 06:08:59 INFO - ##### 06:08:59 INFO - ##### Finished run-tests step (success) 06:08:59 INFO - ##### 06:08:59 INFO - Running post-run listener: _resource_record_post_run 06:08:59 INFO - Total resource usage - Wall time: 1193s; CPU: 13.0%; Read bytes: 4096; Write bytes: 557584384; Read time: 16; Write time: 957664 06:08:59 INFO - TinderboxPrint: CPU usage
13.0% 06:08:59 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 06:08:59 INFO - TinderboxPrint: I/O write bytes / time
557,584,384 / 957,664 06:08:59 INFO - TinderboxPrint: CPU idle
8,278.7 (86.9%) 06:08:59 INFO - TinderboxPrint: CPU user
1,195.0 (12.5%) 06:08:59 INFO - TinderboxPrint: Swap in / out
0 / 0 06:08:59 INFO - install - Wall time: 18s; CPU: 16.0%; Read bytes: 0; Write bytes: 256897024; Read time: 0; Write time: 556256 06:08:59 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 0; Write bytes: 296787968; Read time: 0; Write time: 400624 06:09:00 INFO - Running post-run listener: _upload_blobber_files 06:09:00 INFO - Blob upload gear active. 06:09:00 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:09:00 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 06:09:00 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', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 06:09:00 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 -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 06:09:00 INFO - (blobuploader) - INFO - Open directory for files ... 06:09:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:09:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:09:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:09:03 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:09:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:09:03 INFO - (blobuploader) - INFO - Done attempting. 06:09:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 06:09:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:09:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:09:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 06:09:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:09:04 INFO - (blobuploader) - INFO - Done attempting. 06:09:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 06:09:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:09:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:09:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 06:09:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:09:05 INFO - (blobuploader) - INFO - Done attempting. 06:09:05 INFO - (blobuploader) - INFO - Iteration through files over. 06:09:05 INFO - Return code: 0 06:09:05 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:09:05 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:09:05 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e300054334ca00ace62088f9280eb3b1c3a0eaf5f0c3871f639bb0a112b0a38149ba8f821500267f40e3739f020dc7c38b12e624d7e9a2500444cd911a761975", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/62a03f58da473d97ee17f7c6130c076080c62e96ef0acb3bcf3cbe69ca18d5d22784215a4ca979a7950cd891df5d3de43da4c15cef8fb2108333c859bf286068", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8c77e2eae1706fff46adff02bdd4edc393ef8fb74dfc76cc811023ecbb028cb9cc324dbd539551ca59e95f46f99c04671fd77149eee8215610befebb9a0c4b55"} 06:09:05 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:09:05 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:09:05 INFO - Contents: 06:09:05 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e300054334ca00ace62088f9280eb3b1c3a0eaf5f0c3871f639bb0a112b0a38149ba8f821500267f40e3739f020dc7c38b12e624d7e9a2500444cd911a761975", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/62a03f58da473d97ee17f7c6130c076080c62e96ef0acb3bcf3cbe69ca18d5d22784215a4ca979a7950cd891df5d3de43da4c15cef8fb2108333c859bf286068", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8c77e2eae1706fff46adff02bdd4edc393ef8fb74dfc76cc811023ecbb028cb9cc324dbd539551ca59e95f46f99c04671fd77149eee8215610befebb9a0c4b55"} 06:09:05 INFO - Running post-run listener: copy_logs_to_upload_dir 06:09:05 INFO - Copying logs to upload dir... 06:09:05 INFO - mkdir: /builds/slave/test/build/upload/logs 06:09:05 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1231.487486 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 31 secs) (at 2016-07-14 06:09:04.580102) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-14 06:09:04.584118) ========= 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=af724c981e2a0f424dbabf99000001e7-1468500511.38650-944110783 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/sha512/e300054334ca00ace62088f9280eb3b1c3a0eaf5f0c3871f639bb0a112b0a38149ba8f821500267f40e3739f020dc7c38b12e624d7e9a2500444cd911a761975", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/62a03f58da473d97ee17f7c6130c076080c62e96ef0acb3bcf3cbe69ca18d5d22784215a4ca979a7950cd891df5d3de43da4c15cef8fb2108333c859bf286068", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8c77e2eae1706fff46adff02bdd4edc393ef8fb74dfc76cc811023ecbb028cb9cc324dbd539551ca59e95f46f99c04671fd77149eee8215610befebb9a0c4b55"} build_url:https://queue.taskcluster.net/v1/task/cEl2NsG-Qh6_8WYWHMNqqA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011362 build_url: 'https://queue.taskcluster.net/v1/task/cEl2NsG-Qh6_8WYWHMNqqA/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/sha512/e300054334ca00ace62088f9280eb3b1c3a0eaf5f0c3871f639bb0a112b0a38149ba8f821500267f40e3739f020dc7c38b12e624d7e9a2500444cd911a761975", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/62a03f58da473d97ee17f7c6130c076080c62e96ef0acb3bcf3cbe69ca18d5d22784215a4ca979a7950cd891df5d3de43da4c15cef8fb2108333c859bf286068", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8c77e2eae1706fff46adff02bdd4edc393ef8fb74dfc76cc811023ecbb028cb9cc324dbd539551ca59e95f46f99c04671fd77149eee8215610befebb9a0c4b55"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-14 06:09:04.610148) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-14 06:09:04.610536) ========= 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=af724c981e2a0f424dbabf99000001e7-1468500511.38650-944110783 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004428 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-14 06:09:04.696297) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-14 06:09:04.696656) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-14 06:09:04.696949) ========= ========= Total master_lag: 0.18 =========