builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-056 starttime: 1469597620.28 results: success (0) buildid: 20160726192234 builduid: cfac72652e0f4f99b7d123a1986f466d revision: 250943418f3a43c46de84797ad58a22f724caf58 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-26 22:33:40.281231) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-26 22:33:40.281811) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-26 22:33:40.282189) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-26 22:33:40.296762) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-26 22:33:40.297073) ========= 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=52faae308a556c440401f8e5000001e8-1469597620.725820-1875803709 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-26 22:33:41-- 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-26 22:33:41 (46.6 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.121253 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-26 22:33:40.432849) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-26 22:33:40.433228) ========= 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=52faae308a556c440401f8e5000001e8-1469597620.725820-1875803709 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020305 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-26 22:33:40.483168) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-26 22:33:40.483523) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 250943418f3a43c46de84797ad58a22f724caf58 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 250943418f3a43c46de84797ad58a22f724caf58 --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=52faae308a556c440401f8e5000001e8-1469597620.725820-1875803709 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-26 22:33:41,710 truncating revision to first 12 chars 2016-07-26 22:33:41,710 Setting DEBUG logging. 2016-07-26 22:33:41,710 attempt 1/10 2016-07-26 22:33:41,710 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/250943418f3a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-26 22:33:43,138 unpacking tar archive at: mozilla-inbound-250943418f3a/testing/mozharness/ program finished with exit code 0 elapsedTime=1.782486 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-26 22:33:42.280366) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-26 22:33:42.280791) ========= script_repo_revision: 250943418f3a43c46de84797ad58a22f724caf58 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-26 22:33:42.281168) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-26 22:33:42.281538) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-26 22:33:42.289865) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2016-07-26 22:33:42.290170) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 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', 'Mozilla-Inbound', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Inbound'] 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=52faae308a556c440401f8e5000001e8-1469597620.725820-1875803709 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 22:33:43 INFO - MultiFileLogger online at 20160726 22:33:43 in /builds/slave/test 22:33:43 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 22:33:43 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:33:43 INFO - {'append_to_log': False, 22:33:43 INFO - 'base_work_dir': '/builds/slave/test', 22:33:43 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 22:33:43 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:33:43 INFO - 'branch': 'Mozilla-Inbound', 22:33:43 INFO - 'buildbot_json_path': 'buildprops.json', 22:33:43 INFO - 'config_files': ('talos/linux_config.py',), 22:33:43 INFO - 'default_actions': ('clobber', 22:33:43 INFO - 'read-buildbot-config', 22:33:43 INFO - 'download-and-extract', 22:33:43 INFO - 'populate-webroot', 22:33:43 INFO - 'create-virtualenv', 22:33:43 INFO - 'install', 22:33:43 INFO - 'run-tests'), 22:33:43 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:33:43 INFO - 'download_minidump_stackwalk': True, 22:33:43 INFO - 'download_symbols': 'ondemand', 22:33:43 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:33:43 INFO - 'tooltool.py': '/tools/tooltool.py', 22:33:43 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:33:43 INFO - '/tools/misc-python/virtualenv.py')}, 22:33:43 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:33:43 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:33:43 INFO - 'installer_path': 'installer.exe', 22:33:43 INFO - 'log_level': 'info', 22:33:43 INFO - 'log_name': 'talos', 22:33:43 INFO - 'log_to_console': True, 22:33:43 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 22:33:43 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 22:33:43 INFO - 'opt_config_files': (), 22:33:43 INFO - 'pip_index': False, 22:33:43 INFO - 'sps_profile': False, 22:33:43 INFO - 'sps_profile_interval': 0, 22:33:43 INFO - 'suite': 'dromaeojs', 22:33:43 INFO - 'system_bits': '32', 22:33:43 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:33:43 INFO - 'title': 'talos-linux64-ix-056', 22:33:43 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:33:43 INFO - 'use_talos_json': True, 22:33:43 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:33:43 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:33:43 INFO - 'work_dir': 'build'} 22:33:43 INFO - [mozharness: 2016-07-27 05:33:43.608570Z] Running clobber step. 22:33:43 INFO - Running pre-action listener: _resource_record_pre_action 22:33:43 INFO - Running main action method: clobber 22:33:43 INFO - rmtree: /builds/slave/test/build 22:33:43 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:33:44 INFO - Running post-action listener: _resource_record_post_action 22:33:44 INFO - [mozharness: 2016-07-27 05:33:44.611340Z] Finished clobber step (success) 22:33:44 INFO - [mozharness: 2016-07-27 05:33:44.611441Z] Running read-buildbot-config step. 22:33:44 INFO - Running pre-action listener: _resource_record_pre_action 22:33:44 INFO - Running main action method: read_buildbot_config 22:33:44 INFO - Using buildbot properties: 22:33:44 INFO - { 22:33:44 INFO - "project": "", 22:33:44 INFO - "product": "firefox", 22:33:44 INFO - "script_repo_revision": "production", 22:33:44 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs", 22:33:44 INFO - "repository": "", 22:33:44 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs", 22:33:44 INFO - "buildid": "20160726192234", 22:33:44 INFO - "pgo_build": "True", 22:33:44 INFO - "basedir": "/builds/slave/test", 22:33:44 INFO - "buildnumber": 1301, 22:33:44 INFO - "slavename": "talos-linux64-ix-056", 22:33:44 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 22:33:44 INFO - "platform": "ubuntu64_hw", 22:33:44 INFO - "scheduler": "tests-mozilla-inbound-linux64-pgo-talos", 22:33:44 INFO - "branch": "mozilla-inbound", 22:33:44 INFO - "repo_path": "integration/mozilla-inbound", 22:33:44 INFO - "revision": "250943418f3a43c46de84797ad58a22f724caf58", 22:33:44 INFO - "stage_platform": "linux64-pgo", 22:33:44 INFO - "builduid": "cfac72652e0f4f99b7d123a1986f466d", 22:33:44 INFO - "slavebuilddir": "test" 22:33:44 INFO - } 22:33:44 INFO - Found installer url https://queue.taskcluster.net/v1/task/RiB9WYIKQUiOH4wrZDkgwQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 22:33:44 INFO - Running post-action listener: _resource_record_post_action 22:33:44 INFO - [mozharness: 2016-07-27 05:33:44.613869Z] Finished read-buildbot-config step (success) 22:33:44 INFO - [mozharness: 2016-07-27 05:33:44.613958Z] Running download-and-extract step. 22:33:44 INFO - Running pre-action listener: _resource_record_pre_action 22:33:44 INFO - Running main action method: download_and_extract 22:33:44 INFO - mkdir: /builds/slave/test/build/tests 22:33:44 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')]} 22:33:44 INFO - https://queue.taskcluster.net/v1/task/RiB9WYIKQUiOH4wrZDkgwQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 22:33:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RiB9WYIKQUiOH4wrZDkgwQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 22:33:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RiB9WYIKQUiOH4wrZDkgwQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 22:33:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RiB9WYIKQUiOH4wrZDkgwQ/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 22:33:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RiB9WYIKQUiOH4wrZDkgwQ/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 22:33:45 INFO - Downloaded 1361 bytes. 22:33:45 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 22:33:45 INFO - Using the following test package requirements: 22:33:45 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 22:33:45 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:33:45 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 22:33:45 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:33:45 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 22:33:45 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:33:45 INFO - u'jsshell-linux-x86_64.zip'], 22:33:45 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:33:45 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 22:33:45 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 22:33:45 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:33:45 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 22:33:45 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:33:45 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 22:33:45 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:33:45 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 22:33:45 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:33:45 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 22:33:45 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 22:33:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 22:33:45 INFO - https://queue.taskcluster.net/v1/task/RiB9WYIKQUiOH4wrZDkgwQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:33:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RiB9WYIKQUiOH4wrZDkgwQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 22:33:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RiB9WYIKQUiOH4wrZDkgwQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 22:33:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RiB9WYIKQUiOH4wrZDkgwQ/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 22:33:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RiB9WYIKQUiOH4wrZDkgwQ/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 22:33:46 INFO - Downloaded 24348929 bytes. 22:33:46 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'] 22:33:46 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 22:33:47 INFO - Return code: 0 22:33:47 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 22:33: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')]} 22:33:47 INFO - https://queue.taskcluster.net/v1/task/RiB9WYIKQUiOH4wrZDkgwQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:33:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RiB9WYIKQUiOH4wrZDkgwQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 22:33:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RiB9WYIKQUiOH4wrZDkgwQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 22:33:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RiB9WYIKQUiOH4wrZDkgwQ/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 22:33:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RiB9WYIKQUiOH4wrZDkgwQ/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 22:33:48 INFO - Downloaded 24348929 bytes. 22:33:48 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'] 22:33:48 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 22:33:49 INFO - Return code: 0 22:33:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 22:33:49 INFO - https://queue.taskcluster.net/v1/task/RiB9WYIKQUiOH4wrZDkgwQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 22:33:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RiB9WYIKQUiOH4wrZDkgwQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 22:33:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RiB9WYIKQUiOH4wrZDkgwQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 22:33:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RiB9WYIKQUiOH4wrZDkgwQ/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 22:33:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RiB9WYIKQUiOH4wrZDkgwQ/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 22:33:50 INFO - Downloaded 14403317 bytes. 22:33:50 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'] 22:33:50 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 22:33:50 INFO - Return code: 0 22:33: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')]} 22:33:50 INFO - https://queue.taskcluster.net/v1/task/RiB9WYIKQUiOH4wrZDkgwQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 22:33:50 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RiB9WYIKQUiOH4wrZDkgwQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 22:33:50 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RiB9WYIKQUiOH4wrZDkgwQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 22:33:50 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RiB9WYIKQUiOH4wrZDkgwQ/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 22:33:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RiB9WYIKQUiOH4wrZDkgwQ/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 22:33:52 INFO - Downloaded 60875576 bytes. 22:33:52 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/RiB9WYIKQUiOH4wrZDkgwQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 22:33:52 INFO - mkdir: /builds/slave/test/properties 22:33:52 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:33:52 INFO - Writing to file /builds/slave/test/properties/build_url 22:33:52 INFO - Contents: 22:33:52 INFO - build_url:https://queue.taskcluster.net/v1/task/RiB9WYIKQUiOH4wrZDkgwQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 22:33:52 INFO - Running post-action listener: _resource_record_post_action 22:33:52 INFO - Running post-action listener: set_extra_try_arguments 22:33:52 INFO - [mozharness: 2016-07-27 05:33:52.911150Z] Finished download-and-extract step (success) 22:33:52 INFO - [mozharness: 2016-07-27 05:33:52.911382Z] Running populate-webroot step. 22:33:52 INFO - Running pre-action listener: _resource_record_pre_action 22:33:52 INFO - Running main action method: populate_webroot 22:33:52 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:33:52 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:33:52 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:33:52 INFO - '--tppagecycles', 22:33:52 INFO - '7'], 22:33:52 INFO - 'tests': ['tsvgm']}}, 22:33:52 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:33:52 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:33:52 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:33:52 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:33:52 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:33:52 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:33:52 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:33:52 INFO - 'talos_options': ['--disable-e10s'], 22:33:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:33:52 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:33:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:33:52 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:33:52 INFO - 'talos_options': ['--disable-e10s'], 22:33:52 INFO - 'tests': ['damp', 'tps']}, 22:33:52 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:33:52 INFO - 'tests': ['damp', 'tps']}, 22:33:52 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:33:52 INFO - 'tests': ['dromaeo_dom']}, 22:33:52 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:33:52 INFO - 'g4': {'talos_options': ['--disable-e10s'], 22:33:52 INFO - 'tests': ['basic_compositor_video']}, 22:33:52 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 22:33:52 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:33:52 INFO - 'tests': ['a11yr', 22:33:52 INFO - 'ts_paint', 22:33:52 INFO - 'tpaint', 22:33:52 INFO - 'sessionrestore', 22:33:52 INFO - 'sessionrestore_no_auto_restore', 22:33:52 INFO - 'tabpaint']}, 22:33:52 INFO - 'other-e10s': {'tests': ['a11yr', 22:33:52 INFO - 'ts_paint', 22:33:52 INFO - 'tpaint', 22:33:52 INFO - 'sessionrestore', 22:33:52 INFO - 'sessionrestore_no_auto_restore', 22:33:52 INFO - 'tabpaint']}, 22:33:52 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:33:52 INFO - 'ts_paint', 22:33:52 INFO - 'tpaint', 22:33:52 INFO - 'sessionrestore', 22:33:52 INFO - 'sessionrestore_no_auto_restore', 22:33:52 INFO - 'tabpaint']}, 22:33:52 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:33:52 INFO - 'ts_paint', 22:33:52 INFO - 'tpaint', 22:33:52 INFO - 'sessionrestore', 22:33:52 INFO - 'sessionrestore_no_auto_restore', 22:33:52 INFO - 'tabpaint']}, 22:33:52 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:33:52 INFO - 'tests': ['a11yr', 22:33:52 INFO - 'ts_paint', 22:33:52 INFO - 'tpaint', 22:33:52 INFO - 'sessionrestore', 22:33:52 INFO - 'sessionrestore_no_auto_restore', 22:33:52 INFO - 'tabpaint']}, 22:33:52 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:33:52 INFO - 'tests': ['a11yr', 22:33:52 INFO - 'ts_paint', 22:33:52 INFO - 'tpaint', 22:33:52 INFO - 'sessionrestore', 22:33:52 INFO - 'sessionrestore_no_auto_restore', 22:33:52 INFO - 'tabpaint']}, 22:33:52 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:33:52 INFO - 'tests': ['tsvgx', 22:33:52 INFO - 'tsvgr_opacity', 22:33:52 INFO - 'tart', 22:33:52 INFO - 'tscrollx', 22:33:52 INFO - 'cart']}, 22:33:52 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:33:52 INFO - 'tsvgr_opacity', 22:33:52 INFO - 'tart', 22:33:52 INFO - 'tscrollx', 22:33:52 INFO - 'cart']}, 22:33:52 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:33:52 INFO - 'talos_options': ['--disable-e10s'], 22:33:52 INFO - 'tests': ['tp5o']}, 22:33:52 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:33:52 INFO - 'tests': ['tp5o']}, 22:33:52 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:33:52 INFO - 'talos_options': ['--disable-e10s', 22:33:52 INFO - '--xperf_path', 22:33:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:33:52 INFO - 'tests': ['tp5n']}, 22:33:52 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:33:52 INFO - 'talos_options': ['--xperf_path', 22:33:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:33:52 INFO - 'tests': ['tp5n']}}, 22:33:52 INFO - 'talos.zip': {'path': '', 22:33:52 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:33:52 INFO - Running post-action listener: _resource_record_post_action 22:33:52 INFO - [mozharness: 2016-07-27 05:33:52.929641Z] Finished populate-webroot step (success) 22:33:52 INFO - [mozharness: 2016-07-27 05:33:52.929730Z] Running create-virtualenv step. 22:33:52 INFO - Running pre-action listener: _resource_record_pre_action 22:33:52 INFO - Running main action method: create_virtualenv 22:33:52 INFO - Creating virtualenv /builds/slave/test/build/venv 22:33:52 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 22:33:52 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:33:53 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:33:53 INFO - Using real prefix '/usr' 22:33:53 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:33:53 INFO - Installing distribute.............................................................................................................................................................................................done. 22:33:55 INFO - Installing pip.................done. 22:33:55 INFO - Return code: 0 22:33:55 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:33: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')]} 22:33:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:33:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:33: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')]} 22:33:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:33:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:33: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2687030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27ea8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2948180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29463b0>, '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 0x29472b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2947740>, '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': '52faae308a556c440401f8e5000001e8-1469597620.725820-1875803709', '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 22:33: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', 'pip>=1.5'] in /builds/slave/test/build 22:33: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 pip>=1.5 22:33:55 INFO - Using env: {'DISPLAY': ':0', 22:33:55 INFO - 'HOME': '/home/cltbld', 22:33:55 INFO - 'LANG': 'en_US.UTF-8', 22:33:55 INFO - 'LANGUAGE': 'en_US:en', 22:33:55 INFO - 'LOGNAME': 'cltbld', 22:33:55 INFO - 'MAIL': '/var/mail/cltbld', 22:33:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:33:55 INFO - 'MOZ_NO_REMOTE': '1', 22:33:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:33:55 INFO - 'NO_EM_RESTART': '1', 22:33:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:33:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:33:55 INFO - 'PWD': '/builds/slave/test', 22:33:55 INFO - 'SHELL': '/bin/bash', 22:33:55 INFO - 'SHLVL': '1', 22:33:55 INFO - 'TERM': 'linux', 22:33:55 INFO - 'TMOUT': '86400', 22:33:55 INFO - 'USER': 'cltbld', 22:33:55 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1469597620.725820-1875803709', 22:33:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:33:55 INFO - '_': '/tools/buildbot/bin/python'} 22:33:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:33:55 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 22:33:55 INFO - Cleaning up... 22:33:55 INFO - Return code: 0 22:33:55 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:33: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')]} 22:33:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:33:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:33: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')]} 22:33:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:33:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:33: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', '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 0x2687030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27ea8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2948180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29463b0>, '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 0x29472b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2947740>, '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': '52faae308a556c440401f8e5000001e8-1469597620.725820-1875803709', '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 22:33: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', 'psutil>=3.1.1'] in /builds/slave/test/build 22:33: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 psutil>=3.1.1 22:33:55 INFO - Using env: {'DISPLAY': ':0', 22:33:55 INFO - 'HOME': '/home/cltbld', 22:33:55 INFO - 'LANG': 'en_US.UTF-8', 22:33:55 INFO - 'LANGUAGE': 'en_US:en', 22:33:55 INFO - 'LOGNAME': 'cltbld', 22:33:55 INFO - 'MAIL': '/var/mail/cltbld', 22:33:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:33:55 INFO - 'MOZ_NO_REMOTE': '1', 22:33:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:33:55 INFO - 'NO_EM_RESTART': '1', 22:33:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:33:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:33:55 INFO - 'PWD': '/builds/slave/test', 22:33:55 INFO - 'SHELL': '/bin/bash', 22:33:55 INFO - 'SHLVL': '1', 22:33:55 INFO - 'TERM': 'linux', 22:33:55 INFO - 'TMOUT': '86400', 22:33:55 INFO - 'USER': 'cltbld', 22:33:55 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1469597620.725820-1875803709', 22:33:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:33:55 INFO - '_': '/tools/buildbot/bin/python'} 22:33:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:33:56 INFO - Downloading/unpacking psutil>=3.1.1 22:33:56 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 22:33:56 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 22:33:56 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 22:33:56 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 22:33:56 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 22:33:56 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 22:33:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:33:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:33:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:33:58 INFO - Installing collected packages: psutil 22:33:58 INFO - Running setup.py install for psutil 22:33:58 INFO - building 'psutil._psutil_linux' extension 22:33:58 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 22:33:58 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 22:33:58 INFO - building 'psutil._psutil_posix' extension 22:33:58 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 22:33:58 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 22:33:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:33:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:33:58 INFO - Successfully installed psutil 22:33:58 INFO - Cleaning up... 22:33:58 INFO - Return code: 0 22:33:58 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:33: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')]} 22:33:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:33:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:33: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')]} 22:33:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:33:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:33:58 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 0x2687030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27ea8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2948180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29463b0>, '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 0x29472b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2947740>, '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': '52faae308a556c440401f8e5000001e8-1469597620.725820-1875803709', '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 22:33:58 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 22:33:58 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 22:33:58 INFO - Using env: {'DISPLAY': ':0', 22:33:58 INFO - 'HOME': '/home/cltbld', 22:33:58 INFO - 'LANG': 'en_US.UTF-8', 22:33:58 INFO - 'LANGUAGE': 'en_US:en', 22:33:58 INFO - 'LOGNAME': 'cltbld', 22:33:58 INFO - 'MAIL': '/var/mail/cltbld', 22:33:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:33:58 INFO - 'MOZ_NO_REMOTE': '1', 22:33:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:33:58 INFO - 'NO_EM_RESTART': '1', 22:33:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:33:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:33:58 INFO - 'PWD': '/builds/slave/test', 22:33:58 INFO - 'SHELL': '/bin/bash', 22:33:58 INFO - 'SHLVL': '1', 22:33:58 INFO - 'TERM': 'linux', 22:33:58 INFO - 'TMOUT': '86400', 22:33:58 INFO - 'USER': 'cltbld', 22:33:58 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1469597620.725820-1875803709', 22:33:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:33:58 INFO - '_': '/tools/buildbot/bin/python'} 22:33:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:33:58 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:33:58 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 22:33:58 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 22:33:58 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 22:33:58 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 22:33:58 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 22:33:58 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 22:34:00 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:34:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:34:00 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:34:00 INFO - Installing collected packages: mozsystemmonitor 22:34:00 INFO - Running setup.py install for mozsystemmonitor 22:34:00 INFO - Successfully installed mozsystemmonitor 22:34:00 INFO - Cleaning up... 22:34:00 INFO - Return code: 0 22:34:00 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:34:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:34:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:34:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:34:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:34:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:34:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:34:00 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 0x2687030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27ea8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2948180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29463b0>, '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 0x29472b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2947740>, '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': '52faae308a556c440401f8e5000001e8-1469597620.725820-1875803709', '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 22:34:00 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 22:34:00 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 22:34:00 INFO - Using env: {'DISPLAY': ':0', 22:34:00 INFO - 'HOME': '/home/cltbld', 22:34:00 INFO - 'LANG': 'en_US.UTF-8', 22:34:00 INFO - 'LANGUAGE': 'en_US:en', 22:34:00 INFO - 'LOGNAME': 'cltbld', 22:34:00 INFO - 'MAIL': '/var/mail/cltbld', 22:34:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:34:00 INFO - 'MOZ_NO_REMOTE': '1', 22:34:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:34:00 INFO - 'NO_EM_RESTART': '1', 22:34:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:34:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:34:00 INFO - 'PWD': '/builds/slave/test', 22:34:00 INFO - 'SHELL': '/bin/bash', 22:34:00 INFO - 'SHLVL': '1', 22:34:00 INFO - 'TERM': 'linux', 22:34:00 INFO - 'TMOUT': '86400', 22:34:00 INFO - 'USER': 'cltbld', 22:34:00 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1469597620.725820-1875803709', 22:34:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:34:00 INFO - '_': '/tools/buildbot/bin/python'} 22:34:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:34:01 INFO - Downloading/unpacking jsonschema==2.5.1 22:34:01 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 22:34:01 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 22:34:01 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 22:34:01 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 22:34:01 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 22:34:01 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 22:34:03 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:34:03 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:34: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 22:34: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 22:34: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 22:34: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 22:34: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 22:34: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 22:34:03 INFO - Downloading functools32-3.2.3-2.tar.gz 22:34:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:34:03 INFO - warning: no files found matching '*.txt' 22:34:03 INFO - no previously-included directories found matching 'build' 22:34:03 INFO - no previously-included directories found matching 'dist' 22:34:03 INFO - no previously-included directories found matching '.git*' 22:34:03 INFO - Installing collected packages: jsonschema, functools32 22:34:03 INFO - Running setup.py install for functools32 22:34:03 INFO - warning: no files found matching '*.txt' 22:34:03 INFO - no previously-included directories found matching 'build' 22:34:03 INFO - no previously-included directories found matching 'dist' 22:34:03 INFO - no previously-included directories found matching '.git*' 22:34:03 INFO - Successfully installed jsonschema functools32 22:34:03 INFO - Cleaning up... 22:34:03 INFO - Return code: 0 22:34:03 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:34:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 22:34:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:34:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:34:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 22:34:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:34:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:34:03 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 0x2687030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27ea8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2948180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29463b0>, '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 0x29472b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2947740>, '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': '52faae308a556c440401f8e5000001e8-1469597620.725820-1875803709', '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 22:34:03 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 22:34:03 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 22:34:03 INFO - Using env: {'DISPLAY': ':0', 22:34:03 INFO - 'HOME': '/home/cltbld', 22:34:03 INFO - 'LANG': 'en_US.UTF-8', 22:34:03 INFO - 'LANGUAGE': 'en_US:en', 22:34:03 INFO - 'LOGNAME': 'cltbld', 22:34:03 INFO - 'MAIL': '/var/mail/cltbld', 22:34:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:34:03 INFO - 'MOZ_NO_REMOTE': '1', 22:34:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:34:03 INFO - 'NO_EM_RESTART': '1', 22:34:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:34:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:34:03 INFO - 'PWD': '/builds/slave/test', 22:34:03 INFO - 'SHELL': '/bin/bash', 22:34:03 INFO - 'SHLVL': '1', 22:34:03 INFO - 'TERM': 'linux', 22:34:03 INFO - 'TMOUT': '86400', 22:34:03 INFO - 'USER': 'cltbld', 22:34:03 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1469597620.725820-1875803709', 22:34:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:34:03 INFO - '_': '/tools/buildbot/bin/python'} 22:34:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:34:03 INFO - Downloading/unpacking blobuploader==1.2.4 22:34: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 22:34: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 22:34: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 22:34: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 22:34: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 22:34: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 22:34:05 INFO - Downloading blobuploader-1.2.4.tar.gz 22:34:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:34:05 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:34:05 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 22:34:05 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 22:34:05 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 22:34:05 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 22:34:05 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 22:34:05 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 22:34:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:34:06 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:34:06 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 22:34:06 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 22:34:06 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 22:34:06 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 22:34:06 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 22:34:06 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 22:34:06 INFO - Downloading docopt-0.6.1.tar.gz 22:34:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:34:06 INFO - Installing collected packages: blobuploader, requests, docopt 22:34:06 INFO - Running setup.py install for blobuploader 22:34:06 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:34:06 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:34:06 INFO - Running setup.py install for requests 22:34:06 INFO - Running setup.py install for docopt 22:34:06 INFO - Successfully installed blobuploader requests docopt 22:34:06 INFO - Cleaning up... 22:34:07 INFO - Return code: 0 22:34:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:34:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:34:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:34:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:34:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:34:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:34:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:34:07 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 0x2687030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27ea8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2948180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29463b0>, '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 0x29472b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2947740>, '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': '52faae308a556c440401f8e5000001e8-1469597620.725820-1875803709', '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 22:34:07 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 22:34:07 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 22:34:07 INFO - Using env: {'DISPLAY': ':0', 22:34:07 INFO - 'HOME': '/home/cltbld', 22:34:07 INFO - 'LANG': 'en_US.UTF-8', 22:34:07 INFO - 'LANGUAGE': 'en_US:en', 22:34:07 INFO - 'LOGNAME': 'cltbld', 22:34:07 INFO - 'MAIL': '/var/mail/cltbld', 22:34:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:34:07 INFO - 'MOZ_NO_REMOTE': '1', 22:34:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:34:07 INFO - 'NO_EM_RESTART': '1', 22:34:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:34:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:34:07 INFO - 'PWD': '/builds/slave/test', 22:34:07 INFO - 'SHELL': '/bin/bash', 22:34:07 INFO - 'SHLVL': '1', 22:34:07 INFO - 'TERM': 'linux', 22:34:07 INFO - 'TMOUT': '86400', 22:34:07 INFO - 'USER': 'cltbld', 22:34:07 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1469597620.725820-1875803709', 22:34:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:34:07 INFO - '_': '/tools/buildbot/bin/python'} 22:34:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:34:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:34:07 INFO - Running setup.py (path:/tmp/pip-tPZ0Iq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:34:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:34:07 INFO - Running setup.py (path:/tmp/pip-hlUi0g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:34:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:34:07 INFO - Running setup.py (path:/tmp/pip-z4YBbr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:34:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:34:07 INFO - Running setup.py (path:/tmp/pip-HCXp6Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:34:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:34:07 INFO - Running setup.py (path:/tmp/pip-0h0oKK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:34:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:34:07 INFO - Running setup.py (path:/tmp/pip-vi7ZmL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:34:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:34:07 INFO - Running setup.py (path:/tmp/pip-sTdrLQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:34:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:34:07 INFO - Running setup.py (path:/tmp/pip-UdkK3O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:34:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:34:07 INFO - Running setup.py (path:/tmp/pip-ITaaXQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:34:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:34:07 INFO - Running setup.py (path:/tmp/pip-qBQyph-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:34:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:34:07 INFO - Running setup.py (path:/tmp/pip-FD4_us-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:34:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:34:08 INFO - Running setup.py (path:/tmp/pip-dHFUSZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:34:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:34:08 INFO - Running setup.py (path:/tmp/pip-dNmbP3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:34:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:34:08 INFO - Running setup.py (path:/tmp/pip-3iVl37-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:34:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:34:08 INFO - Running setup.py (path:/tmp/pip-GrJuIb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:34:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:34:08 INFO - Running setup.py (path:/tmp/pip-qjDYhD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:34:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:34:08 INFO - Running setup.py (path:/tmp/pip-_naDk5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:34:08 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:34:08 INFO - Running setup.py install for manifestparser 22:34:08 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:34:08 INFO - Running setup.py install for mozcrash 22:34:08 INFO - Running setup.py install for mozdebug 22:34:08 INFO - Running setup.py install for mozdevice 22:34:08 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:34:08 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:34:08 INFO - Running setup.py install for mozfile 22:34:08 INFO - Running setup.py install for mozhttpd 22:34:09 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:34:09 INFO - Running setup.py install for mozinfo 22:34:09 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:34:09 INFO - Running setup.py install for mozInstall 22:34:09 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:34:09 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:34:09 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:34:09 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:34:09 INFO - Running setup.py install for mozleak 22:34:09 INFO - Running setup.py install for mozlog 22:34:09 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:34:09 INFO - Running setup.py install for moznetwork 22:34:09 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:34:09 INFO - Running setup.py install for mozprocess 22:34:09 INFO - Running setup.py install for mozprofile 22:34:09 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:34:09 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:34:09 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:34:09 INFO - Running setup.py install for mozrunner 22:34:09 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:34:09 INFO - Running setup.py install for mozscreenshot 22:34:10 INFO - Running setup.py install for moztest 22:34:10 INFO - Running setup.py install for mozversion 22:34:10 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:34:10 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:34:10 INFO - Cleaning up... 22:34:10 INFO - Return code: 0 22:34:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:34:10 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')]} 22:34:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:34:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:34:10 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')]} 22:34:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:34:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:34:10 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 0x2687030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27ea8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2948180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29463b0>, '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 0x29472b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2947740>, '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': '52faae308a556c440401f8e5000001e8-1469597620.725820-1875803709', '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 22:34:10 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 22:34:10 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 22:34:10 INFO - Using env: {'DISPLAY': ':0', 22:34:10 INFO - 'HOME': '/home/cltbld', 22:34:10 INFO - 'LANG': 'en_US.UTF-8', 22:34:10 INFO - 'LANGUAGE': 'en_US:en', 22:34:10 INFO - 'LOGNAME': 'cltbld', 22:34:10 INFO - 'MAIL': '/var/mail/cltbld', 22:34:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:34:10 INFO - 'MOZ_NO_REMOTE': '1', 22:34:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:34:10 INFO - 'NO_EM_RESTART': '1', 22:34:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:34:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:34:10 INFO - 'PWD': '/builds/slave/test', 22:34:10 INFO - 'SHELL': '/bin/bash', 22:34:10 INFO - 'SHLVL': '1', 22:34:10 INFO - 'TERM': 'linux', 22:34:10 INFO - 'TMOUT': '86400', 22:34:10 INFO - 'USER': 'cltbld', 22:34:10 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1469597620.725820-1875803709', 22:34:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:34:10 INFO - '_': '/tools/buildbot/bin/python'} 22:34:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:34:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:34:10 INFO - Running setup.py (path:/tmp/pip-1PJCmY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:34:10 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)) 22:34:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:34:10 INFO - Running setup.py (path:/tmp/pip-fQou6Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:34:10 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)) 22:34:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:34:10 INFO - Running setup.py (path:/tmp/pip-J5SQDb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:34:10 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)) 22:34:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:34:10 INFO - Running setup.py (path:/tmp/pip-ZdIQFl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:34:10 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)) 22:34:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:34:10 INFO - Running setup.py (path:/tmp/pip-EGB7nk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:34:10 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)) 22:34:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:34:10 INFO - Running setup.py (path:/tmp/pip-rTfDwm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:34:10 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)) 22:34:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:34:10 INFO - Running setup.py (path:/tmp/pip-6HL3cL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:34:11 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)) 22:34:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:34:11 INFO - Running setup.py (path:/tmp/pip-ubf96T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:34:11 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)) 22:34:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:34:11 INFO - Running setup.py (path:/tmp/pip-1Ytz04-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:34:11 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)) 22:34:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:34:11 INFO - Running setup.py (path:/tmp/pip-WTDJTL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:34:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:34:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:34:11 INFO - Running setup.py (path:/tmp/pip-Ad4ORg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:34:11 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)) 22:34:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:34:11 INFO - Running setup.py (path:/tmp/pip-agpjNC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:34:11 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)) 22:34:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:34:11 INFO - Running setup.py (path:/tmp/pip-myJikQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:34:11 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)) 22:34:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:34:11 INFO - Running setup.py (path:/tmp/pip-xD3fXe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:34:11 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)) 22:34:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:34:11 INFO - Running setup.py (path:/tmp/pip-nHFDR6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:34:11 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)) 22:34:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:34:11 INFO - Running setup.py (path:/tmp/pip-rfaMjq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:34:11 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)) 22:34:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:34:11 INFO - Running setup.py (path:/tmp/pip-HiBfxO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:34:11 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)) 22:34:11 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)) 22:34:11 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)) 22:34:11 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)) 22:34:11 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)) 22:34:11 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)) 22:34:11 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:34:11 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 22:34:11 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 22:34:11 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 22:34:11 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 22:34:11 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 22:34:11 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 22:34:13 INFO - Downloading blessings-1.6.tar.gz 22:34:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:34:13 INFO - Installing collected packages: blessings 22:34:13 INFO - Running setup.py install for blessings 22:34:13 INFO - Successfully installed blessings 22:34:13 INFO - Cleaning up... 22:34:13 INFO - Return code: 0 22:34:13 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:34:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:34:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:34:14 INFO - Reading from file tmpfile_stdout 22:34:14 INFO - Current package versions: 22:34:14 INFO - argparse == 1.2.1 22:34:14 INFO - blessings == 1.6 22:34:14 INFO - blobuploader == 1.2.4 22:34:14 INFO - docopt == 0.6.1 22:34:14 INFO - functools32 == 3.2.3-2 22:34:14 INFO - jsonschema == 2.5.1 22:34:14 INFO - manifestparser == 1.1 22:34:14 INFO - mozInstall == 1.12 22:34:14 INFO - mozcrash == 0.17 22:34:14 INFO - mozdebug == 0.1 22:34:14 INFO - mozdevice == 0.48 22:34:14 INFO - mozfile == 1.2 22:34:14 INFO - mozhttpd == 0.7 22:34:14 INFO - mozinfo == 0.9 22:34:14 INFO - mozleak == 0.1 22:34:14 INFO - mozlog == 3.3 22:34:14 INFO - moznetwork == 0.27 22:34:14 INFO - mozprocess == 0.23 22:34:14 INFO - mozprofile == 0.28 22:34:14 INFO - mozrunner == 6.12 22:34:14 INFO - mozscreenshot == 0.1 22:34:14 INFO - mozsystemmonitor == 0.3 22:34:14 INFO - moztest == 0.7 22:34:14 INFO - mozversion == 1.4 22:34:14 INFO - psutil == 3.1.1 22:34:14 INFO - requests == 1.2.3 22:34:14 INFO - wsgiref == 0.1.2 22:34:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:34:14 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')]} 22:34:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:34:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:34:14 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')]} 22:34:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:34:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:34:14 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 0x2687030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27ea8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2948180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29463b0>, '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 0x29472b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2947740>, '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': '52faae308a556c440401f8e5000001e8-1469597620.725820-1875803709', '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 22:34:14 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 22:34:14 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 22:34:14 INFO - Using env: {'DISPLAY': ':0', 22:34:14 INFO - 'HOME': '/home/cltbld', 22:34:14 INFO - 'LANG': 'en_US.UTF-8', 22:34:14 INFO - 'LANGUAGE': 'en_US:en', 22:34:14 INFO - 'LOGNAME': 'cltbld', 22:34:14 INFO - 'MAIL': '/var/mail/cltbld', 22:34:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:34:14 INFO - 'MOZ_NO_REMOTE': '1', 22:34:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:34:14 INFO - 'NO_EM_RESTART': '1', 22:34:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:34:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:34:14 INFO - 'PWD': '/builds/slave/test', 22:34:14 INFO - 'SHELL': '/bin/bash', 22:34:14 INFO - 'SHLVL': '1', 22:34:14 INFO - 'TERM': 'linux', 22:34:14 INFO - 'TMOUT': '86400', 22:34:14 INFO - 'USER': 'cltbld', 22:34:14 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1469597620.725820-1875803709', 22:34:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:34:14 INFO - '_': '/tools/buildbot/bin/python'} 22:34:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:34:14 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)) 22:34:14 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)) 22:34:14 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)) 22:34:14 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)) 22:34:14 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)) 22:34:14 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)) 22:34:14 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)) 22:34:14 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)) 22:34:14 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)) 22:34:14 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)) 22:34:14 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)) 22:34:14 INFO - Cleaning up... 22:34:14 INFO - Return code: 0 22:34:14 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:34:14 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')]} 22:34:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:34:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:34:14 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')]} 22:34:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:34:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:34:14 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 0x2687030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27ea8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2948180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29463b0>, '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 0x29472b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2947740>, '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': '52faae308a556c440401f8e5000001e8-1469597620.725820-1875803709', '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 22:34:14 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 22:34:14 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 22:34:14 INFO - Using env: {'DISPLAY': ':0', 22:34:14 INFO - 'HOME': '/home/cltbld', 22:34:14 INFO - 'LANG': 'en_US.UTF-8', 22:34:14 INFO - 'LANGUAGE': 'en_US:en', 22:34:14 INFO - 'LOGNAME': 'cltbld', 22:34:14 INFO - 'MAIL': '/var/mail/cltbld', 22:34:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:34:14 INFO - 'MOZ_NO_REMOTE': '1', 22:34:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:34:14 INFO - 'NO_EM_RESTART': '1', 22:34:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:34:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:34:14 INFO - 'PWD': '/builds/slave/test', 22:34:14 INFO - 'SHELL': '/bin/bash', 22:34:14 INFO - 'SHLVL': '1', 22:34:14 INFO - 'TERM': 'linux', 22:34:14 INFO - 'TMOUT': '86400', 22:34:14 INFO - 'USER': 'cltbld', 22:34:14 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1469597620.725820-1875803709', 22:34:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:34:14 INFO - '_': '/tools/buildbot/bin/python'} 22:34:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:34:14 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:34:14 INFO - Cleaning up... 22:34:14 INFO - Return code: 0 22:34:14 INFO - Running post-action listener: _resource_record_post_action 22:34:14 INFO - Running post-action listener: _start_resource_monitoring 22:34:14 INFO - Starting resource monitoring. 22:34:14 INFO - [mozharness: 2016-07-27 05:34:14.606860Z] Finished create-virtualenv step (success) 22:34:14 INFO - [mozharness: 2016-07-27 05:34:14.607299Z] Running install step. 22:34:14 INFO - Running pre-action listener: _resource_record_pre_action 22:34:14 INFO - Running main action method: install 22:34:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:34:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:34:14 INFO - Reading from file tmpfile_stdout 22:34:14 INFO - Detecting whether we're running mozinstall >=1.0... 22:34:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:34:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:34:14 INFO - Reading from file tmpfile_stdout 22:34:14 INFO - Output received: 22:34:14 INFO - Usage: mozinstall [options] installer 22:34:14 INFO - Options: 22:34:14 INFO - -h, --help show this help message and exit 22:34:14 INFO - -d DEST, --destination=DEST 22:34:14 INFO - Directory to install application into. [default: 22:34:14 INFO - "/builds/slave/test"] 22:34:14 INFO - --app=APP Application being installed. [default: firefox] 22:34:14 INFO - mkdir: /builds/slave/test/build/application 22:34:14 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'] 22:34:14 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 22:34:32 INFO - Reading from file tmpfile_stdout 22:34:32 INFO - Output received: 22:34:32 INFO - /builds/slave/test/build/application/firefox/firefox 22:34:32 INFO - Running post-action listener: _resource_record_post_action 22:34:32 INFO - [mozharness: 2016-07-27 05:34:32.168434Z] Finished install step (success) 22:34:32 INFO - [mozharness: 2016-07-27 05:34:32.168710Z] Running run-tests step. 22:34:32 INFO - Running pre-action listener: _resource_record_pre_action 22:34:32 INFO - Running main action method: run_tests 22:34:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:34:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:34:32 INFO - Python 2.7.3 22:34:32 INFO - Return code: 0 22:34:32 INFO - grabbing minidump binary from tooltool 22:34:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 22:34:32 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 0x29463b0>, '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 0x29472b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2947740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:34:32 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 22:34:32 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 22:34:32 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 22:34:32 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:34:32 INFO - Return code: 0 22:34:32 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 22:34:32 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:34:32 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:34:32 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 22:34:32 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:34:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RiB9WYIKQUiOH4wrZDkgwQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-056', '--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 22:34:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Inbound --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/RiB9WYIKQUiOH4wrZDkgwQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-056 --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 22:34:32 INFO - Using env: {'DISPLAY': ':0', 22:34:32 INFO - 'HOME': '/home/cltbld', 22:34:32 INFO - 'LANG': 'en_US.UTF-8', 22:34:32 INFO - 'LANGUAGE': 'en_US:en', 22:34:32 INFO - 'LOGNAME': 'cltbld', 22:34:32 INFO - 'MAIL': '/var/mail/cltbld', 22:34:32 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:34:32 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 22:34:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:34:32 INFO - 'MOZ_NO_REMOTE': '1', 22:34:32 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:34:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:34:32 INFO - 'NO_EM_RESTART': '1', 22:34:32 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:34:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:34:32 INFO - 'PWD': '/builds/slave/test', 22:34:32 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:34:32 INFO - 'SHELL': '/bin/bash', 22:34:32 INFO - 'SHLVL': '1', 22:34:32 INFO - 'TERM': 'linux', 22:34:32 INFO - 'TMOUT': '86400', 22:34:32 INFO - 'USER': 'cltbld', 22:34:32 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1469597620.725820-1875803709', 22:34:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:34:32 INFO - '_': '/tools/buildbot/bin/python'} 22:34:32 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RiB9WYIKQUiOH4wrZDkgwQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-056', '--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 22:34:32 INFO - mozversion application_buildid: 20160726192234 22:34:32 INFO - mozversion application_changeset: 250943418f3a43c46de84797ad58a22f724caf58 22:34:32 INFO - mozversion application_display_name: Nightly 22:34:32 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:34:32 INFO - mozversion application_name: Firefox 22:34:32 INFO - mozversion application_remotingname: firefox 22:34:32 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 22:34:32 INFO - mozversion application_vendor: Mozilla 22:34:32 INFO - mozversion application_version: 50.0a1 22:34:32 INFO - mozversion platform_buildid: 20160726192234 22:34:32 INFO - mozversion platform_changeset: 250943418f3a43c46de84797ad58a22f724caf58 22:34:32 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 22:34:32 INFO - mozversion platform_version: 50.0a1 22:34:32 INFO - using testdate: 1469597672 22:34:32 INFO - actual date: 1469597672 22:34:32 INFO - starting webserver on 'localhost:60899' 22:34:32 INFO - SUITE-START | Running 2 tests 22:34:32 INFO - TEST-START | dromaeo_css 22:34:32 INFO - operating with platform_type : linux_ 22:34:32 INFO - Initialising browser for dromaeo_css test... 22:34:32 INFO - TEST-INFO | started process 3361 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpObF4tN/profile http://localhost:60899/getInfo.html) 22:34:35 INFO - PROCESS | 3361 | JavaScript error: , line 0: uncaught exception: undefined 22:34:36 INFO - PROCESS | 3361 | __metrics Screen width/height:1600/1200 22:34:36 INFO - PROCESS | 3361 | colorDepth:24 22:34:36 INFO - PROCESS | 3361 | Browser inner width/height: 1024/697 22:34:36 INFO - PROCESS | 3361 | __metrics 22:34:37 INFO - PROCESS | 3361 | 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" 22:34:37 INFO - PROCESS | 3361 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 58: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:34:38 INFO - TEST-INFO | 3361: exit 0 22:34:38 INFO - Browser initialized. 22:34:38 INFO - Running cycle 1/1 for dromaeo_css test... 22:34:38 INFO - TEST-INFO | started process 3446 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpObF4tN/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:34:50 INFO - PROCESS | 3446 | RSS: Main: 157364224 22:34:50 INFO - PROCESS | 3446 | 22:35:41 INFO - PROCESS | 3446 | 1469597741992 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] 22:37:50 INFO - PROCESS | 3446 | Cycle 1(1): loaded http://localhost:60899/tests/dromaeo/cssquery-dojo.html (next: http://localhost:60899/tests/dromaeo/cssquery-ext.html) 22:37:51 INFO - PROCESS | 3446 | RSS: Main: 256217088 22:37:51 INFO - PROCESS | 3446 | 22:40:53 INFO - PROCESS | 3446 | Cycle 1(1): loaded http://localhost:60899/tests/dromaeo/cssquery-ext.html (next: http://localhost:60899/tests/dromaeo/cssquery-jquery.html) 22:40:54 INFO - PROCESS | 3446 | RSS: Main: 242618368 22:40:54 INFO - PROCESS | 3446 | 22:43:56 INFO - PROCESS | 3446 | Cycle 1(1): loaded http://localhost:60899/tests/dromaeo/cssquery-jquery.html (next: http://localhost:60899/tests/dromaeo/cssquery-mootools.html) 22:43:57 INFO - PROCESS | 3446 | RSS: Main: 217083904 22:43:57 INFO - PROCESS | 3446 | 22:46:59 INFO - PROCESS | 3446 | Cycle 1(1): loaded http://localhost:60899/tests/dromaeo/cssquery-mootools.html (next: http://localhost:60899/tests/dromaeo/cssquery-prototype.html) 22:46:59 INFO - PROCESS | 3446 | RSS: Main: 218554368 22:46:59 INFO - PROCESS | 3446 | 22:50:02 INFO - PROCESS | 3446 | Cycle 1(1): loaded http://localhost:60899/tests/dromaeo/cssquery-prototype.html (next: http://localhost:60899/tests/dromaeo/cssquery-yui.html) 22:50:02 INFO - PROCESS | 3446 | RSS: Main: 187240448 22:50:02 INFO - PROCESS | 3446 | 22:53:06 INFO - PROCESS | 3446 | Cycle 1(1): loaded http://localhost:60899/tests/dromaeo/cssquery-yui.html (next: http://localhost:60899/tests/dromaeo/cssquery-dojo.html) 22:53:06 INFO - PROCESS | 3446 | RSS: Main: 277139456 22:53:06 INFO - PROCESS | 3446 | 22:53:06 INFO - PROCESS | 3446 | __start_tp_report 22:53:06 INFO - PROCESS | 3446 | _x_x_mozilla_page_load 22:53:06 INFO - PROCESS | 3446 | _x_x_mozilla_page_load_details 22:53:06 INFO - PROCESS | 3446 | |i|pagename|runs| 22:53:06 INFO - PROCESS | 3446 | |0;dojo.html;3075.5540446635223;3079.7382222511087;3090.953635695465;3122.2038380213044;3148.0870547541213;5292.550133238679;5307.548858347041;5366.195070739382;5373.7581808818595;5399.48704873037;5334.546563542103;5403.324584426947;5403.83788486345;5451.236826844245;5458.808941687042;4940.357753492041;4966.5778408835295;4978.751062446881;5007.649464537484;5048.495150484959;4910.7299098549565;5025.899482010358;5079.91636601346;5083.440821509631;5129.9230511542355;4837.516248375169;4879.853604391874;5019.272205530196;5022.995400919831;5031.597472202233;4891.4863939286215;4927.728974906378;4940.851774446773;4959.528844759742;4984.576311013569;4855.271709243606;4883.949950760603;4885.2916327132725;4897.608191344684;4942.654014219006;5252.3171987641745;5360.088784906537;5372.91940620891;5378.300820893259;5399.4600539945695;5787.276590426197;5850.180974663551;5889.7644094236175;5899.498542623882;5907.350191478934;5878.470937615635;5903.586748927577;5903.940960590425;5942.3760505146765;5956.97021514894;5283.630145889751;5420.159875219348;5533.778648854041;5548.0845660465975;5550.306211723535;5915.1718759373725;5915.349311575723;5932.940670593325;5985.760569577255;5986.132010858401;5699.230603868425;5736.340320863154;5754.856128596735;5822.505087067647;5822.883542329215;5604.32748070226;5666.801661941812;5845.503132233723;5905.4685078343155;5908.143319218775;5635.690037048002;5657.2079908811975;5680.176374425685;5725.742341594639;5734.713264336767;5700.686462244616;5702.144678298288;5708.885822283614;5713.857153571194;5714.114312281603;4290.914181716348;4346;4348.065165989327;4351.325544540601;4398.406215160912;3909.8240579174008;3966.464527288837;3969.186316805048;3976.423418604344;4032.475778148822;5540.9168862467095;5545.168224766237;5545.750441230154;5546.584006199551;5596.692181930033;5699.715014249271;5878.853028674232;5883.441073098049;5886.23478947734;5915.556333274935;5402.8919421611345;5442;5470.17947307907;5537.1140617501005;5543.334799824047;4932.7286999215385;4966.478519755374;4991.9500804991485;5005.524475174852;5046.772895219724;5889.204957330791;5891.145783861316;5907.881842363215;5910.911336329958;5966.850828729316;5563.944360556343;5648.971755141362;5704.657720536781;5717.028105222205;5795.623284486495;5924.496417804535;5960.493358064613;5969.373215812451;5979.641221526722;6003.6998150093195;5734.283214598176;5742.822721342031;5746.224259724883;5753.769849205984;5755.3381361144;5658.179563963285;5777.104548794943;5829.271341082364;5849.766009359578;5875.059990401515;5704.087346024616;5726.226959360433;5729.570282228933;5732.656040637575;5734.741936612779;5722.771089156554;5724.112762521816;5730.598858079894;5732.283464566929;5740.368559458373;5387.81142660005;5551.278333816578;5568.248286481273;5614.129809879475;5702.800401985911;5751.424857514216;5752.108423194411;5806.970965145148;5811.331696854916;5812.447817457335;5611.3266408031295;5626.156076588544;5629.324481062299;5636.239107720569;5637.83086507389 22:53:06 INFO - PROCESS | 3446 | |1;ext.html;598.2521847690386;603.4388019142198;622.6575383539054;637.2225884421008;648.4002297874465;34299.65700342996;44237.77881110594;45523;45567;48531.514684853144;45053.32420013702;45099.77450112753;45595.544044559545;45691.31463028049;45701.77149114258;8076.35389168868;8921.286297096216;9055.95472022639;9061.13919177677;9076.727698169065;10506.369617822917;10797.40614266215;10803.891961080406;11122.388268645222;11125.833112503318;10941.56233750649;11041.668749937515;11043.889561104366;11050;11058.281211721212;8099.433039687225;10592.364458132537;10755.731106722316;10828.079613232885;10841;10232.079112879877;10674.466276686213;10687.412192329419;10688.732781680443;10696.679099626946;42644.14711705749;43051.784741076095;43064.3540346895;43071.784641076905;43101.78449107734;3481.495183198422;3535.734819888519;3576.6065732769375;3663.322285377187;3759.7180211484247;33480.665193347995;33605.159871003176;33767.83116084428;35614.46578301328;35759.28481430383;4383.232934236496;4423.203823311803;4480.439945006874;4505.684602077856;4659.743714095724;361790;362459.18770406244;362933.8533073362;363030;363573;57647;57782;58054;58120;58321;61653.38346616566;61707;61722.69138654368;61731.691341543;61744;15795.842041579437;17497.287920372364;17644.647107057786;17817.287308507515;17913.7312940306;12064;12717.236965782082;12963.675908102374;13417.194968301934;13571.253581053137;19599;19629.822210667404;19631.509212269808;19641.508962276057;19715.802841971396;7054.2240353561065;7109.28907109285;7143.785686429415;7155.320244576861;7256.238094999949;947.8483442649143;1020.698893826323;1026.1688032693542;1064.723171975292;1097.5993762276871;32253;32682.346353072804;32753;32791.836040819646;33273.3345333092;1069.8662667166604;1092.639428988432;1092.8032954068344;1094.0919037199264;1108;16311.918440407959;17393;17776.51128841963;18035.458936231935;18179;544229;548621;550853;551152;552355;61670.69164654148;62227;62261.377386226464;62268.68865655643;62376.376236237964;1217.0993464835958;1360.299445785441;1362.2371471975725;1362.6797702539895;1377.8966577506726;29584.704152958195;32955.505667415484;33206.335873282886;33496.68808207541;33636.831815840764;50066.24900626421;50436;50608.493915060375;50650.746746266035;50735;449153;450905;451071;454273;456204;24868.37829054288;27280.181594552196;27824.721752782214;28051.438971220054;28109.437811243253;46141.53858461372;46253.76873115613;47204;47428.52571474241;48165.27752083792;40182.79908600439;40507.1898562033;40910.1817963645;41081.589184107776;42228.78885605675;39696.60303396929;39858.402123967586;40831.795841020605;40944;42003.36994945017;38640;39451.40822887716;39549.60450395574;40307;40391.39412908867;34702.82648586741;36379.27241455209;36690;37083.25833483369;37487;27463;27483.725162748116;27502.587461187697;27803.16590502288;27818.860905695343 22:53:06 INFO - PROCESS | 3446 | |2;jquery.html;382.64414094891737;387.6317498376541;404.14119995010594;404.22590738735323;413.0582272418886;14534.781978270334;17086.914565427185;18772;19755.703664445027;20081.297154599593;15552;18443.72334414985;18932.905335473337;19144.712829307573;19782.802171978277;5822.505087067594;6104.328523862371;6116.694165291718;6118.908216376758;6138.47822935051;6051.818445446622;6108.389161083878;6125.908111378333;6127.724252408652;6146.539009574278;5807.128930660392;5925.466707996279;5970.79102231422;5971.0744834550715;5977.133315669225;5927.436893495111;5952.255968003999;5999.250093738305;6002.099685047256;6077;5218.660787048868;5311.548518375943;5333.866653333659;5342.358916929955;5394.6493477924205;23545;23660.881695591583;23946.521069578685;24240.7575924242;24273.878630606734;2315.6758053872472;2380.155044959049;2560.8591527465983;2621.279148234232;2637.6478809963523;15218.543443696595;16080.71354291654;16241.350345986146;16566.751498727528;17008.574785630335;2053.486628342914;2111.218849025855;2116.333354993172;2177.2379667116384;2206.6248737714627;92908;98546.63806938329;100132;100172.49913750385;101084;17587;17635.470935871945;17862.5534361642;18063.096845157954;18716.66041744782;32119;32187.51718724221;32243.51634725481;32263.35473290521;32284;9785.412875227508;9993.450360230258;10082.29423940317;10131.797364052825;10292.330998485075;8455.619497122645;8557.358198135165;8592.183742544448;8796.648134074694;8811.779705507286;7336.796657659369;8223.21879421454;8678.39251252419;8828;9013.909860901438;3198.6321573018936;3230.8707651693667;3253.4143854106014;3388.610669625491;3468.965310346864;915.4232833314969;932.8181004704018;1000.1598657127902;1003.5634498992962;1066.4667666166918;19198.904005480163;19244.615107698046;19286.132124054453;19407.611847762964;19904.203831846855;919.0946917286467;929.3680297397699;931.8928323242778;950.078423928788;1051.295631926611;10595.788084238273;10875.728106797393;10888.020078193;10953.671389858317;10957.56169753201;425351;438396;490193;494680;497558;89569;91942.54028729947;92426;92874;102186;1009.914157296638;1045.2056437107854;1062.054771253585;1070.8126077936486;1080.0225795654944;17026.574335641708;17456.30197699338;17737.467875963743;17971.73042404391;17992.10039498046;44521.77739111284;44674.55325446834;45184.54815451804;45353.092938141715;45845.31232031455;56106.43893561175;57834.7108264456;61798.38201617926;62839.6858015707;63168.68415657892;8049.195080492138;8154.551499667576;8210;8549.14508549165;8573.18554737299;12760.553380631633;13365.86634133685;13543.97992895622;13618.250996195304;13813.723725525233;11481.885181148082;11669.35818529989;11721.413929303671;11986.940065299617;12022.939885300517;11289.322640641587;11373.20387572862;11387.601433949772;11790.587329443428;11841.111916606116;11650.359230242419;11983.64049078529;12021.459034343301;12068.577599783965;12463.439145238668;8255.298299644597;8302.501849889026;8308.501489910625;8479.830403391774;8543.957280213559;8742.825143496968;11066.28069175501;11205.439728013404;11217.270877392943;11839.1712580122 22:53:06 INFO - PROCESS | 3446 | |3;mootools.html;1235.5860786636476;1477.0546850015992;1533.1414407931557;1667.533090734594;1805.2147315917578;8623.956880215597;8839.734807955763;8901.4214076085;9072.77318067047;9081.545922703855;8917.643294268239;8976.73069807905;8983;8985.685501007467;9005.50469724165;2352.9529409411803;2353.6587194856734;2381.535600557892;2383.7854593086618;2384.177458776719;2301.4591570980806;2333.7782910623464;2372.3713215997773;2390.020091762378;2393.4136136646457;2355.528894221154;2361.681173041635;2372.4306166519996;2384.8092152627837;2393.0308225168833;2378.5242951409787;2393.4255778613096;2393.5930891748444;2399.1003373734848;2422.2491027781357;2292.9197478088186;2343.7539058398966;2357.799587035087;2362.0197617988515;2364.0307473935777;8762.518061506615;9000.909990900072;9031.142041506046;9073.909260907372;9081.500517471537;5389.78440862365;5560.944390556083;5570.554355651579;5586.16207568868;5592.273004509388;3442.277121804424;3458.6887180153653;3501.474778783203;3507.298540291926;3512.1395258161915;1091.683411810574;1100.9614663486739;1148.632437619962;1163.5055101581793;1172.3962159487871;12613.684657883534;12663.556775512905;12698;12747.171433856769;12779.361031948365;4404.889877753018;4476.888077798081;4606.792694328697;4624;4688.9531104689195;9990.15083717892;10008.349457285252;10012.24908131893;10014.098731114234;10022.699319020441;5342.8664283392445;5374.758135883894;5382.67703937765;5386.892262154735;5387.164989426645;4413.713108647928;4854.126257273654;4930.457649658606;4971.1797553403285;4997.9750101249265;3935.114599215211;3987.9202415951518;4079.9796001019804;4082.4284600156;4104.117614712851;1173.9647810565696;1215.076541828217;1220.9633710988685;1223.1621339382552;1237.2329155923385;847.9109693482095;958.3531116496476;972.397113789455;976.8534719792088;996.6645489199486;5792.739326730307;5903.2325797646035;5915.497182739429;5998.220231369981;6008.489278411296;983.9065288797552;1023.6417253961054;1028.2545154762738;1063.9255252132432;1073.983890241647;3988.6210809973004;4032.94971777328;4035.1526179502634;4098.467199264136;4158.91682166355;11128.888711112948;11366.261193022427;11454.828177577343;11496.655100347003;11508.53965841357;10698.946505267424;10786.137109031259;10954.452277386099;11362.147838912115;11425.37160456183;8055.275025247756;8289.04675962272;8301.128381519975;8395.95802020986;8457.703980360659;6110.633361998294;6133.84665383369;6176.289726681489;6310.716017779303;6408.423241908251;10566.841497377392;10569.207309451667;10577.206709496972;10585.52365143555;10616.628418005334;10255.692329230134;10639.521221545205;10652.09457196116;10731.839022414826;10821.458927053458;7233.348998590152;7928.682852685828;8089.312408445348;8135.308498777434;8328.958355208186;8825.073367296598;8984.416012959136;9002.96465906403;9003.459792412476;9007.729768106967;7662.108975541949;7912.960435197787;7934.285914267512;8033.35733141359;8221;7778.533288002738;7899.447038707235;7947.324477419253;7976.800579985546;7977.361811054986;6907.861842763217;6997.230304666381;7008.544444611288;7009.579425234502;7011.123609548807;5065.620078494201;5091.796328146833;5226.895462090661;5250.369955605352;5489.039418101896;5326.174442961347;5329.893402131858;5365.302510673743;5368.033753924331;5615.775368985195 22:53:06 INFO - PROCESS | 3446 | |4;prototype.html;322.7208464678053;325.123791382225;331.5457822782788;337.99615143025204;341.36846833358277;5294.41761406245;5347.385050719168;5378.757955891984;5385.272988146599;5477.219903531358;5142.562882155012;5177.482251774821;5255.185446255837;5263.815766448175;5427.309429587888;4078.2659121358142;4137.172565486907;4167.3332266837315;4189.2040512302565;4192.454980852485;4190.559991200926;4211.810468528924;4213.71481698082;4236.512801027875;4238.046439551107;4104.8563300284495;4131.5455299916985;4167.208230436223;4172.33242681171;4184.079502509443;4158.459400277975;4188.32986722126;4197.286461301586;4197.643200327961;4214.957850421502;3706.610805865372;3726.3851464508325;3726.6832319252894;3734.719896007783;3736.532933383327;5020.899582008376;5174.818881339135;5198.662086964373;5228.895422091537;5238;4737.786799593992;4739.502352252998;4756.952430475721;4759.286107083965;4821.397325334333;5560.610757246995;5569.303837020372;5576.414476479992;5583.8604034899045;5584.6090773645865;3764.2471505698695;3951.38753493209;4048.5020542399207;4069.5930406959365;4072.8188825240645;5743.128435782067;5775.335836378787;5776;5776.884462310731;5779.739911703941;4036.253293140778;4070.776107314126;4086.8773936781945;4092.652124569445;4092.9181416371507;3873.4189871518934;4149.8962525937095;4163.333866581333;4176.812043458051;4183.184279065553;4643.605293550018;4653.697509661861;4656;4669.906601867943;4684.648651351163;5624.565735737361;5631.35326396593;5646.887062258732;5655.830325090254;5669.744861481161;1389.3817251323267;1397.9301034948212;1404.7401230772339;1410.1750475971671;1414.5261337452068;901.5942825728448;923.1045885491063;929.0198840223494;930.1907340613574;933.9766505837408;4864.148773964542;4898.289747986593;4899.951000490021;4943.9258411123865;4984.476629953875;4809.807607695723;5094.5669618082875;5099.235114732745;5106.489351064938;5131.61512886535;4819.927701084486;4829.468758436569;4847.127517046965;4849.2968519565175;4866.732329721828;2733.5079685656374;2739.917732495653;2750.7386798254133;2759.530879750447;2761.467385601011;5064.746762661852;5117.334746482933;5155;5208.244804503326;5312.2297266896085;5155.200943853709;5192.45479224676;5207.218917162456;5219.634625576173;5270.683758974474;5169.508896654812;5200.00999425332;5250.613354386475;5252.501012403815;5322.840314790563;4916.680415772963;5060.216880715665;5072.188449848007;5085.669431486942;5124.692518448905;5019.0965626187635;5043;5054.671446355975;5074.112030394593;5126.0521002018995;4979.800807967786;5129.230615407719;5192.22216114377;5272.47098341492;5282.735863206747;3683.3369993401443;3701.021401992815;3735.477033215406;3743.9782517441367;3759.3421151297866;5102.387713474407;5557.944420555743;5646.520045796154;5796.710299431785;5808.680522571299;5662.915056274077;5688.3742788294085;5692.658440493584;5709.22925405065;5717.7712891485535;5559.9444005561045;5676.347220069745;5694.487496125369;5697.772089116389;5740;5526.91709624364;5543.417941116286;5574.303212098488;5577.211394302849;5578.2748242728185;5603.142397377285;5677.290338707661;5717.256756621613;5724.0555308375315;5729.627574207663;5370.758315875717;5533.225348451139;5555.416681248573;5564.276644036249;5608.270924779753 22:53:06 INFO - PROCESS | 3446 | |5;yui.html;727.4616783580152;735.3675838778653;758.9089309282887;766.6281853301148;827.1934863508077;15582.922085389571;16290.26693798779;16301.266443010049;16309.347626094972;16360.918195409022;15605.765913511306;15638.609034774136;15789.447369342075;15842.920785396056;15843.524694259162;661.4278648968632;749.0412272291476;776.8873513340562;802.7391097893179;805.3154818404339;776.9456138070337;785.8781888807245;786.850498405304;790.083503136359;794.1026639896937;849.0278630967539;849.180540778149;849.587949844325;849.7578190215789;850.8170743290192;844.0420123160196;846.0989046665312;846.3398549131687;847.089378917666;849.1975083546051;504.6316189181881;518.049379388822;612.8039027511198;625.9937400625981;627.0750642801877;14980;15024.549263522113;15029.173395463245;15033.849661503355;15037.548873533811;13502.594922152253;13758.93120534401;14002.229877356742;14130.22283774392;14279.714405711931;11060.115190784718;11224.326540407601;11813.350265735382;13097.934510327483;13753.724925501534;4457.621102206284;4506.842260520866;4522.86431407055;4545.295479200696;4563.133004729124;77066.99999999943;78919.6054019732;79348;79446;80154.19845801525;10649.361038337569;10995.450227488594;11002.284851484628;11010.559577616805;11108.55565777376;20337.389878303675;20539.897300513403;20545.383638490868;20552.588948220953;20566.485837853874;8648.740537783877;8676.60955257015;8745.868811967826;8938.4190027648;9061.909380906238;4786.569208771228;4959.20652695567;4982.053409852117;5010.423801262828;5035.823746168867;3383.3909896218424;3446.9310613787584;3446.9827650861585;3454.4990976308295;3456.948145777815;591.5326891755465;591.5504216795266;599.2958274028;600.5255847880126;606.0212756398384;721.7546034348346;726.3317747672154;748.6631016042802;764.2357642357642;772.5326177662546;9815.312928095107;9915.355501892354;10042.799144017225;10049.246306526893;10191.439470829175;673.2123415603755;689.1867596236489;745.4468445573888;746.7484298501644;758.6623952341155;4415.8012889419415;4754.9286760698615;4770.236762118114;4799.3760811095035;4815.3258543803895;109866;117518;118715.40642296906;119444;119546.4022679881;100088;101803;102793;104918;106163;85717.57141214254;87812.12187878168;88165.55917220374;89658.55170724104;89959.55020224857;9282.396644218104;9353.345265831598;9363.204127648954;9427.764305892133;9529.428234305964;51936.74031629818;52480.737596311774;52883;53446.46553534415;55440.168397474874;77557.61221193858;79222.05834562315;80512;80655.19344806712;80983.59508202657;12234.816477753018;12277.815832762695;12320.691982700146;12487.375631218585;12812.743745124859;13333.66665833362;13565;13767.518136865563;14266;14661.92669036648;12793.232406055666;13078.476860925457;13099.738005239651;13482.662933426744;13537.661558460723;12543.435545400296;13045.804312935503;13735.931320343334;13744;13755.724885502434;12625.684357891127;13230.272335021666;13525.823827630129;13573.405825551235;13670.453181873012;9720.369509934668;9726;9840.852387214054;10036.247281453774;10188.898111018794;10892.945535272589;11054.944725276644;11067.833982490109;11247.775044499227;11342.546298147981 22:53:06 INFO - PROCESS | 3446 | __end_tp_report 22:53:06 INFO - PROCESS | 3446 | __start_cc_report 22:53:06 INFO - PROCESS | 3446 | _x_x_mozilla_cycle_collect,982 22:53:06 INFO - PROCESS | 3446 | __end_cc_report 22:53:06 INFO - PROCESS | 3446 | __startTimestamp1469598786398__endTimestamp 22:53:06 INFO - PROCESS | 3446 | 22:53:06 INFO - PROCESS | 3446 | ------- Summary: start ------- 22:53:06 INFO - PROCESS | 3446 | Number of tests: 6 22:53:06 INFO - PROCESS | 3446 | 22:53:06 INFO - PROCESS | 3446 | [#0] dojo.html Cycles:165 Average:5391.71 Median:5626.16 stddev:617.98 (11.0%) stddev-sans-first:592.56 22:53:06 INFO - PROCESS | 3446 | Values: 3075.6 3079.7 3091.0 3122.2 3148.1 5292.6 5307.5 5366.2 5373.8 5399.5 5334.5 5403.3 5403.8 5451.2 5458.8 4940.4 4966.6 4978.8 5007.6 5048.5 4910.7 5025.9 5079.9 5083.4 5129.9 4837.5 4879.9 5019.3 5023.0 5031.6 4891.5 4927.7 4940.9 4959.5 4984.6 4855.3 4883.9 4885.3 4897.6 4942.7 5252.3 5360.1 5372.9 5378.3 5399.5 5787.3 5850.2 5889.8 5899.5 5907.4 5878.5 5903.6 5903.9 5942.4 5957.0 5283.6 5420.2 5533.8 5548.1 5550.3 5915.2 5915.3 5932.9 5985.8 5986.1 5699.2 5736.3 5754.9 5822.5 5822.9 5604.3 5666.8 5845.5 5905.5 5908.1 5635.7 5657.2 5680.2 5725.7 5734.7 5700.7 5702.1 5708.9 5713.9 5714.1 4290.9 4346.0 4348.1 4351.3 4398.4 3909.8 3966.5 3969.2 3976.4 4032.5 5540.9 5545.2 5545.8 5546.6 5596.7 5699.7 5878.9 5883.4 5886.2 5915.6 5402.9 5442.0 5470.2 5537.1 5543.3 4932.7 4966.5 4992.0 5005.5 5046.8 5889.2 5891.1 5907.9 5910.9 5966.9 5563.9 5649.0 5704.7 5717.0 5795.6 5924.5 5960.5 5969.4 5979.6 6003.7 5734.3 5742.8 5746.2 5753.8 5755.3 5658.2 5777.1 5829.3 5849.8 5875.1 5704.1 5726.2 5729.6 5732.7 5734.7 5722.8 5724.1 5730.6 5732.3 5740.4 5387.8 5551.3 5568.2 5614.1 5702.8 5751.4 5752.1 5807.0 5811.3 5812.4 5611.3 5626.2 5629.3 5636.2 5637.8 22:53:06 INFO - PROCESS | 3446 | 22:53:06 INFO - PROCESS | 3446 | [#1] ext.html Cycles:180 Average:61852.74 Median:36886.63 stddev:122229.61 (331.4%) stddev-sans-first:122485.98 22:53:06 INFO - PROCESS | 3446 | Values: 598.3 603.4 622.7 637.2 648.4 34299.7 44237.8 45523.0 45567.0 48531.5 45053.3 45099.8 45595.5 45691.3 45701.8 8076.4 8921.3 9056.0 9061.1 9076.7 10506.4 10797.4 10803.9 11122.4 11125.8 10941.6 11041.7 11043.9 11050.0 11058.3 8099.4 10592.4 10755.7 10828.1 10841.0 10232.1 10674.5 10687.4 10688.7 10696.7 42644.1 43051.8 43064.4 43071.8 43101.8 3481.5 3535.7 3576.6 3663.3 3759.7 33480.7 33605.2 33767.8 35614.5 35759.3 4383.2 4423.2 4480.4 4505.7 4659.7 361790.0 362459.2 362933.9 363030.0 363573.0 57647.0 57782.0 58054.0 58120.0 58321.0 61653.4 61707.0 61722.7 61731.7 61744.0 15795.8 17497.3 17644.6 17817.3 17913.7 12064.0 12717.2 12963.7 13417.2 13571.3 19599.0 19629.8 19631.5 19641.5 19715.8 7054.2 7109.3 7143.8 7155.3 7256.2 947.8 1020.7 1026.2 1064.7 1097.6 32253.0 32682.3 32753.0 32791.8 33273.3 1069.9 1092.6 1092.8 1094.1 1108.0 16311.9 17393.0 17776.5 18035.5 18179.0 544229.0 548621.0 550853.0 551152.0 552355.0 61670.7 62227.0 62261.4 62268.7 62376.4 1217.1 1360.3 1362.2 1362.7 1377.9 29584.7 32955.5 33206.3 33496.7 33636.8 50066.2 50436.0 50608.5 50650.7 50735.0 449153.0 450905.0 451071.0 454273.0 456204.0 24868.4 27280.2 27824.7 28051.4 28109.4 46141.5 46253.8 47204.0 47428.5 48165.3 40182.8 40507.2 40910.2 41081.6 42228.8 39696.6 39858.4 40831.8 40944.0 42003.4 38640.0 39451.4 39549.6 40307.0 40391.4 34702.8 36379.3 36690.0 37083.3 37487.0 27463.0 27483.7 27502.6 27803.2 27818.9 22:53:06 INFO - PROCESS | 3446 | 22:53:06 INFO - PROCESS | 3446 | [#2] jquery.html Cycles:180 Average:30163.10 Median:17658.82 stddev:77959.74 (441.5%) stddev-sans-first:78146.37 22:53:06 INFO - PROCESS | 3446 | Values: 382.6 387.6 404.1 404.2 413.1 14534.8 17086.9 18772.0 19755.7 20081.3 15552.0 18443.7 18932.9 19144.7 19782.8 5822.5 6104.3 6116.7 6118.9 6138.5 6051.8 6108.4 6125.9 6127.7 6146.5 5807.1 5925.5 5970.8 5971.1 5977.1 5927.4 5952.3 5999.3 6002.1 6077.0 5218.7 5311.5 5333.9 5342.4 5394.6 23545.0 23660.9 23946.5 24240.8 24273.9 2315.7 2380.2 2560.9 2621.3 2637.6 15218.5 16080.7 16241.4 16566.8 17008.6 2053.5 2111.2 2116.3 2177.2 2206.6 92908.0 98546.6 100132.0 100172.5 101084.0 17587.0 17635.5 17862.6 18063.1 18716.7 32119.0 32187.5 32243.5 32263.4 32284.0 9785.4 9993.5 10082.3 10131.8 10292.3 8455.6 8557.4 8592.2 8796.6 8811.8 7336.8 8223.2 8678.4 8828.0 9013.9 3198.6 3230.9 3253.4 3388.6 3469.0 915.4 932.8 1000.2 1003.6 1066.5 19198.9 19244.6 19286.1 19407.6 19904.2 919.1 929.4 931.9 950.1 1051.3 10595.8 10875.7 10888.0 10953.7 10957.6 425351.0 438396.0 490193.0 494680.0 497558.0 89569.0 91942.5 92426.0 92874.0 102186.0 1009.9 1045.2 1062.1 1070.8 1080.0 17026.6 17456.3 17737.5 17971.7 17992.1 44521.8 44674.6 45184.5 45353.1 45845.3 56106.4 57834.7 61798.4 62839.7 63168.7 8049.2 8154.6 8210.0 8549.1 8573.2 12760.6 13365.9 13544.0 13618.3 13813.7 11481.9 11669.4 11721.4 11986.9 12022.9 11289.3 11373.2 11387.6 11790.6 11841.1 11650.4 11983.6 12021.5 12068.6 12463.4 8255.3 8302.5 8308.5 8479.8 8544.0 8742.8 11066.3 11205.4 11217.3 11839.2 22:53:06 INFO - PROCESS | 3446 | 22:53:06 INFO - PROCESS | 3446 | [#3] mootools.html Cycles:180 Average:5935.39 Median:4541.84 stddev:3393.54 (74.7%) stddev-sans-first:3384.67 22:53:06 INFO - PROCESS | 3446 | Values: 1235.6 1477.1 1533.1 1667.5 1805.2 8624.0 8839.7 8901.4 9072.8 9081.5 8917.6 8976.7 8983.0 8985.7 9005.5 2353.0 2353.7 2381.5 2383.8 2384.2 2301.5 2333.8 2372.4 2390.0 2393.4 2355.5 2361.7 2372.4 2384.8 2393.0 2378.5 2393.4 2393.6 2399.1 2422.2 2292.9 2343.8 2357.8 2362.0 2364.0 8762.5 9000.9 9031.1 9073.9 9081.5 5389.8 5560.9 5570.6 5586.2 5592.3 3442.3 3458.7 3501.5 3507.3 3512.1 1091.7 1101.0 1148.6 1163.5 1172.4 12613.7 12663.6 12698.0 12747.2 12779.4 4404.9 4476.9 4606.8 4624.0 4689.0 9990.2 10008.3 10012.2 10014.1 10022.7 5342.9 5374.8 5382.7 5386.9 5387.2 4413.7 4854.1 4930.5 4971.2 4998.0 3935.1 3987.9 4080.0 4082.4 4104.1 1174.0 1215.1 1221.0 1223.2 1237.2 847.9 958.4 972.4 976.9 996.7 5792.7 5903.2 5915.5 5998.2 6008.5 983.9 1023.6 1028.3 1063.9 1074.0 3988.6 4032.9 4035.2 4098.5 4158.9 11128.9 11366.3 11454.8 11496.7 11508.5 10698.9 10786.1 10954.5 11362.1 11425.4 8055.3 8289.0 8301.1 8396.0 8457.7 6110.6 6133.8 6176.3 6310.7 6408.4 10566.8 10569.2 10577.2 10585.5 10616.6 10255.7 10639.5 10652.1 10731.8 10821.5 7233.3 7928.7 8089.3 8135.3 8329.0 8825.1 8984.4 9003.0 9003.5 9007.7 7662.1 7913.0 7934.3 8033.4 8221.0 7778.5 7899.4 7947.3 7976.8 7977.4 6907.9 6997.2 7008.5 7009.6 7011.1 5065.6 5091.8 5226.9 5250.4 5489.0 5326.2 5329.9 5365.3 5368.0 5615.8 22:53:06 INFO - PROCESS | 3446 | 22:53:06 INFO - PROCESS | 3446 | [#4] prototype.html Cycles:180 Average:4538.87 Median:5079.89 stddev:1314.54 (25.9%) stddev-sans-first:1279.57 22:53:06 INFO - PROCESS | 3446 | Values: 322.7 325.1 331.5 338.0 341.4 5294.4 5347.4 5378.8 5385.3 5477.2 5142.6 5177.5 5255.2 5263.8 5427.3 4078.3 4137.2 4167.3 4189.2 4192.5 4190.6 4211.8 4213.7 4236.5 4238.0 4104.9 4131.5 4167.2 4172.3 4184.1 4158.5 4188.3 4197.3 4197.6 4215.0 3706.6 3726.4 3726.7 3734.7 3736.5 5020.9 5174.8 5198.7 5228.9 5238.0 4737.8 4739.5 4757.0 4759.3 4821.4 5560.6 5569.3 5576.4 5583.9 5584.6 3764.2 3951.4 4048.5 4069.6 4072.8 5743.1 5775.3 5776.0 5776.9 5779.7 4036.3 4070.8 4086.9 4092.7 4092.9 3873.4 4149.9 4163.3 4176.8 4183.2 4643.6 4653.7 4656.0 4669.9 4684.6 5624.6 5631.4 5646.9 5655.8 5669.7 1389.4 1397.9 1404.7 1410.2 1414.5 901.6 923.1 929.0 930.2 934.0 4864.1 4898.3 4900.0 4943.9 4984.5 4809.8 5094.6 5099.2 5106.5 5131.6 4819.9 4829.5 4847.1 4849.3 4866.7 2733.5 2739.9 2750.7 2759.5 2761.5 5064.7 5117.3 5155.0 5208.2 5312.2 5155.2 5192.5 5207.2 5219.6 5270.7 5169.5 5200.0 5250.6 5252.5 5322.8 4916.7 5060.2 5072.2 5085.7 5124.7 5019.1 5043.0 5054.7 5074.1 5126.1 4979.8 5129.2 5192.2 5272.5 5282.7 3683.3 3701.0 3735.5 3744.0 3759.3 5102.4 5557.9 5646.5 5796.7 5808.7 5662.9 5688.4 5692.7 5709.2 5717.8 5559.9 5676.3 5694.5 5697.8 5740.0 5526.9 5543.4 5574.3 5577.2 5578.3 5603.1 5677.3 5717.3 5724.1 5729.6 5370.8 5533.2 5555.4 5564.3 5608.3 22:53:06 INFO - PROCESS | 3446 | 22:53:06 INFO - PROCESS | 3446 | [#5] yui.html Cycles:180 Average:21241.76 Median:4659.03 stddev:31004.83 (665.5%) stddev-sans-first:31053.55 22:53:06 INFO - PROCESS | 3446 | Values: 727.5 735.4 758.9 766.6 827.2 15582.9 16290.3 16301.3 16309.3 16360.9 15605.8 15638.6 15789.4 15842.9 15843.5 661.4 749.0 776.9 802.7 805.3 776.9 785.9 786.9 790.1 794.1 849.0 849.2 849.6 849.8 850.8 844.0 846.1 846.3 847.1 849.2 504.6 518.0 612.8 626.0 627.1 14980.0 15024.5 15029.2 15033.8 15037.5 13502.6 13758.9 14002.2 14130.2 14279.7 11060.1 11224.3 11813.4 13097.9 13753.7 4457.6 4506.8 4522.9 4545.3 4563.1 77067.0 78919.6 79348.0 79446.0 80154.2 10649.4 10995.5 11002.3 11010.6 11108.6 20337.4 20539.9 20545.4 20552.6 20566.5 8648.7 8676.6 8745.9 8938.4 9061.9 4786.6 4959.2 4982.1 5010.4 5035.8 3383.4 3446.9 3447.0 3454.5 3456.9 591.5 591.6 599.3 600.5 606.0 721.8 726.3 748.7 764.2 772.5 9815.3 9915.4 10042.8 10049.2 10191.4 673.2 689.2 745.4 746.7 758.7 4415.8 4754.9 4770.2 4799.4 4815.3 109866.0 117518.0 118715.4 119444.0 119546.4 100088.0 101803.0 102793.0 104918.0 106163.0 85717.6 87812.1 88165.6 89658.6 89959.6 9282.4 9353.3 9363.2 9427.8 9529.4 51936.7 52480.7 52883.0 53446.5 55440.2 77557.6 79222.1 80512.0 80655.2 80983.6 12234.8 12277.8 12320.7 12487.4 12812.7 13333.7 13565.0 13767.5 14266.0 14661.9 12793.2 13078.5 13099.7 13482.7 13537.7 12543.4 13045.8 13735.9 13744.0 13755.7 12625.7 13230.3 13525.8 13573.4 13670.5 9720.4 9726.0 9840.9 10036.2 10188.9 10892.9 11054.9 11067.8 11247.8 11342.5 22:53:06 INFO - PROCESS | 3446 | -------- Summary: end -------- 22:53:06 INFO - PROCESS | 3446 | 22:53:07 INFO - TEST-INFO | 3446: exit 0 22:53:07 INFO - TEST-OK | dromaeo_css | took 1114707ms 22:53:07 INFO - TEST-START | kraken 22:53:07 INFO - operating with platform_type : linux_ 22:53:07 INFO - Initialising browser for kraken test... 22:53:07 INFO - TEST-INFO | started process 3636 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpd1CG3D/profile http://localhost:60899/getInfo.html) 22:53:10 INFO - PROCESS | 3636 | JavaScript error: , line 0: uncaught exception: undefined 22:53:11 INFO - PROCESS | 3636 | __metrics Screen width/height:1600/1200 22:53:11 INFO - PROCESS | 3636 | colorDepth:24 22:53:11 INFO - PROCESS | 3636 | Browser inner width/height: 1024/697 22:53:11 INFO - PROCESS | 3636 | __metrics 22:53:12 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" 22:53:12 INFO - PROCESS | 3636 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 58: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:53:13 INFO - TEST-INFO | 3636: exit 0 22:53:13 INFO - Browser initialized. 22:53:13 INFO - Running cycle 1/1 for kraken test... 22:53:13 INFO - TEST-INFO | started process 3721 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpd1CG3D/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:53:24 INFO - PROCESS | 3721 | RSS: Main: 157827072 22:53:24 INFO - PROCESS | 3721 | 22:53:27 INFO - PROCESS | 3721 | Cycle 1(1): loaded http://localhost:60899/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:60899/tests/kraken/driver.html?testName=audio-beat-detection) 22:53:28 INFO - PROCESS | 3721 | RSS: Main: 214237184 22:53:28 INFO - PROCESS | 3721 | 22:53:30 INFO - PROCESS | 3721 | Cycle 1(1): loaded http://localhost:60899/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:60899/tests/kraken/driver.html?testName=audio-dft) 22:53:31 INFO - PROCESS | 3721 | RSS: Main: 192929792 22:53:31 INFO - PROCESS | 3721 | 22:53:34 INFO - PROCESS | 3721 | Cycle 1(1): loaded http://localhost:60899/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:60899/tests/kraken/driver.html?testName=audio-fft) 22:53:35 INFO - PROCESS | 3721 | RSS: Main: 270921728 22:53:35 INFO - PROCESS | 3721 | 22:53:37 INFO - PROCESS | 3721 | Cycle 1(1): loaded http://localhost:60899/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:60899/tests/kraken/driver.html?testName=audio-oscillator) 22:53:37 INFO - PROCESS | 3721 | RSS: Main: 181039104 22:53:37 INFO - PROCESS | 3721 | 22:53:40 INFO - PROCESS | 3721 | Cycle 1(1): loaded http://localhost:60899/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:60899/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:53:40 INFO - PROCESS | 3721 | RSS: Main: 185696256 22:53:40 INFO - PROCESS | 3721 | 22:53:43 INFO - PROCESS | 3721 | Cycle 1(1): loaded http://localhost:60899/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:60899/tests/kraken/driver.html?testName=imaging-darkroom) 22:53:44 INFO - PROCESS | 3721 | RSS: Main: 220594176 22:53:44 INFO - PROCESS | 3721 | 22:53:47 INFO - PROCESS | 3721 | Cycle 1(1): loaded http://localhost:60899/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:60899/tests/kraken/driver.html?testName=imaging-desaturate) 22:53:48 INFO - PROCESS | 3721 | RSS: Main: 221077504 22:53:48 INFO - PROCESS | 3721 | 22:53:51 INFO - PROCESS | 3721 | Cycle 1(1): loaded http://localhost:60899/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:60899/tests/kraken/driver.html?testName=json-parse-financial) 22:53:51 INFO - PROCESS | 3721 | RSS: Main: 221368320 22:53:51 INFO - PROCESS | 3721 | 22:53:53 INFO - PROCESS | 3721 | Cycle 1(1): loaded http://localhost:60899/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:60899/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:53:53 INFO - PROCESS | 3721 | RSS: Main: 185622528 22:53:53 INFO - PROCESS | 3721 | 22:53:55 INFO - PROCESS | 3721 | Cycle 1(1): loaded http://localhost:60899/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:60899/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:53:56 INFO - PROCESS | 3721 | RSS: Main: 190218240 22:53:56 INFO - PROCESS | 3721 | 22:53:58 INFO - PROCESS | 3721 | Cycle 1(1): loaded http://localhost:60899/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:60899/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:53:58 INFO - PROCESS | 3721 | RSS: Main: 195321856 22:53:58 INFO - PROCESS | 3721 | 22:54:01 INFO - PROCESS | 3721 | Cycle 1(1): loaded http://localhost:60899/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:60899/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:54:02 INFO - PROCESS | 3721 | RSS: Main: 188846080 22:54:02 INFO - PROCESS | 3721 | 22:54:04 INFO - PROCESS | 3721 | Cycle 1(1): loaded http://localhost:60899/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:60899/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:54:05 INFO - PROCESS | 3721 | RSS: Main: 193880064 22:54:05 INFO - PROCESS | 3721 | 22:54:06 INFO - PROCESS | 3721 | Cycle 1(1): loaded http://localhost:60899/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:60899/tests/kraken/driver.html?testName=ai-astar) 22:54:07 INFO - PROCESS | 3721 | RSS: Main: 245039104 22:54:07 INFO - PROCESS | 3721 | 22:54:07 INFO - PROCESS | 3721 | __start_tp_report 22:54:07 INFO - PROCESS | 3721 | _x_x_mozilla_page_load 22:54:07 INFO - PROCESS | 3721 | _x_x_mozilla_page_load_details 22:54:07 INFO - PROCESS | 3721 | |i|pagename|runs| 22:54:07 INFO - PROCESS | 3721 | |0;ai-astar;102;112;112;111;111;112;112;109;110;110 22:54:07 INFO - PROCESS | 3721 | |1;audio-beat-detection;145;118;120;188;121;121;146;124;160;119 22:54:07 INFO - PROCESS | 3721 | |2;audio-dft;163;161;162;164;161;162;161;162;161;163 22:54:07 INFO - PROCESS | 3721 | |3;audio-fft;76;83;75;109;77;78;74;74;105;75 22:54:07 INFO - PROCESS | 3721 | |4;audio-oscillator;85;84;118;84;84;84;127;85;84;133 22:54:07 INFO - PROCESS | 3721 | |5;imaging-gaussian-blur;117;117;117;117;117;116;117;117;117;117 22:54:07 INFO - PROCESS | 3721 | |6;imaging-darkroom;146;147;146;146;147;146;146;146;147;147 22:54:07 INFO - PROCESS | 3721 | |7;imaging-desaturate;96;95;95;95;94;95;95;95;95;95 22:54:07 INFO - PROCESS | 3721 | |8;json-parse-financial;53;53;52;51;53;54;53;53;53;53 22:54:07 INFO - PROCESS | 3721 | |9;json-stringify-tinderbox;64;64;63;63;65;63;64;64;65;70 22:54:07 INFO - PROCESS | 3721 | |10;stanford-crypto-aes;78;77;83;77;77;86;80;76;81;77 22:54:07 INFO - PROCESS | 3721 | |11;stanford-crypto-ccm;123;121;130;125;120;129;131;128;160;123 22:54:07 INFO - PROCESS | 3721 | |12;stanford-crypto-pbkdf2;160;155;156;156;158;158;156;155;154;169 22:54:07 INFO - PROCESS | 3721 | |13;stanford-crypto-sha256-iterative;64;63;63;62;63;64;64;62;62;66 22:54:07 INFO - PROCESS | 3721 | __end_tp_report 22:54:07 INFO - PROCESS | 3721 | __start_cc_report 22:54:07 INFO - PROCESS | 3721 | _x_x_mozilla_cycle_collect,1670 22:54:07 INFO - PROCESS | 3721 | __end_cc_report 22:54:07 INFO - PROCESS | 3721 | __startTimestamp1469598847239__endTimestamp 22:54:07 INFO - PROCESS | 3721 | 22:54:07 INFO - PROCESS | 3721 | ------- Summary: start ------- 22:54:07 INFO - PROCESS | 3721 | Number of tests: 14 22:54:07 INFO - PROCESS | 3721 | 22:54:07 INFO - PROCESS | 3721 | [#0] ai-astar Cycles:10 Average:110.10 Median:111.50 stddev:3.03 (2.7%) stddev-sans-first:1.12 22:54:07 INFO - PROCESS | 3721 | Values: 102.0 112.0 112.0 111.0 111.0 112.0 112.0 109.0 110.0 110.0 22:54:07 INFO - PROCESS | 3721 | 22:54:07 INFO - PROCESS | 3721 | [#1] audio-beat-detection Cycles:10 Average:136.20 Median:134.50 stddev:23.39 (17.4%) stddev-sans-first:24.59 22:54:07 INFO - PROCESS | 3721 | Values: 145.0 118.0 120.0 188.0 121.0 121.0 146.0 124.0 160.0 119.0 22:54:07 INFO - PROCESS | 3721 | 22:54:07 INFO - PROCESS | 3721 | [#2] audio-dft Cycles:10 Average:162.00 Median:162.00 stddev:1.05 (0.7%) stddev-sans-first:1.05 22:54:07 INFO - PROCESS | 3721 | Values: 163.0 161.0 162.0 164.0 161.0 162.0 161.0 162.0 161.0 163.0 22:54:07 INFO - PROCESS | 3721 | 22:54:07 INFO - PROCESS | 3721 | [#3] audio-fft Cycles:10 Average:82.60 Median:75.50 stddev:13.16 (17.4%) stddev-sans-first:13.74 22:54:07 INFO - PROCESS | 3721 | Values: 76.0 83.0 75.0 109.0 77.0 78.0 74.0 74.0 105.0 75.0 22:54:07 INFO - PROCESS | 3721 | 22:54:07 INFO - PROCESS | 3721 | [#4] audio-oscillator Cycles:10 Average:96.80 Median:84.00 stddev:20.47 (24.4%) stddev-sans-first:21.26 22:54:07 INFO - PROCESS | 3721 | Values: 85.0 84.0 118.0 84.0 84.0 84.0 127.0 85.0 84.0 133.0 22:54:07 INFO - PROCESS | 3721 | 22:54:07 INFO - PROCESS | 3721 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 22:54:07 INFO - PROCESS | 3721 | Values: 117.0 117.0 117.0 117.0 117.0 116.0 117.0 117.0 117.0 117.0 22:54:07 INFO - PROCESS | 3721 | 22:54:07 INFO - PROCESS | 3721 | [#6] imaging-darkroom Cycles:10 Average:146.40 Median:146.50 stddev:0.52 (0.4%) stddev-sans-first:0.53 22:54:07 INFO - PROCESS | 3721 | Values: 146.0 147.0 146.0 146.0 147.0 146.0 146.0 146.0 147.0 147.0 22:54:07 INFO - PROCESS | 3721 | 22:54:07 INFO - PROCESS | 3721 | [#7] imaging-desaturate Cycles:10 Average:95.00 Median:95.00 stddev:0.47 (0.5%) stddev-sans-first:0.33 22:54:07 INFO - PROCESS | 3721 | Values: 96.0 95.0 95.0 95.0 94.0 95.0 95.0 95.0 95.0 95.0 22:54:07 INFO - PROCESS | 3721 | 22:54:07 INFO - PROCESS | 3721 | [#8] json-parse-financial Cycles:10 Average:52.80 Median:53.00 stddev:0.79 (1.5%) stddev-sans-first:0.83 22:54:07 INFO - PROCESS | 3721 | Values: 53.0 53.0 52.0 51.0 53.0 54.0 53.0 53.0 53.0 53.0 22:54:07 INFO - PROCESS | 3721 | 22:54:07 INFO - PROCESS | 3721 | [#9] json-stringify-tinderbox Cycles:10 Average:64.50 Median:64.00 stddev:2.07 (3.2%) stddev-sans-first:2.19 22:54:07 INFO - PROCESS | 3721 | Values: 64.0 64.0 63.0 63.0 65.0 63.0 64.0 64.0 65.0 70.0 22:54:07 INFO - PROCESS | 3721 | 22:54:07 INFO - PROCESS | 3721 | [#10] stanford-crypto-aes Cycles:10 Average:79.20 Median:79.00 stddev:3.26 (4.1%) stddev-sans-first:3.43 22:54:07 INFO - PROCESS | 3721 | Values: 78.0 77.0 83.0 77.0 77.0 86.0 80.0 76.0 81.0 77.0 22:54:07 INFO - PROCESS | 3721 | 22:54:07 INFO - PROCESS | 3721 | [#11] stanford-crypto-ccm Cycles:10 Average:129.00 Median:128.50 stddev:11.55 (9.0%) stddev-sans-first:12.04 22:54:07 INFO - PROCESS | 3721 | Values: 123.0 121.0 130.0 125.0 120.0 129.0 131.0 128.0 160.0 123.0 22:54:07 INFO - PROCESS | 3721 | 22:54:07 INFO - PROCESS | 3721 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:157.70 Median:157.00 stddev:4.35 (2.8%) stddev-sans-first:4.53 22:54:07 INFO - PROCESS | 3721 | Values: 160.0 155.0 156.0 156.0 158.0 158.0 156.0 155.0 154.0 169.0 22:54:07 INFO - PROCESS | 3721 | 22:54:07 INFO - PROCESS | 3721 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.30 Median:63.50 stddev:1.25 (2.0%) stddev-sans-first:1.30 22:54:07 INFO - PROCESS | 3721 | Values: 64.0 63.0 63.0 62.0 63.0 64.0 64.0 62.0 62.0 66.0 22:54:07 INFO - PROCESS | 3721 | -------- Summary: end -------- 22:54:07 INFO - PROCESS | 3721 | 22:54:08 INFO - TEST-INFO | 3721: exit 0 22:54:08 INFO - TEST-OK | kraken | took 61130ms 22:54:08 INFO - SUITE-END | took 1175s 22:54:08 INFO - Completed test suite (00:19:35) 22:54:08 INFO - javascript benchmark 22:54:08 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3075.5540446635223, 3079.7382222511087, 3090.953635695465, 3122.2038380213044, 3148.0870547541213, 5292.550133238679, 5307.548858347041, 5366.195070739382, 5373.7581808818595, 5399.48704873037, 5334.546563542103, 5403.324584426947, 5403.83788486345, 5451.236826844245, 5458.808941687042, 4940.357753492041, 4966.5778408835295, 4978.751062446881, 5007.649464537484, 5048.495150484959, 4910.7299098549565, 5025.899482010358, 5079.91636601346, 5083.440821509631, 5129.9230511542355, 4837.516248375169, 4879.853604391874, 5019.272205530196, 5022.995400919831, 5031.597472202233, 4891.4863939286215, 4927.728974906378, 4940.851774446773, 4959.528844759742, 4984.576311013569, 4855.271709243606, 4883.949950760603, 4885.2916327132725, 4897.608191344684, 4942.654014219006, 5252.3171987641745, 5360.088784906537, 5372.91940620891, 5378.300820893259, 5399.4600539945695, 5787.276590426197, 5850.180974663551, 5889.7644094236175, 5899.498542623882, 5907.350191478934, 5878.470937615635, 5903.586748927577, 5903.940960590425, 5942.3760505146765, 5956.97021514894, 5283.630145889751, 5420.159875219348, 5533.778648854041, 5548.0845660465975, 5550.306211723535, 5915.1718759373725, 5915.349311575723, 5932.940670593325, 5985.760569577255, 5986.132010858401, 5699.230603868425, 5736.340320863154, 5754.856128596735, 5822.505087067647, 5822.883542329215, 5604.32748070226, 5666.801661941812, 5845.503132233723, 5905.4685078343155, 5908.143319218775, 5635.690037048002, 5657.2079908811975, 5680.176374425685, 5725.742341594639, 5734.713264336767, 5700.686462244616, 5702.144678298288, 5708.885822283614, 5713.857153571194, 5714.114312281603, 4290.914181716348, 4346.0, 4348.065165989327, 4351.325544540601, 4398.406215160912, 3909.8240579174008, 3966.464527288837, 3969.186316805048, 3976.423418604344, 4032.475778148822, 5540.9168862467095, 5545.168224766237, 5545.750441230154, 5546.584006199551, 5596.692181930033, 5699.715014249271, 5878.853028674232, 5883.441073098049, 5886.23478947734, 5915.556333274935, 5402.8919421611345, 5442.0, 5470.17947307907, 5537.1140617501005, 5543.334799824047, 4932.7286999215385, 4966.478519755374, 4991.9500804991485, 5005.524475174852, 5046.772895219724, 5889.204957330791, 5891.145783861316, 5907.881842363215, 5910.911336329958, 5966.850828729316, 5563.944360556343, 5648.971755141362, 5704.657720536781, 5717.028105222205, 5795.623284486495, 5924.496417804535, 5960.493358064613, 5969.373215812451, 5979.641221526722, 6003.6998150093195, 5734.283214598176, 5742.822721342031, 5746.224259724883, 5753.769849205984, 5755.3381361144, 5658.179563963285, 5777.104548794943, 5829.271341082364, 5849.766009359578, 5875.059990401515, 5704.087346024616, 5726.226959360433, 5729.570282228933, 5732.656040637575, 5734.741936612779, 5722.771089156554, 5724.112762521816, 5730.598858079894, 5732.283464566929, 5740.368559458373, 5387.81142660005, 5551.278333816578, 5568.248286481273, 5614.129809879475, 5702.800401985911, 5751.424857514216, 5752.108423194411, 5806.970965145148, 5811.331696854916, 5812.447817457335, 5611.3266408031295, 5626.156076588544, 5629.324481062299, 5636.239107720569, 5637.83086507389], "value": 5349.31303813741, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [598.2521847690386, 603.4388019142198, 622.6575383539054, 637.2225884421008, 648.4002297874465, 34299.65700342996, 44237.77881110594, 45523.0, 45567.0, 48531.514684853144, 45053.32420013702, 45099.77450112753, 45595.544044559545, 45691.31463028049, 45701.77149114258, 8076.35389168868, 8921.286297096216, 9055.95472022639, 9061.13919177677, 9076.727698169065, 10506.369617822917, 10797.40614266215, 10803.891961080406, 11122.388268645222, 11125.833112503318, 10941.56233750649, 11041.668749937515, 11043.889561104366, 11050.0, 11058.281211721212, 8099.433039687225, 10592.364458132537, 10755.731106722316, 10828.079613232885, 10841.0, 10232.079112879877, 10674.466276686213, 10687.412192329419, 10688.732781680443, 10696.679099626946, 42644.14711705749, 43051.784741076095, 43064.3540346895, 43071.784641076905, 43101.78449107734, 3481.495183198422, 3535.734819888519, 3576.6065732769375, 3663.322285377187, 3759.7180211484247, 33480.665193347995, 33605.159871003176, 33767.83116084428, 35614.46578301328, 35759.28481430383, 4383.232934236496, 4423.203823311803, 4480.439945006874, 4505.684602077856, 4659.743714095724, 361790.0, 362459.18770406244, 362933.8533073362, 363030.0, 363573.0, 57647.0, 57782.0, 58054.0, 58120.0, 58321.0, 61653.38346616566, 61707.0, 61722.69138654368, 61731.691341543, 61744.0, 15795.842041579437, 17497.287920372364, 17644.647107057786, 17817.287308507515, 17913.7312940306, 12064.0, 12717.236965782082, 12963.675908102374, 13417.194968301934, 13571.253581053137, 19599.0, 19629.822210667404, 19631.509212269808, 19641.508962276057, 19715.802841971396, 7054.2240353561065, 7109.28907109285, 7143.785686429415, 7155.320244576861, 7256.238094999949, 947.8483442649143, 1020.698893826323, 1026.1688032693542, 1064.723171975292, 1097.5993762276871, 32253.0, 32682.346353072804, 32753.0, 32791.836040819646, 33273.3345333092, 1069.8662667166604, 1092.639428988432, 1092.8032954068344, 1094.0919037199264, 1108.0, 16311.918440407959, 17393.0, 17776.51128841963, 18035.458936231935, 18179.0, 544229.0, 548621.0, 550853.0, 551152.0, 552355.0, 61670.69164654148, 62227.0, 62261.377386226464, 62268.68865655643, 62376.376236237964, 1217.0993464835958, 1360.299445785441, 1362.2371471975725, 1362.6797702539895, 1377.8966577506726, 29584.704152958195, 32955.505667415484, 33206.335873282886, 33496.68808207541, 33636.831815840764, 50066.24900626421, 50436.0, 50608.493915060375, 50650.746746266035, 50735.0, 449153.0, 450905.0, 451071.0, 454273.0, 456204.0, 24868.37829054288, 27280.181594552196, 27824.721752782214, 28051.438971220054, 28109.437811243253, 46141.53858461372, 46253.76873115613, 47204.0, 47428.52571474241, 48165.27752083792, 40182.79908600439, 40507.1898562033, 40910.1817963645, 41081.589184107776, 42228.78885605675, 39696.60303396929, 39858.402123967586, 40831.795841020605, 40944.0, 42003.36994945017, 38640.0, 39451.40822887716, 39549.60450395574, 40307.0, 40391.39412908867, 34702.82648586741, 36379.27241455209, 36690.0, 37083.25833483369, 37487.0, 27463.0, 27483.725162748116, 27502.587461187697, 27803.16590502288, 27818.860905695343], "value": 20754.346292444563, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [382.64414094891737, 387.6317498376541, 404.14119995010594, 404.22590738735323, 413.0582272418886, 14534.781978270334, 17086.914565427185, 18772.0, 19755.703664445027, 20081.297154599593, 15552.0, 18443.72334414985, 18932.905335473337, 19144.712829307573, 19782.802171978277, 5822.505087067594, 6104.328523862371, 6116.694165291718, 6118.908216376758, 6138.47822935051, 6051.818445446622, 6108.389161083878, 6125.908111378333, 6127.724252408652, 6146.539009574278, 5807.128930660392, 5925.466707996279, 5970.79102231422, 5971.0744834550715, 5977.133315669225, 5927.436893495111, 5952.255968003999, 5999.250093738305, 6002.099685047256, 6077.0, 5218.660787048868, 5311.548518375943, 5333.866653333659, 5342.358916929955, 5394.6493477924205, 23545.0, 23660.881695591583, 23946.521069578685, 24240.7575924242, 24273.878630606734, 2315.6758053872472, 2380.155044959049, 2560.8591527465983, 2621.279148234232, 2637.6478809963523, 15218.543443696595, 16080.71354291654, 16241.350345986146, 16566.751498727528, 17008.574785630335, 2053.486628342914, 2111.218849025855, 2116.333354993172, 2177.2379667116384, 2206.6248737714627, 92908.0, 98546.63806938329, 100132.0, 100172.49913750385, 101084.0, 17587.0, 17635.470935871945, 17862.5534361642, 18063.096845157954, 18716.66041744782, 32119.0, 32187.51718724221, 32243.51634725481, 32263.35473290521, 32284.0, 9785.412875227508, 9993.450360230258, 10082.29423940317, 10131.797364052825, 10292.330998485075, 8455.619497122645, 8557.358198135165, 8592.183742544448, 8796.648134074694, 8811.779705507286, 7336.796657659369, 8223.21879421454, 8678.39251252419, 8828.0, 9013.909860901438, 3198.6321573018936, 3230.8707651693667, 3253.4143854106014, 3388.610669625491, 3468.965310346864, 915.4232833314969, 932.8181004704018, 1000.1598657127902, 1003.5634498992962, 1066.4667666166918, 19198.904005480163, 19244.615107698046, 19286.132124054453, 19407.611847762964, 19904.203831846855, 919.0946917286467, 929.3680297397699, 931.8928323242778, 950.078423928788, 1051.295631926611, 10595.788084238273, 10875.728106797393, 10888.020078193, 10953.671389858317, 10957.56169753201, 425351.0, 438396.0, 490193.0, 494680.0, 497558.0, 89569.0, 91942.54028729947, 92426.0, 92874.0, 102186.0, 1009.914157296638, 1045.2056437107854, 1062.054771253585, 1070.8126077936486, 1080.0225795654944, 17026.574335641708, 17456.30197699338, 17737.467875963743, 17971.73042404391, 17992.10039498046, 44521.77739111284, 44674.55325446834, 45184.54815451804, 45353.092938141715, 45845.31232031455, 56106.43893561175, 57834.7108264456, 61798.38201617926, 62839.6858015707, 63168.68415657892, 8049.195080492138, 8154.551499667576, 8210.0, 8549.14508549165, 8573.18554737299, 12760.553380631633, 13365.86634133685, 13543.97992895622, 13618.250996195304, 13813.723725525233, 11481.885181148082, 11669.35818529989, 11721.413929303671, 11986.940065299617, 12022.939885300517, 11289.322640641587, 11373.20387572862, 11387.601433949772, 11790.587329443428, 11841.111916606116, 11650.359230242419, 11983.64049078529, 12021.459034343301, 12068.577599783965, 12463.439145238668, 8255.298299644597, 8302.501849889026, 8308.501489910625, 8479.830403391774, 8543.957280213559, 8742.825143496968, 11066.28069175501, 11205.439728013404, 11217.270877392943, 11839.1712580122], "value": 10185.940459060143, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1235.5860786636476, 1477.0546850015992, 1533.1414407931557, 1667.533090734594, 1805.2147315917578, 8623.956880215597, 8839.734807955763, 8901.4214076085, 9072.77318067047, 9081.545922703855, 8917.643294268239, 8976.73069807905, 8983.0, 8985.685501007467, 9005.50469724165, 2352.9529409411803, 2353.6587194856734, 2381.535600557892, 2383.7854593086618, 2384.177458776719, 2301.4591570980806, 2333.7782910623464, 2372.3713215997773, 2390.020091762378, 2393.4136136646457, 2355.528894221154, 2361.681173041635, 2372.4306166519996, 2384.8092152627837, 2393.0308225168833, 2378.5242951409787, 2393.4255778613096, 2393.5930891748444, 2399.1003373734848, 2422.2491027781357, 2292.9197478088186, 2343.7539058398966, 2357.799587035087, 2362.0197617988515, 2364.0307473935777, 8762.518061506615, 9000.909990900072, 9031.142041506046, 9073.909260907372, 9081.500517471537, 5389.78440862365, 5560.944390556083, 5570.554355651579, 5586.16207568868, 5592.273004509388, 3442.277121804424, 3458.6887180153653, 3501.474778783203, 3507.298540291926, 3512.1395258161915, 1091.683411810574, 1100.9614663486739, 1148.632437619962, 1163.5055101581793, 1172.3962159487871, 12613.684657883534, 12663.556775512905, 12698.0, 12747.171433856769, 12779.361031948365, 4404.889877753018, 4476.888077798081, 4606.792694328697, 4624.0, 4688.9531104689195, 9990.15083717892, 10008.349457285252, 10012.24908131893, 10014.098731114234, 10022.699319020441, 5342.8664283392445, 5374.758135883894, 5382.67703937765, 5386.892262154735, 5387.164989426645, 4413.713108647928, 4854.126257273654, 4930.457649658606, 4971.1797553403285, 4997.9750101249265, 3935.114599215211, 3987.9202415951518, 4079.9796001019804, 4082.4284600156, 4104.117614712851, 1173.9647810565696, 1215.076541828217, 1220.9633710988685, 1223.1621339382552, 1237.2329155923385, 847.9109693482095, 958.3531116496476, 972.397113789455, 976.8534719792088, 996.6645489199486, 5792.739326730307, 5903.2325797646035, 5915.497182739429, 5998.220231369981, 6008.489278411296, 983.9065288797552, 1023.6417253961054, 1028.2545154762738, 1063.9255252132432, 1073.983890241647, 3988.6210809973004, 4032.94971777328, 4035.1526179502634, 4098.467199264136, 4158.91682166355, 11128.888711112948, 11366.261193022427, 11454.828177577343, 11496.655100347003, 11508.53965841357, 10698.946505267424, 10786.137109031259, 10954.452277386099, 11362.147838912115, 11425.37160456183, 8055.275025247756, 8289.04675962272, 8301.128381519975, 8395.95802020986, 8457.703980360659, 6110.633361998294, 6133.84665383369, 6176.289726681489, 6310.716017779303, 6408.423241908251, 10566.841497377392, 10569.207309451667, 10577.206709496972, 10585.52365143555, 10616.628418005334, 10255.692329230134, 10639.521221545205, 10652.09457196116, 10731.839022414826, 10821.458927053458, 7233.348998590152, 7928.682852685828, 8089.312408445348, 8135.308498777434, 8328.958355208186, 8825.073367296598, 8984.416012959136, 9002.96465906403, 9003.459792412476, 9007.729768106967, 7662.108975541949, 7912.960435197787, 7934.285914267512, 8033.35733141359, 8221.0, 7778.533288002738, 7899.447038707235, 7947.324477419253, 7976.800579985546, 7977.361811054986, 6907.861842763217, 6997.230304666381, 7008.544444611288, 7009.579425234502, 7011.123609548807, 5065.620078494201, 5091.796328146833, 5226.895462090661, 5250.369955605352, 5489.039418101896, 5326.174442961347, 5329.893402131858, 5365.302510673743, 5368.033753924331, 5615.775368985195], "value": 4735.217210509381, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [322.7208464678053, 325.123791382225, 331.5457822782788, 337.99615143025204, 341.36846833358277, 5294.41761406245, 5347.385050719168, 5378.757955891984, 5385.272988146599, 5477.219903531358, 5142.562882155012, 5177.482251774821, 5255.185446255837, 5263.815766448175, 5427.309429587888, 4078.2659121358142, 4137.172565486907, 4167.3332266837315, 4189.2040512302565, 4192.454980852485, 4190.559991200926, 4211.810468528924, 4213.71481698082, 4236.512801027875, 4238.046439551107, 4104.8563300284495, 4131.5455299916985, 4167.208230436223, 4172.33242681171, 4184.079502509443, 4158.459400277975, 4188.32986722126, 4197.286461301586, 4197.643200327961, 4214.957850421502, 3706.610805865372, 3726.3851464508325, 3726.6832319252894, 3734.719896007783, 3736.532933383327, 5020.899582008376, 5174.818881339135, 5198.662086964373, 5228.895422091537, 5238.0, 4737.786799593992, 4739.502352252998, 4756.952430475721, 4759.286107083965, 4821.397325334333, 5560.610757246995, 5569.303837020372, 5576.414476479992, 5583.8604034899045, 5584.6090773645865, 3764.2471505698695, 3951.38753493209, 4048.5020542399207, 4069.5930406959365, 4072.8188825240645, 5743.128435782067, 5775.335836378787, 5776.0, 5776.884462310731, 5779.739911703941, 4036.253293140778, 4070.776107314126, 4086.8773936781945, 4092.652124569445, 4092.9181416371507, 3873.4189871518934, 4149.8962525937095, 4163.333866581333, 4176.812043458051, 4183.184279065553, 4643.605293550018, 4653.697509661861, 4656.0, 4669.906601867943, 4684.648651351163, 5624.565735737361, 5631.35326396593, 5646.887062258732, 5655.830325090254, 5669.744861481161, 1389.3817251323267, 1397.9301034948212, 1404.7401230772339, 1410.1750475971671, 1414.5261337452068, 901.5942825728448, 923.1045885491063, 929.0198840223494, 930.1907340613574, 933.9766505837408, 4864.148773964542, 4898.289747986593, 4899.951000490021, 4943.9258411123865, 4984.476629953875, 4809.807607695723, 5094.5669618082875, 5099.235114732745, 5106.489351064938, 5131.61512886535, 4819.927701084486, 4829.468758436569, 4847.127517046965, 4849.2968519565175, 4866.732329721828, 2733.5079685656374, 2739.917732495653, 2750.7386798254133, 2759.530879750447, 2761.467385601011, 5064.746762661852, 5117.334746482933, 5155.0, 5208.244804503326, 5312.2297266896085, 5155.200943853709, 5192.45479224676, 5207.218917162456, 5219.634625576173, 5270.683758974474, 5169.508896654812, 5200.00999425332, 5250.613354386475, 5252.501012403815, 5322.840314790563, 4916.680415772963, 5060.216880715665, 5072.188449848007, 5085.669431486942, 5124.692518448905, 5019.0965626187635, 5043.0, 5054.671446355975, 5074.112030394593, 5126.0521002018995, 4979.800807967786, 5129.230615407719, 5192.22216114377, 5272.47098341492, 5282.735863206747, 3683.3369993401443, 3701.021401992815, 3735.477033215406, 3743.9782517441367, 3759.3421151297866, 5102.387713474407, 5557.944420555743, 5646.520045796154, 5796.710299431785, 5808.680522571299, 5662.915056274077, 5688.3742788294085, 5692.658440493584, 5709.22925405065, 5717.7712891485535, 5559.9444005561045, 5676.347220069745, 5694.487496125369, 5697.772089116389, 5740.0, 5526.91709624364, 5543.417941116286, 5574.303212098488, 5577.211394302849, 5578.2748242728185, 5603.142397377285, 5677.290338707661, 5717.256756621613, 5724.0555308375315, 5729.627574207663, 5370.758315875717, 5533.225348451139, 5555.416681248573, 5564.276644036249, 5608.270924779753], "value": 4122.161251568952, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [727.4616783580152, 735.3675838778653, 758.9089309282887, 766.6281853301148, 827.1934863508077, 15582.922085389571, 16290.26693798779, 16301.266443010049, 16309.347626094972, 16360.918195409022, 15605.765913511306, 15638.609034774136, 15789.447369342075, 15842.920785396056, 15843.524694259162, 661.4278648968632, 749.0412272291476, 776.8873513340562, 802.7391097893179, 805.3154818404339, 776.9456138070337, 785.8781888807245, 786.850498405304, 790.083503136359, 794.1026639896937, 849.0278630967539, 849.180540778149, 849.587949844325, 849.7578190215789, 850.8170743290192, 844.0420123160196, 846.0989046665312, 846.3398549131687, 847.089378917666, 849.1975083546051, 504.6316189181881, 518.049379388822, 612.8039027511198, 625.9937400625981, 627.0750642801877, 14980.0, 15024.549263522113, 15029.173395463245, 15033.849661503355, 15037.548873533811, 13502.594922152253, 13758.93120534401, 14002.229877356742, 14130.22283774392, 14279.714405711931, 11060.115190784718, 11224.326540407601, 11813.350265735382, 13097.934510327483, 13753.724925501534, 4457.621102206284, 4506.842260520866, 4522.86431407055, 4545.295479200696, 4563.133004729124, 77066.99999999943, 78919.6054019732, 79348.0, 79446.0, 80154.19845801525, 10649.361038337569, 10995.450227488594, 11002.284851484628, 11010.559577616805, 11108.55565777376, 20337.389878303675, 20539.897300513403, 20545.383638490868, 20552.588948220953, 20566.485837853874, 8648.740537783877, 8676.60955257015, 8745.868811967826, 8938.4190027648, 9061.909380906238, 4786.569208771228, 4959.20652695567, 4982.053409852117, 5010.423801262828, 5035.823746168867, 3383.3909896218424, 3446.9310613787584, 3446.9827650861585, 3454.4990976308295, 3456.948145777815, 591.5326891755465, 591.5504216795266, 599.2958274028, 600.5255847880126, 606.0212756398384, 721.7546034348346, 726.3317747672154, 748.6631016042802, 764.2357642357642, 772.5326177662546, 9815.312928095107, 9915.355501892354, 10042.799144017225, 10049.246306526893, 10191.439470829175, 673.2123415603755, 689.1867596236489, 745.4468445573888, 746.7484298501644, 758.6623952341155, 4415.8012889419415, 4754.9286760698615, 4770.236762118114, 4799.3760811095035, 4815.3258543803895, 109866.0, 117518.0, 118715.40642296906, 119444.0, 119546.4022679881, 100088.0, 101803.0, 102793.0, 104918.0, 106163.0, 85717.57141214254, 87812.12187878168, 88165.55917220374, 89658.55170724104, 89959.55020224857, 9282.396644218104, 9353.345265831598, 9363.204127648954, 9427.764305892133, 9529.428234305964, 51936.74031629818, 52480.737596311774, 52883.0, 53446.46553534415, 55440.168397474874, 77557.61221193858, 79222.05834562315, 80512.0, 80655.19344806712, 80983.59508202657, 12234.816477753018, 12277.815832762695, 12320.691982700146, 12487.375631218585, 12812.743745124859, 13333.66665833362, 13565.0, 13767.518136865563, 14266.0, 14661.92669036648, 12793.232406055666, 13078.476860925457, 13099.738005239651, 13482.662933426744, 13537.661558460723, 12543.435545400296, 13045.804312935503, 13735.931320343334, 13744.0, 13755.724885502434, 12625.684357891127, 13230.272335021666, 13525.823827630129, 13573.405825551235, 13670.453181873012, 9720.369509934668, 9726.0, 9840.852387214054, 10036.247281453774, 10188.898111018794, 10892.945535272589, 11054.944725276644, 11067.833982490109, 11247.775044499227, 11342.546298147981], "value": 7564.513471947658, "unit": "score"}], "name": "dromaeo_css", "value": 7420.793741976801, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 112.0, 112.0, 111.0, 111.0, 112.0, 112.0, 109.0, 110.0, 110.0], "value": 110.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 118.0, 120.0, 188.0, 121.0, 121.0, 146.0, 124.0, 160.0, 119.0], "value": 136.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 161.0, 162.0, 164.0, 161.0, 162.0, 161.0, 162.0, 161.0, 163.0], "value": 162.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 83.0, 75.0, 109.0, 77.0, 78.0, 74.0, 74.0, 105.0, 75.0], "value": 82.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 118.0, 84.0, 84.0, 84.0, 127.0, 85.0, 84.0, 133.0], "value": 96.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 116.0, 117.0, 117.0, 117.0, 117.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 146.0, 146.0, 147.0, 146.0, 146.0, 146.0, 147.0, 147.0], "value": 146.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 95.0, 95.0, 94.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 95.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 53.0, 52.0, 51.0, 53.0, 54.0, 53.0, 53.0, 53.0, 53.0], "value": 52.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 63.0, 63.0, 65.0, 63.0, 64.0, 64.0, 65.0, 70.0], "value": 64.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 77.0, 83.0, 77.0, 77.0, 86.0, 80.0, 76.0, 81.0, 77.0], "value": 79.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [123.0, 121.0, 130.0, 125.0, 120.0, 129.0, 131.0, 128.0, 160.0, 123.0], "value": 129.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 155.0, 156.0, 156.0, 158.0, 158.0, 156.0, 155.0, 154.0, 169.0], "value": 157.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 63.0, 63.0, 62.0, 63.0, 64.0, 64.0, 62.0, 62.0, 66.0], "value": 63.3, "unit": "score"}], "name": "kraken", "value": 1492.4999999999998, "alertThreshold": 2.0}]} 22:54:08 INFO - Return code: 0 22:54:08 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:54:08 INFO - # TBPL SUCCESS # 22:54:08 INFO - Running post-action listener: _resource_record_post_action 22:54:08 INFO - [mozharness: 2016-07-27 05:54:08.530165Z] Finished run-tests step (success) 22:54:08 INFO - Running post-run listener: _resource_record_post_run 22:54:08 INFO - Total resource usage - Wall time: 1193s; CPU: 13.0%; Read bytes: 12288; Write bytes: 574705664; Read time: 308; Write time: 616996 22:54:08 INFO - TinderboxPrint: CPU usage
13.1% 22:54:08 INFO - TinderboxPrint: I/O read bytes / time
12,288 / 308 22:54:08 INFO - TinderboxPrint: I/O write bytes / time
574,705,664 / 616,996 22:54:08 INFO - TinderboxPrint: CPU idle
8,267.4 (86.8%) 22:54:08 INFO - TinderboxPrint: CPU user
1,202.7 (12.6%) 22:54:08 INFO - TinderboxPrint: Swap in / out
0 / 0 22:54:08 INFO - install - Wall time: 18s; CPU: 14.0%; Read bytes: 4096; Write bytes: 278204416; Read time: 112; Write time: 376200 22:54:08 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 4096; Write bytes: 296501248; Read time: 180; Write time: 240796 22:54:09 INFO - Running post-run listener: _upload_blobber_files 22:54:09 INFO - Blob upload gear active. 22:54:09 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:54:09 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 22:54:09 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', 'Mozilla-Inbound', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 22:54:09 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 Mozilla-Inbound -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 22:54:09 INFO - (blobuploader) - INFO - Open directory for files ... 22:54:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:54:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:54:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:54:20 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:54:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:54:20 INFO - (blobuploader) - INFO - Done attempting. 22:54:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 22:54:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:54:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:54:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 22:54:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:54:21 INFO - (blobuploader) - INFO - Done attempting. 22:54:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 22:54:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:54:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:54:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 22:54:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:54:22 INFO - (blobuploader) - INFO - Done attempting. 22:54:22 INFO - (blobuploader) - INFO - Iteration through files over. 22:54:22 INFO - Return code: 0 22:54:22 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:54:22 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:54:22 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/8559d0f65e1d3b5ffb18d026daf0024d022388afa2eb6c45b353be002a2c2ef69b13e5c93595991c1fba5ef20455e1a0de27c3e8254122a4bff11e33fbdd8e8d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/98a74f6a9841c6b217bfcec77ce29bee0ef24beb6537c1271c0c0bec465d432b648641e1e925c35cf72aae154989205bd4b1742c2372a26b3467ae73a1c88b60", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/833b14a5750924d9eda6b8be3ac8e992fdd245fe23466fffe21d5e506b16150b76dad196fc4afaf029a7066d96e60ae4d1c461da97889f63e3385dcf245f85a4"} 22:54:22 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:54:22 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:54:22 INFO - Contents: 22:54:22 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/8559d0f65e1d3b5ffb18d026daf0024d022388afa2eb6c45b353be002a2c2ef69b13e5c93595991c1fba5ef20455e1a0de27c3e8254122a4bff11e33fbdd8e8d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/98a74f6a9841c6b217bfcec77ce29bee0ef24beb6537c1271c0c0bec465d432b648641e1e925c35cf72aae154989205bd4b1742c2372a26b3467ae73a1c88b60", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/833b14a5750924d9eda6b8be3ac8e992fdd245fe23466fffe21d5e506b16150b76dad196fc4afaf029a7066d96e60ae4d1c461da97889f63e3385dcf245f85a4"} 22:54:22 INFO - Running post-run listener: copy_logs_to_upload_dir 22:54:22 INFO - Copying logs to upload dir... 22:54:22 INFO - mkdir: /builds/slave/test/build/upload/logs 22:54:22 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1238.734990 ========= master_lag: 0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2016-07-26 22:54:21.026022) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-26 22:54:21.031910) ========= 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=52faae308a556c440401f8e5000001e8-1469597620.725820-1875803709 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/8559d0f65e1d3b5ffb18d026daf0024d022388afa2eb6c45b353be002a2c2ef69b13e5c93595991c1fba5ef20455e1a0de27c3e8254122a4bff11e33fbdd8e8d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/98a74f6a9841c6b217bfcec77ce29bee0ef24beb6537c1271c0c0bec465d432b648641e1e925c35cf72aae154989205bd4b1742c2372a26b3467ae73a1c88b60", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/833b14a5750924d9eda6b8be3ac8e992fdd245fe23466fffe21d5e506b16150b76dad196fc4afaf029a7066d96e60ae4d1c461da97889f63e3385dcf245f85a4"} build_url:https://queue.taskcluster.net/v1/task/RiB9WYIKQUiOH4wrZDkgwQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011192 build_url: 'https://queue.taskcluster.net/v1/task/RiB9WYIKQUiOH4wrZDkgwQ/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/Mozilla-Inbound/sha512/8559d0f65e1d3b5ffb18d026daf0024d022388afa2eb6c45b353be002a2c2ef69b13e5c93595991c1fba5ef20455e1a0de27c3e8254122a4bff11e33fbdd8e8d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/98a74f6a9841c6b217bfcec77ce29bee0ef24beb6537c1271c0c0bec465d432b648641e1e925c35cf72aae154989205bd4b1742c2372a26b3467ae73a1c88b60", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/833b14a5750924d9eda6b8be3ac8e992fdd245fe23466fffe21d5e506b16150b76dad196fc4afaf029a7066d96e60ae4d1c461da97889f63e3385dcf245f85a4"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-26 22:54:21.059284) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-26 22:54:21.059619) ========= 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=52faae308a556c440401f8e5000001e8-1469597620.725820-1875803709 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004510 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-26 22:54:21.142984) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-26 22:54:21.143312) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-26 22:54:21.143601) ========= ========= Total master_lag: 0.15 =========