builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-061 starttime: 1469595794.65 results: success (0) buildid: 20160726192234 builduid: cfac72652e0f4f99b7d123a1986f466d revision: 250943418f3a43c46de84797ad58a22f724caf58 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-26 22:03:14.655167) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-26 22:03:14.656083) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-26 22:03:14.656495) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-26 22:03:14.704663) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-26 22:03:14.705042) ========= 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=516f8d7ca085b4d087ab0a71000001a4-1469594776.346728-895458149 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-26 22:03:14-- 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% 20.4M=0.001s 2016-07-26 22:03:14 (20.4 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.137838 ========= master_lag: 0.06 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-26 22:03:14.901181) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-26 22:03:14.901511) ========= 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=516f8d7ca085b4d087ab0a71000001a4-1469594776.346728-895458149 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.046979 ========= master_lag: 0.01 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-26 22:03:14.962055) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-26 22:03:14.962525) ========= 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=516f8d7ca085b4d087ab0a71000001a4-1469594776.346728-895458149 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-26 22:03:15,051 truncating revision to first 12 chars 2016-07-26 22:03:15,051 Setting DEBUG logging. 2016-07-26 22:03:15,051 attempt 1/10 2016-07-26 22:03:15,051 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:03:15,357 unpacking tar archive at: mozilla-inbound-250943418f3a/testing/mozharness/ program finished with exit code 0 elapsedTime=0.713952 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-26 22:03:15.701457) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-26 22:03:15.701780) ========= script_repo_revision: 250943418f3a43c46de84797ad58a22f724caf58 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-26 22:03:15.702229) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-26 22:03:15.702547) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-26 22:03:15.739891) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 51 secs) (at 2016-07-26 22:03:15.740291) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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=516f8d7ca085b4d087ab0a71000001a4-1469594776.346728-895458149 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 22:03:16 INFO - MultiFileLogger online at 20160726 22:03:16 in /builds/slave/test 22:03:16 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --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:03:16 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:03:16 INFO - {'append_to_log': False, 22:03:16 INFO - 'base_work_dir': '/builds/slave/test', 22:03:16 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 22:03:16 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:03:16 INFO - 'branch': 'Mozilla-Inbound', 22:03:16 INFO - 'buildbot_json_path': 'buildprops.json', 22:03:16 INFO - 'config_files': ('talos/linux_config.py',), 22:03:16 INFO - 'default_actions': ('clobber', 22:03:16 INFO - 'read-buildbot-config', 22:03:16 INFO - 'download-and-extract', 22:03:16 INFO - 'populate-webroot', 22:03:16 INFO - 'create-virtualenv', 22:03:16 INFO - 'install', 22:03:16 INFO - 'run-tests'), 22:03:16 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:03:16 INFO - 'download_minidump_stackwalk': True, 22:03:16 INFO - 'download_symbols': 'ondemand', 22:03:16 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:03:16 INFO - 'tooltool.py': '/tools/tooltool.py', 22:03:16 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:03:16 INFO - '/tools/misc-python/virtualenv.py')}, 22:03:16 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:03:16 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:03:16 INFO - 'installer_path': 'installer.exe', 22:03:16 INFO - 'log_level': 'info', 22:03:16 INFO - 'log_name': 'talos', 22:03:16 INFO - 'log_to_console': True, 22:03:16 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 22:03:16 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 22:03:16 INFO - 'opt_config_files': (), 22:03:16 INFO - 'pip_index': False, 22:03:16 INFO - 'sps_profile': False, 22:03:16 INFO - 'sps_profile_interval': 0, 22:03:16 INFO - 'suite': 'dromaeojs-e10s', 22:03:16 INFO - 'system_bits': '32', 22:03:16 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:03:16 INFO - 'title': 'talos-linux64-ix-061', 22:03:16 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:03:16 INFO - 'use_talos_json': True, 22:03:16 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:03:16 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:03:16 INFO - 'work_dir': 'build'} 22:03:16 INFO - [mozharness: 2016-07-27 05:03:16.026389Z] Running clobber step. 22:03:16 INFO - Running pre-action listener: _resource_record_pre_action 22:03:16 INFO - Running main action method: clobber 22:03:16 INFO - rmtree: /builds/slave/test/build 22:03:16 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:03:19 INFO - Running post-action listener: _resource_record_post_action 22:03:19 INFO - [mozharness: 2016-07-27 05:03:19.538114Z] Finished clobber step (success) 22:03:19 INFO - [mozharness: 2016-07-27 05:03:19.538399Z] Running read-buildbot-config step. 22:03:19 INFO - Running pre-action listener: _resource_record_pre_action 22:03:19 INFO - Running main action method: read_buildbot_config 22:03:19 INFO - Using buildbot properties: 22:03:19 INFO - { 22:03:19 INFO - "project": "", 22:03:19 INFO - "product": "firefox", 22:03:19 INFO - "script_repo_revision": "production", 22:03:19 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs-e10s", 22:03:19 INFO - "repository": "", 22:03:19 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs-e10s", 22:03:19 INFO - "buildid": "20160726192234", 22:03:19 INFO - "pgo_build": "True", 22:03:19 INFO - "basedir": "/builds/slave/test", 22:03:19 INFO - "buildnumber": 1082, 22:03:19 INFO - "slavename": "talos-linux64-ix-061", 22:03:19 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 22:03:19 INFO - "platform": "ubuntu64_hw", 22:03:19 INFO - "scheduler": "tests-mozilla-inbound-linux64-pgo-talos", 22:03:19 INFO - "branch": "mozilla-inbound", 22:03:19 INFO - "repo_path": "integration/mozilla-inbound", 22:03:19 INFO - "revision": "250943418f3a43c46de84797ad58a22f724caf58", 22:03:19 INFO - "stage_platform": "linux64-pgo", 22:03:19 INFO - "builduid": "cfac72652e0f4f99b7d123a1986f466d", 22:03:19 INFO - "slavebuilddir": "test" 22:03:19 INFO - } 22:03:19 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:03:19 INFO - Running post-action listener: _resource_record_post_action 22:03:19 INFO - [mozharness: 2016-07-27 05:03:19.543831Z] Finished read-buildbot-config step (success) 22:03:19 INFO - [mozharness: 2016-07-27 05:03:19.543983Z] Running download-and-extract step. 22:03:19 INFO - Running pre-action listener: _resource_record_pre_action 22:03:19 INFO - Running main action method: download_and_extract 22:03:19 INFO - mkdir: /builds/slave/test/build/tests 22:03:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:03:19 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:03:19 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:03:19 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:03:19 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:03:19 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:03:25 INFO - Downloaded 1361 bytes. 22:03:25 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 22:03:25 INFO - Using the following test package requirements: 22:03:25 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 22:03:25 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:03:25 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 22:03:25 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:03:25 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 22:03:25 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:03:25 INFO - u'jsshell-linux-x86_64.zip'], 22:03:25 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:03:25 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 22:03:25 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 22:03:25 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:03:25 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 22:03:25 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:03:25 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 22:03:25 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:03:25 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 22:03:25 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:03:25 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 22:03:25 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 22:03:25 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:03:25 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:03:25 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:03:25 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:03:25 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:03:25 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:03:34 INFO - Downloaded 24348929 bytes. 22:03:34 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:03:34 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:03:35 INFO - Return code: 0 22:03:35 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:03:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:03:35 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:03:35 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:03:35 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:03:35 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:03:35 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:03:38 INFO - Downloaded 24348929 bytes. 22:03:38 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:03:38 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 22:03:39 INFO - Return code: 0 22:03:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:03:39 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:03:39 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:03:39 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:03:39 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:03:39 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:03:40 INFO - Downloaded 14403317 bytes. 22:03:40 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:03:40 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:03:40 INFO - Return code: 0 22:03:40 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:03:40 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:03:40 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:03:40 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:03:40 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:03:40 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:03:42 INFO - Downloaded 60875576 bytes. 22:03:42 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:03:42 INFO - mkdir: /builds/slave/test/properties 22:03:42 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:03:42 INFO - Writing to file /builds/slave/test/properties/build_url 22:03:42 INFO - Contents: 22:03:42 INFO - build_url:https://queue.taskcluster.net/v1/task/RiB9WYIKQUiOH4wrZDkgwQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 22:03:42 INFO - Running post-action listener: _resource_record_post_action 22:03:42 INFO - Running post-action listener: set_extra_try_arguments 22:03:42 INFO - [mozharness: 2016-07-27 05:03:42.669102Z] Finished download-and-extract step (success) 22:03:42 INFO - [mozharness: 2016-07-27 05:03:42.669336Z] Running populate-webroot step. 22:03:42 INFO - Running pre-action listener: _resource_record_pre_action 22:03:42 INFO - Running main action method: populate_webroot 22:03:42 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:03:42 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:03:42 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:03:42 INFO - '--tppagecycles', 22:03:42 INFO - '7'], 22:03:42 INFO - 'tests': ['tsvgm']}}, 22:03:42 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:03:42 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:03:42 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:03:42 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:03:42 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:03:42 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:03:42 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:03:42 INFO - 'talos_options': ['--disable-e10s'], 22:03:42 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:03:42 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:03:42 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:03:42 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:03:42 INFO - 'talos_options': ['--disable-e10s'], 22:03:42 INFO - 'tests': ['damp', 'tps']}, 22:03:42 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:03:42 INFO - 'tests': ['damp', 'tps']}, 22:03:42 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:03:42 INFO - 'tests': ['dromaeo_dom']}, 22:03:42 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:03:42 INFO - 'g4': {'talos_options': ['--disable-e10s'], 22:03:42 INFO - 'tests': ['basic_compositor_video']}, 22:03:42 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 22:03:42 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:03:42 INFO - 'tests': ['a11yr', 22:03:42 INFO - 'ts_paint', 22:03:42 INFO - 'tpaint', 22:03:42 INFO - 'sessionrestore', 22:03:42 INFO - 'sessionrestore_no_auto_restore', 22:03:42 INFO - 'tabpaint']}, 22:03:42 INFO - 'other-e10s': {'tests': ['a11yr', 22:03:42 INFO - 'ts_paint', 22:03:42 INFO - 'tpaint', 22:03:42 INFO - 'sessionrestore', 22:03:42 INFO - 'sessionrestore_no_auto_restore', 22:03:42 INFO - 'tabpaint']}, 22:03:42 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:03:42 INFO - 'ts_paint', 22:03:42 INFO - 'tpaint', 22:03:42 INFO - 'sessionrestore', 22:03:42 INFO - 'sessionrestore_no_auto_restore', 22:03:42 INFO - 'tabpaint']}, 22:03:42 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:03:42 INFO - 'ts_paint', 22:03:42 INFO - 'tpaint', 22:03:42 INFO - 'sessionrestore', 22:03:42 INFO - 'sessionrestore_no_auto_restore', 22:03:42 INFO - 'tabpaint']}, 22:03:42 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:03:42 INFO - 'tests': ['a11yr', 22:03:42 INFO - 'ts_paint', 22:03:42 INFO - 'tpaint', 22:03:42 INFO - 'sessionrestore', 22:03:42 INFO - 'sessionrestore_no_auto_restore', 22:03:42 INFO - 'tabpaint']}, 22:03:42 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:03:42 INFO - 'tests': ['a11yr', 22:03:42 INFO - 'ts_paint', 22:03:42 INFO - 'tpaint', 22:03:42 INFO - 'sessionrestore', 22:03:42 INFO - 'sessionrestore_no_auto_restore', 22:03:42 INFO - 'tabpaint']}, 22:03:42 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:03:42 INFO - 'tests': ['tsvgx', 22:03:42 INFO - 'tsvgr_opacity', 22:03:42 INFO - 'tart', 22:03:42 INFO - 'tscrollx', 22:03:42 INFO - 'cart']}, 22:03:42 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:03:42 INFO - 'tsvgr_opacity', 22:03:42 INFO - 'tart', 22:03:42 INFO - 'tscrollx', 22:03:42 INFO - 'cart']}, 22:03:42 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:03:42 INFO - 'talos_options': ['--disable-e10s'], 22:03:42 INFO - 'tests': ['tp5o']}, 22:03:42 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:03:42 INFO - 'tests': ['tp5o']}, 22:03:42 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:03:42 INFO - 'talos_options': ['--disable-e10s', 22:03:42 INFO - '--xperf_path', 22:03:42 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:03:42 INFO - 'tests': ['tp5n']}, 22:03:42 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:03:42 INFO - 'talos_options': ['--xperf_path', 22:03:42 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:03:42 INFO - 'tests': ['tp5n']}}, 22:03:42 INFO - 'talos.zip': {'path': '', 22:03:42 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:03:42 INFO - Running post-action listener: _resource_record_post_action 22:03:42 INFO - [mozharness: 2016-07-27 05:03:42.686006Z] Finished populate-webroot step (success) 22:03:42 INFO - [mozharness: 2016-07-27 05:03:42.686095Z] Running create-virtualenv step. 22:03:42 INFO - Running pre-action listener: _resource_record_pre_action 22:03:42 INFO - Running main action method: create_virtualenv 22:03:42 INFO - Creating virtualenv /builds/slave/test/build/venv 22:03:42 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:03:42 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:03:42 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:03:42 INFO - Using real prefix '/usr' 22:03:42 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:03:43 INFO - Installing distribute.............................................................................................................................................................................................done. 22:03:45 INFO - Installing pip.................done. 22:03:46 INFO - Return code: 0 22:03:46 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:03:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:03:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:03:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:03:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:03:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:03:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:03:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21ee030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23518a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x241d400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24ac570>, '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 0x24ad470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24ad900>, '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': '516f8d7ca085b4d087ab0a71000001a4-1469594776.346728-895458149', '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:03:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 22:03:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 22:03:46 INFO - Using env: {'DISPLAY': ':0', 22:03:46 INFO - 'HOME': '/home/cltbld', 22:03:46 INFO - 'LANG': 'en_US.UTF-8', 22:03:46 INFO - 'LANGUAGE': 'en_US:en', 22:03:46 INFO - 'LOGNAME': 'cltbld', 22:03:46 INFO - 'MAIL': '/var/mail/cltbld', 22:03:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:03:46 INFO - 'MOZ_NO_REMOTE': '1', 22:03:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:03:46 INFO - 'NO_EM_RESTART': '1', 22:03:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:03:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:03:46 INFO - 'PWD': '/builds/slave/test', 22:03:46 INFO - 'SHELL': '/bin/bash', 22:03:46 INFO - 'SHLVL': '1', 22:03:46 INFO - 'TERM': 'linux', 22:03:46 INFO - 'TMOUT': '86400', 22:03:46 INFO - 'USER': 'cltbld', 22:03:46 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1469594776.346728-895458149', 22:03:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:03:46 INFO - '_': '/tools/buildbot/bin/python'} 22:03:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:03:46 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:03:46 INFO - Cleaning up... 22:03:46 INFO - Return code: 0 22:03:46 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:03:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:03:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:03:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:03:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:03:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:03:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:03:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x21ee030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23518a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x241d400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24ac570>, '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 0x24ad470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24ad900>, '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': '516f8d7ca085b4d087ab0a71000001a4-1469594776.346728-895458149', '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:03:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 22:03:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 22:03:46 INFO - Using env: {'DISPLAY': ':0', 22:03:46 INFO - 'HOME': '/home/cltbld', 22:03:46 INFO - 'LANG': 'en_US.UTF-8', 22:03:46 INFO - 'LANGUAGE': 'en_US:en', 22:03:46 INFO - 'LOGNAME': 'cltbld', 22:03:46 INFO - 'MAIL': '/var/mail/cltbld', 22:03:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:03:46 INFO - 'MOZ_NO_REMOTE': '1', 22:03:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:03:46 INFO - 'NO_EM_RESTART': '1', 22:03:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:03:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:03:46 INFO - 'PWD': '/builds/slave/test', 22:03:46 INFO - 'SHELL': '/bin/bash', 22:03:46 INFO - 'SHLVL': '1', 22:03:46 INFO - 'TERM': 'linux', 22:03:46 INFO - 'TMOUT': '86400', 22:03:46 INFO - 'USER': 'cltbld', 22:03:46 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1469594776.346728-895458149', 22:03:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:03:46 INFO - '_': '/tools/buildbot/bin/python'} 22:03:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:03:46 INFO - Downloading/unpacking psutil>=3.1.1 22:03:46 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:03:46 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:03:46 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:03:46 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:03:46 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:03:46 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:03:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:03:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:03:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:03:48 INFO - Installing collected packages: psutil 22:03:48 INFO - Running setup.py install for psutil 22:03:49 INFO - building 'psutil._psutil_linux' extension 22:03:49 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 22:03:49 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 22:03:50 INFO - building 'psutil._psutil_posix' extension 22:03:50 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:03:50 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:03:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:03:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:03:50 INFO - Successfully installed psutil 22:03:50 INFO - Cleaning up... 22:03:50 INFO - Return code: 0 22:03:50 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:03: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:03:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:03:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:03: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:03:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:03:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:03:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21ee030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23518a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x241d400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24ac570>, '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 0x24ad470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24ad900>, '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': '516f8d7ca085b4d087ab0a71000001a4-1469594776.346728-895458149', '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:03:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 22:03:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 22:03:50 INFO - Using env: {'DISPLAY': ':0', 22:03:50 INFO - 'HOME': '/home/cltbld', 22:03:50 INFO - 'LANG': 'en_US.UTF-8', 22:03:50 INFO - 'LANGUAGE': 'en_US:en', 22:03:50 INFO - 'LOGNAME': 'cltbld', 22:03:50 INFO - 'MAIL': '/var/mail/cltbld', 22:03:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:03:50 INFO - 'MOZ_NO_REMOTE': '1', 22:03:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:03:50 INFO - 'NO_EM_RESTART': '1', 22:03:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:03:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:03:50 INFO - 'PWD': '/builds/slave/test', 22:03:50 INFO - 'SHELL': '/bin/bash', 22:03:50 INFO - 'SHLVL': '1', 22:03:50 INFO - 'TERM': 'linux', 22:03:50 INFO - 'TMOUT': '86400', 22:03:50 INFO - 'USER': 'cltbld', 22:03:50 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1469594776.346728-895458149', 22:03:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:03:50 INFO - '_': '/tools/buildbot/bin/python'} 22:03:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:03:50 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:03:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:03:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:03:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:03:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:03:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:03:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:03:52 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:03:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:03:52 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:03:52 INFO - Installing collected packages: mozsystemmonitor 22:03:52 INFO - Running setup.py install for mozsystemmonitor 22:03:52 INFO - Successfully installed mozsystemmonitor 22:03:52 INFO - Cleaning up... 22:03:52 INFO - Return code: 0 22:03:52 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:03:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:03:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:03:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:03:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:03:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:03:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:03:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21ee030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23518a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x241d400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24ac570>, '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 0x24ad470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24ad900>, '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': '516f8d7ca085b4d087ab0a71000001a4-1469594776.346728-895458149', '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:03:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 22:03:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 22:03:52 INFO - Using env: {'DISPLAY': ':0', 22:03:52 INFO - 'HOME': '/home/cltbld', 22:03:52 INFO - 'LANG': 'en_US.UTF-8', 22:03:52 INFO - 'LANGUAGE': 'en_US:en', 22:03:52 INFO - 'LOGNAME': 'cltbld', 22:03:52 INFO - 'MAIL': '/var/mail/cltbld', 22:03:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:03:52 INFO - 'MOZ_NO_REMOTE': '1', 22:03:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:03:52 INFO - 'NO_EM_RESTART': '1', 22:03:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:03:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:03:52 INFO - 'PWD': '/builds/slave/test', 22:03:52 INFO - 'SHELL': '/bin/bash', 22:03:52 INFO - 'SHLVL': '1', 22:03:52 INFO - 'TERM': 'linux', 22:03:52 INFO - 'TMOUT': '86400', 22:03:52 INFO - 'USER': 'cltbld', 22:03:52 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1469594776.346728-895458149', 22:03:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:03:52 INFO - '_': '/tools/buildbot/bin/python'} 22:03:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:03:52 INFO - Downloading/unpacking jsonschema==2.5.1 22:03:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:03:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:03:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:03:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:03:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:03:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:03:54 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:03:54 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:03:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:03:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:03:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:03:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:03:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:03:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:03:54 INFO - Downloading functools32-3.2.3-2.tar.gz 22:03:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:03:55 INFO - warning: no files found matching '*.txt' 22:03:55 INFO - no previously-included directories found matching 'build' 22:03:55 INFO - no previously-included directories found matching 'dist' 22:03:55 INFO - no previously-included directories found matching '.git*' 22:03:55 INFO - Installing collected packages: jsonschema, functools32 22:03:55 INFO - Running setup.py install for functools32 22:03:55 INFO - warning: no files found matching '*.txt' 22:03:55 INFO - no previously-included directories found matching 'build' 22:03:55 INFO - no previously-included directories found matching 'dist' 22:03:55 INFO - no previously-included directories found matching '.git*' 22:03:55 INFO - Successfully installed jsonschema functools32 22:03:55 INFO - Cleaning up... 22:03:55 INFO - Return code: 0 22:03:55 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:03: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:03:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:03:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:03: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:03:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:03:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:03:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21ee030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23518a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x241d400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24ac570>, '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 0x24ad470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24ad900>, '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': '516f8d7ca085b4d087ab0a71000001a4-1469594776.346728-895458149', '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:03:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 22:03:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 22:03:55 INFO - Using env: {'DISPLAY': ':0', 22:03:55 INFO - 'HOME': '/home/cltbld', 22:03:55 INFO - 'LANG': 'en_US.UTF-8', 22:03:55 INFO - 'LANGUAGE': 'en_US:en', 22:03:55 INFO - 'LOGNAME': 'cltbld', 22:03:55 INFO - 'MAIL': '/var/mail/cltbld', 22:03:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:03:55 INFO - 'MOZ_NO_REMOTE': '1', 22:03:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:03:55 INFO - 'NO_EM_RESTART': '1', 22:03:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:03:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:03:55 INFO - 'PWD': '/builds/slave/test', 22:03:55 INFO - 'SHELL': '/bin/bash', 22:03:55 INFO - 'SHLVL': '1', 22:03:55 INFO - 'TERM': 'linux', 22:03:55 INFO - 'TMOUT': '86400', 22:03:55 INFO - 'USER': 'cltbld', 22:03:55 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1469594776.346728-895458149', 22:03:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:03:55 INFO - '_': '/tools/buildbot/bin/python'} 22:03:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:03:55 INFO - Downloading/unpacking blobuploader==1.2.4 22:03:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:03:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:03:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:03:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:03:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:03:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:03:57 INFO - Downloading blobuploader-1.2.4.tar.gz 22:03:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:03:57 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:03:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:03:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:03:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:03:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:03:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:03:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:03:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:03:57 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:03:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:03:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:03:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:03:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:03:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:03:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:03:58 INFO - Downloading docopt-0.6.1.tar.gz 22:03:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:03:58 INFO - Installing collected packages: blobuploader, requests, docopt 22:03:58 INFO - Running setup.py install for blobuploader 22:03:58 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:03:58 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:03:58 INFO - Running setup.py install for requests 22:03:58 INFO - Running setup.py install for docopt 22:03:58 INFO - Successfully installed blobuploader requests docopt 22:03:58 INFO - Cleaning up... 22:03:58 INFO - Return code: 0 22:03:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:03: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:03:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:03:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:03: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:03:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:03:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:03:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21ee030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23518a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x241d400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24ac570>, '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 0x24ad470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24ad900>, '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': '516f8d7ca085b4d087ab0a71000001a4-1469594776.346728-895458149', '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:03:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 22:03:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 22:03:58 INFO - Using env: {'DISPLAY': ':0', 22:03:58 INFO - 'HOME': '/home/cltbld', 22:03:58 INFO - 'LANG': 'en_US.UTF-8', 22:03:58 INFO - 'LANGUAGE': 'en_US:en', 22:03:58 INFO - 'LOGNAME': 'cltbld', 22:03:58 INFO - 'MAIL': '/var/mail/cltbld', 22:03:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:03:58 INFO - 'MOZ_NO_REMOTE': '1', 22:03:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:03:58 INFO - 'NO_EM_RESTART': '1', 22:03:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:03:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:03:58 INFO - 'PWD': '/builds/slave/test', 22:03:58 INFO - 'SHELL': '/bin/bash', 22:03:58 INFO - 'SHLVL': '1', 22:03:58 INFO - 'TERM': 'linux', 22:03:58 INFO - 'TMOUT': '86400', 22:03:58 INFO - 'USER': 'cltbld', 22:03:58 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1469594776.346728-895458149', 22:03:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:03:58 INFO - '_': '/tools/buildbot/bin/python'} 22:03:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:03:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:03:58 INFO - Running setup.py (path:/tmp/pip-Au5iyw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:03:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:03:59 INFO - Running setup.py (path:/tmp/pip-mWdhPz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:03:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:03:59 INFO - Running setup.py (path:/tmp/pip-N1U1NV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:03:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:03:59 INFO - Running setup.py (path:/tmp/pip-a46Cjn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:03:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:03:59 INFO - Running setup.py (path:/tmp/pip-gStNyM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:03:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:03:59 INFO - Running setup.py (path:/tmp/pip-1CCsa7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:03:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:03:59 INFO - Running setup.py (path:/tmp/pip-yaVWYQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:03:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:03:59 INFO - Running setup.py (path:/tmp/pip-NbsgYT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:03:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:03:59 INFO - Running setup.py (path:/tmp/pip-jT3473-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:03:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:03:59 INFO - Running setup.py (path:/tmp/pip-TUu5Ea-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:03:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:03:59 INFO - Running setup.py (path:/tmp/pip-8pedNc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:03:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:03:59 INFO - Running setup.py (path:/tmp/pip-Cpooql-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:03:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:03:59 INFO - Running setup.py (path:/tmp/pip-raVT05-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:03:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:03:59 INFO - Running setup.py (path:/tmp/pip-MJUXZ9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:03:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:03:59 INFO - Running setup.py (path:/tmp/pip-1zg4wV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:04:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:04:00 INFO - Running setup.py (path:/tmp/pip-IVsBGo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:04:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:04:00 INFO - Running setup.py (path:/tmp/pip-Hovgve-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:04:00 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:04:00 INFO - Running setup.py install for manifestparser 22:04:00 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:04:00 INFO - Running setup.py install for mozcrash 22:04:00 INFO - Running setup.py install for mozdebug 22:04:00 INFO - Running setup.py install for mozdevice 22:04:00 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:04:00 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:04:00 INFO - Running setup.py install for mozfile 22:04:00 INFO - Running setup.py install for mozhttpd 22:04:00 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:04:00 INFO - Running setup.py install for mozinfo 22:04:00 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:04:00 INFO - Running setup.py install for mozInstall 22:04:00 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:04:00 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:04:00 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:04:00 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:04:00 INFO - Running setup.py install for mozleak 22:04:01 INFO - Running setup.py install for mozlog 22:04:01 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:04:01 INFO - Running setup.py install for moznetwork 22:04:01 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:04:01 INFO - Running setup.py install for mozprocess 22:04:01 INFO - Running setup.py install for mozprofile 22:04:01 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:04:01 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:04:01 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:04:01 INFO - Running setup.py install for mozrunner 22:04:01 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:04:01 INFO - Running setup.py install for mozscreenshot 22:04:01 INFO - Running setup.py install for moztest 22:04:01 INFO - Running setup.py install for mozversion 22:04:01 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:04:01 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:04:01 INFO - Cleaning up... 22:04:01 INFO - Return code: 0 22:04:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:04:01 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:04:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:04:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:04:01 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:04:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:04:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:04:01 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 0x21ee030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23518a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x241d400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24ac570>, '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 0x24ad470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24ad900>, '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': '516f8d7ca085b4d087ab0a71000001a4-1469594776.346728-895458149', '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:04:01 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:04:01 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:04:01 INFO - Using env: {'DISPLAY': ':0', 22:04:01 INFO - 'HOME': '/home/cltbld', 22:04:01 INFO - 'LANG': 'en_US.UTF-8', 22:04:01 INFO - 'LANGUAGE': 'en_US:en', 22:04:01 INFO - 'LOGNAME': 'cltbld', 22:04:01 INFO - 'MAIL': '/var/mail/cltbld', 22:04:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:04:01 INFO - 'MOZ_NO_REMOTE': '1', 22:04:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:04:01 INFO - 'NO_EM_RESTART': '1', 22:04:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:04:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:04:01 INFO - 'PWD': '/builds/slave/test', 22:04:01 INFO - 'SHELL': '/bin/bash', 22:04:01 INFO - 'SHLVL': '1', 22:04:01 INFO - 'TERM': 'linux', 22:04:01 INFO - 'TMOUT': '86400', 22:04:01 INFO - 'USER': 'cltbld', 22:04:01 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1469594776.346728-895458149', 22:04:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:04:01 INFO - '_': '/tools/buildbot/bin/python'} 22:04:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:04:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:04:02 INFO - Running setup.py (path:/tmp/pip-CiYyUl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:04:02 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 22:04:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:04:02 INFO - Running setup.py (path:/tmp/pip-q0ZK5b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:04:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:04:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:04:02 INFO - Running setup.py (path:/tmp/pip-NdB8pO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:04:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 22:04:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:04:02 INFO - Running setup.py (path:/tmp/pip-xVQXTN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:04:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:04:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:04:02 INFO - Running setup.py (path:/tmp/pip-MNxJxF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:04:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 22:04:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:04:02 INFO - Running setup.py (path:/tmp/pip-KHrmh4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:04:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 22:04:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:04:02 INFO - Running setup.py (path:/tmp/pip-uT2jZz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:04:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 22:04:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:04:02 INFO - Running setup.py (path:/tmp/pip-4e5E75-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:04:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 22:04:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:04:02 INFO - Running setup.py (path:/tmp/pip-SC3LMV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:04:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 22:04:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:04:02 INFO - Running setup.py (path:/tmp/pip-3oQTnv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:04:02 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:04:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:04:02 INFO - Running setup.py (path:/tmp/pip-aCmDif-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:04:03 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 22:04:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:04:03 INFO - Running setup.py (path:/tmp/pip-kdU3eg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:04:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 22:04:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:04:03 INFO - Running setup.py (path:/tmp/pip-yJvnwm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:04:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 22:04:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:04:03 INFO - Running setup.py (path:/tmp/pip-26Wpml-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:04:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 22:04:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:04:03 INFO - Running setup.py (path:/tmp/pip-qb9ReP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:04:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 22:04:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:04:03 INFO - Running setup.py (path:/tmp/pip-VSgZBb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:04:03 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 22:04:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:04:03 INFO - Running setup.py (path:/tmp/pip-XOT2cM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:04:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 22:04:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:04:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:04:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 22:04:03 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:04:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:04:03 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:04: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:04: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:04: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:04: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:04: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:04: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:04:05 INFO - Downloading blessings-1.6.tar.gz 22:04:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:04:05 INFO - Installing collected packages: blessings 22:04:05 INFO - Running setup.py install for blessings 22:04:05 INFO - Successfully installed blessings 22:04:05 INFO - Cleaning up... 22:04:05 INFO - Return code: 0 22:04:05 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:04:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:04:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:04:05 INFO - Reading from file tmpfile_stdout 22:04:05 INFO - Current package versions: 22:04:05 INFO - argparse == 1.2.1 22:04:05 INFO - blessings == 1.6 22:04:05 INFO - blobuploader == 1.2.4 22:04:05 INFO - docopt == 0.6.1 22:04:05 INFO - functools32 == 3.2.3-2 22:04:05 INFO - jsonschema == 2.5.1 22:04:05 INFO - manifestparser == 1.1 22:04:05 INFO - mozInstall == 1.12 22:04:05 INFO - mozcrash == 0.17 22:04:05 INFO - mozdebug == 0.1 22:04:05 INFO - mozdevice == 0.48 22:04:05 INFO - mozfile == 1.2 22:04:05 INFO - mozhttpd == 0.7 22:04:05 INFO - mozinfo == 0.9 22:04:05 INFO - mozleak == 0.1 22:04:05 INFO - mozlog == 3.3 22:04:05 INFO - moznetwork == 0.27 22:04:05 INFO - mozprocess == 0.23 22:04:05 INFO - mozprofile == 0.28 22:04:05 INFO - mozrunner == 6.12 22:04:05 INFO - mozscreenshot == 0.1 22:04:05 INFO - mozsystemmonitor == 0.3 22:04:05 INFO - moztest == 0.7 22:04:05 INFO - mozversion == 1.4 22:04:05 INFO - psutil == 3.1.1 22:04:05 INFO - requests == 1.2.3 22:04:05 INFO - wsgiref == 0.1.2 22:04:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:04:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:04:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:04:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:04:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:04:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:04:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:04:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21ee030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23518a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x241d400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24ac570>, '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 0x24ad470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24ad900>, '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': '516f8d7ca085b4d087ab0a71000001a4-1469594776.346728-895458149', '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:04:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 22:04:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 22:04:05 INFO - Using env: {'DISPLAY': ':0', 22:04:05 INFO - 'HOME': '/home/cltbld', 22:04:05 INFO - 'LANG': 'en_US.UTF-8', 22:04:05 INFO - 'LANGUAGE': 'en_US:en', 22:04:05 INFO - 'LOGNAME': 'cltbld', 22:04:05 INFO - 'MAIL': '/var/mail/cltbld', 22:04:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:04:05 INFO - 'MOZ_NO_REMOTE': '1', 22:04:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:04:05 INFO - 'NO_EM_RESTART': '1', 22:04:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:04:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:04:05 INFO - 'PWD': '/builds/slave/test', 22:04:05 INFO - 'SHELL': '/bin/bash', 22:04:05 INFO - 'SHLVL': '1', 22:04:05 INFO - 'TERM': 'linux', 22:04:05 INFO - 'TMOUT': '86400', 22:04:05 INFO - 'USER': 'cltbld', 22:04:05 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1469594776.346728-895458149', 22:04:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:04:05 INFO - '_': '/tools/buildbot/bin/python'} 22:04:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:04:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 22:04:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 22:04:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 22:04:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 22:04:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 22:04:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 22:04:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 22:04:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 22:04:06 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 22:04:06 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 22:04:06 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 22:04:06 INFO - Cleaning up... 22:04:06 INFO - Return code: 0 22:04:06 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:04:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:04:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:04:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:04:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:04:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:04:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:04:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21ee030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23518a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x241d400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24ac570>, '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 0x24ad470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24ad900>, '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': '516f8d7ca085b4d087ab0a71000001a4-1469594776.346728-895458149', '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:04:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 22:04:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 22:04:06 INFO - Using env: {'DISPLAY': ':0', 22:04:06 INFO - 'HOME': '/home/cltbld', 22:04:06 INFO - 'LANG': 'en_US.UTF-8', 22:04:06 INFO - 'LANGUAGE': 'en_US:en', 22:04:06 INFO - 'LOGNAME': 'cltbld', 22:04:06 INFO - 'MAIL': '/var/mail/cltbld', 22:04:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:04:06 INFO - 'MOZ_NO_REMOTE': '1', 22:04:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:04:06 INFO - 'NO_EM_RESTART': '1', 22:04:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:04:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:04:06 INFO - 'PWD': '/builds/slave/test', 22:04:06 INFO - 'SHELL': '/bin/bash', 22:04:06 INFO - 'SHLVL': '1', 22:04:06 INFO - 'TERM': 'linux', 22:04:06 INFO - 'TMOUT': '86400', 22:04:06 INFO - 'USER': 'cltbld', 22:04:06 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1469594776.346728-895458149', 22:04:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:04:06 INFO - '_': '/tools/buildbot/bin/python'} 22:04:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:04:06 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:04:06 INFO - Cleaning up... 22:04:06 INFO - Return code: 0 22:04:06 INFO - Running post-action listener: _resource_record_post_action 22:04:06 INFO - Running post-action listener: _start_resource_monitoring 22:04:06 INFO - Starting resource monitoring. 22:04:06 INFO - [mozharness: 2016-07-27 05:04:06.355366Z] Finished create-virtualenv step (success) 22:04:06 INFO - [mozharness: 2016-07-27 05:04:06.356320Z] Running install step. 22:04:06 INFO - Running pre-action listener: _resource_record_pre_action 22:04:06 INFO - Running main action method: install 22:04:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:04:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:04:06 INFO - Reading from file tmpfile_stdout 22:04:06 INFO - Detecting whether we're running mozinstall >=1.0... 22:04:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:04:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:04:06 INFO - Reading from file tmpfile_stdout 22:04:06 INFO - Output received: 22:04:06 INFO - Usage: mozinstall [options] installer 22:04:06 INFO - Options: 22:04:06 INFO - -h, --help show this help message and exit 22:04:06 INFO - -d DEST, --destination=DEST 22:04:06 INFO - Directory to install application into. [default: 22:04:06 INFO - "/builds/slave/test"] 22:04:06 INFO - --app=APP Application being installed. [default: firefox] 22:04:06 INFO - mkdir: /builds/slave/test/build/application 22:04:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 22:04:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 22:04:23 INFO - Reading from file tmpfile_stdout 22:04:23 INFO - Output received: 22:04:23 INFO - /builds/slave/test/build/application/firefox/firefox 22:04:23 INFO - Running post-action listener: _resource_record_post_action 22:04:23 INFO - [mozharness: 2016-07-27 05:04:23.876838Z] Finished install step (success) 22:04:23 INFO - [mozharness: 2016-07-27 05:04:23.877115Z] Running run-tests step. 22:04:23 INFO - Running pre-action listener: _resource_record_pre_action 22:04:23 INFO - Running main action method: run_tests 22:04:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:04:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:04:23 INFO - Python 2.7.3 22:04:23 INFO - Return code: 0 22:04:23 INFO - grabbing minidump binary from tooltool 22:04:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:04:23 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24ac570>, '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 0x24ad470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24ad900>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:04:23 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 22:04:23 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 22:04:23 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 22:04:23 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:04:24 INFO - Return code: 0 22:04:24 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 22:04:24 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:04:24 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:04:24 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 22:04:24 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:04:24 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-e10s', '--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-061', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 22:04:24 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-e10s --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-061 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 22:04:24 INFO - Using env: {'DISPLAY': ':0', 22:04:24 INFO - 'HOME': '/home/cltbld', 22:04:24 INFO - 'LANG': 'en_US.UTF-8', 22:04:24 INFO - 'LANGUAGE': 'en_US:en', 22:04:24 INFO - 'LOGNAME': 'cltbld', 22:04:24 INFO - 'MAIL': '/var/mail/cltbld', 22:04:24 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:04:24 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 22:04:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:04:24 INFO - 'MOZ_NO_REMOTE': '1', 22:04:24 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:04:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:04:24 INFO - 'NO_EM_RESTART': '1', 22:04:24 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:04:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:04:24 INFO - 'PWD': '/builds/slave/test', 22:04:24 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:04:24 INFO - 'SHELL': '/bin/bash', 22:04:24 INFO - 'SHLVL': '1', 22:04:24 INFO - 'TERM': 'linux', 22:04:24 INFO - 'TMOUT': '86400', 22:04:24 INFO - 'USER': 'cltbld', 22:04:24 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1469594776.346728-895458149', 22:04:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:04:24 INFO - '_': '/tools/buildbot/bin/python'} 22:04:24 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs-e10s', '--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-061', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 22:04:24 INFO - mozversion application_buildid: 20160726192234 22:04:24 INFO - mozversion application_changeset: 250943418f3a43c46de84797ad58a22f724caf58 22:04:24 INFO - mozversion application_display_name: Nightly 22:04:24 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:04:24 INFO - mozversion application_name: Firefox 22:04:24 INFO - mozversion application_remotingname: firefox 22:04:24 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 22:04:24 INFO - mozversion application_vendor: Mozilla 22:04:24 INFO - mozversion application_version: 50.0a1 22:04:24 INFO - mozversion platform_buildid: 20160726192234 22:04:24 INFO - mozversion platform_changeset: 250943418f3a43c46de84797ad58a22f724caf58 22:04:24 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 22:04:24 INFO - mozversion platform_version: 50.0a1 22:04:24 INFO - using testdate: 1469595864 22:04:24 INFO - actual date: 1469595864 22:04:24 INFO - starting webserver on 'localhost:46961' 22:04:24 INFO - SUITE-START | Running 2 tests 22:04:24 INFO - TEST-START | dromaeo_css 22:04:24 INFO - operating with platform_type : linux_ 22:04:24 INFO - Initialising browser for dromaeo_css test... 22:04:24 INFO - TEST-INFO | started process 3308 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpVjvQvq/profile http://localhost:46961/getInfo.html) 22:04:28 INFO - PROCESS | 3308 | JavaScript error: , line 0: uncaught exception: undefined 22:04:30 INFO - PROCESS | 3308 | __metrics Screen width/height:1600/1200 22:04:30 INFO - PROCESS | 3308 | colorDepth:24 22:04:30 INFO - PROCESS | 3308 | Browser inner width/height: 1024/697 22:04:30 INFO - PROCESS | 3308 | __metrics 22:04:30 ERROR - PROCESS | 3308 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2967: TypeError: this.tabs is undefined 22:04:31 INFO - TEST-INFO | 3308: exit 0 22:04:31 INFO - Browser initialized. 22:04:31 INFO - Running cycle 1/1 for dromaeo_css test... 22:04:31 INFO - TEST-INFO | started process 3430 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpVjvQvq/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:04:43 INFO - PROCESS | 3430 | RSS: Main: 166891520 22:04:43 INFO - PROCESS | 3430 | 22:05:33 INFO - PROCESS | 3430 | 1469595933171 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:07:41 INFO - PROCESS | 3430 | Cycle 1(1): loaded http://localhost:46961/tests/dromaeo/cssquery-dojo.html (next: http://localhost:46961/tests/dromaeo/cssquery-ext.html) 22:07:42 INFO - PROCESS | 3430 | RSS: Main: 171208704 22:07:42 INFO - PROCESS | 3430 | 22:10:44 INFO - PROCESS | 3430 | Cycle 1(1): loaded http://localhost:46961/tests/dromaeo/cssquery-ext.html (next: http://localhost:46961/tests/dromaeo/cssquery-jquery.html) 22:10:45 INFO - PROCESS | 3430 | RSS: Main: 169578496 22:10:45 INFO - PROCESS | 3430 | 22:13:47 INFO - PROCESS | 3430 | Cycle 1(1): loaded http://localhost:46961/tests/dromaeo/cssquery-jquery.html (next: http://localhost:46961/tests/dromaeo/cssquery-mootools.html) 22:13:48 INFO - PROCESS | 3430 | RSS: Main: 173264896 22:13:48 INFO - PROCESS | 3430 | 22:16:50 INFO - PROCESS | 3430 | Cycle 1(1): loaded http://localhost:46961/tests/dromaeo/cssquery-mootools.html (next: http://localhost:46961/tests/dromaeo/cssquery-prototype.html) 22:16:50 INFO - PROCESS | 3430 | RSS: Main: 167354368 22:16:50 INFO - PROCESS | 3430 | 22:19:53 INFO - PROCESS | 3430 | Cycle 1(1): loaded http://localhost:46961/tests/dromaeo/cssquery-prototype.html (next: http://localhost:46961/tests/dromaeo/cssquery-yui.html) 22:19:53 INFO - PROCESS | 3430 | RSS: Main: 169607168 22:19:53 INFO - PROCESS | 3430 | 22:22:56 INFO - PROCESS | 3430 | Cycle 1(1): loaded http://localhost:46961/tests/dromaeo/cssquery-yui.html (next: http://localhost:46961/tests/dromaeo/cssquery-dojo.html) 22:22:56 INFO - PROCESS | 3430 | RSS: Main: 172748800 22:22:56 INFO - PROCESS | 3430 | 22:22:56 INFO - PROCESS | 3430 | __start_tp_report 22:22:56 INFO - PROCESS | 3430 | _x_x_mozilla_page_load 22:22:56 INFO - PROCESS | 3430 | _x_x_mozilla_page_load_details 22:22:56 INFO - PROCESS | 3430 | |i|pagename|runs| 22:22:56 INFO - PROCESS | 3430 | |0;dojo.html;3115.6105486814145;3125.2186953261685;3130.530420436934;3135.9686403135975;3139.8430078496085;5389.973050134754;5407.810726624569;5424.5117939385445;5485.561155107592;5486.862828429291;5302.416734159249;5351.598630102739;5446.210299506579;5495.670259784406;5562.694051827158;4877.463479017305;4925.187344088239;5022.296878437021;5090.44005159434;5124.615653825959;4937.23472861707;5162.716050617215;5166.044281807859;5180.378354597435;5187.6628019178825;4828.951710482886;4980.252962055721;4991.5258050485145;5035.77339019745;5080.822171224008;4968.925466118011;5058.7976480940715;5066.214736715815;5070.8225212117395;5092.134337162691;5027.824026159104;5038.3198268234055;5040.445550989388;5051.595872330205;5055.216441451581;5312.4687531246955;5375.731213439312;5486.725663716838;5513.5037846594;5569.470900264469;5758.596898217126;5799.130130480462;5808.3030036395485;5817.185594016841;5819.418058194189;5807.274090738658;5829.09649004405;5862.824115276549;5883.3234178069215;5892.351841297454;5320.122179840322;5418.972905135489;5475.342958844964;5526.751296191681;5534.225208470817;6087.178230938766;6087.391260873966;6089.573729838958;6092.786752463642;6100.054491553817;5819.73811178498;5827.912581311284;5829.854253643608;5834.620749651259;5855.882882342329;5688.23208866806;5775.942240577624;5816.88366232673;5824.534037277008;5920.2303700519515;5662.801801936913;5811.27359080115;5817.94182058174;5828.125781132864;5883.588148829626;5835;5852.707364631751;5909.645421274738;5920.31916329619;5946.048632218824;4381.211381951216;4387.517373088957;4424.269995450714;4461.754603496774;4487.618552423015;3915.295246855593;3954.0510277533185;3999.5000624921886;4002.9799851000557;4014.879553613396;5527.585431092684;5566.721663916869;5580.748866301026;5584.441555844464;5634.32388113429;5723.77104915807;5842.853928651818;5851.063829787214;5853.9414605854245;5858.150568167677;5584.301962254718;5602.831915042555;5618.522425593788;5673.744681489343;5709.429057094258;4976.800927962913;5046.26829109777;5051.191809310566;5105.8723531911255;5156.690598564098;5905.793297234576;6025.789097381658;6091.51267898567;6106.114613381035;6118.816435506942;5837.357890632027;5853.9121913172185;5916.792912248223;5925.496332811586;5991.760329586942;6017.217761690952;6056.273247210187;6062.454379105901;6065.211522502046;6072.969635151972;5671.44035390271;5775.566832487496;5822.155787410887;5826.388229235869;5858.648481091148;5785;5832.358440571618;5838.970805145947;5872.354041055566;5925.022371308858;5699.344575373885;5703.800366987136;5742.224799651994;5746.281714785652;5764.13537969308;5644.491995720405;5708.115242137475;5712.914306285492;5714.685692286964;5719.084946408721;5615;5703.486686198262;5721.170430287501;5722.513586345207;5731;5794.594378393472;5815.3603103657715;5816.621919575214;5820.82537523875;5867.090600956859;5539.418361072029;5591.077472217201;5737.799177028784;5752.30972283312;5753.942460575508 22:22:56 INFO - PROCESS | 3430 | |1;ext.html;552.579264800027;587.9794207202748;593.2999061107894;606.2724730323612;637.3690046853617;39130.413043804365;42530;42632.36051459231;42636.3604545932;42644;42281;42774;42981.14037719244;43041.35437968425;43131.78434107833;8871.467711937272;8965.731028069169;8992.775180620503;9034.774130646721;9090.181883630472;8868.955655221715;9009.144131307516;9021;9024.548772561378;9033.322500812432;9036.322275829307;9046.683366082189;9060.864087038699;9090.500022498793;9095.181433670969;8899.15458031486;8929.107089291052;8947.418417802855;9008;9027.14242146995;8886.333524985652;8903.198712115876;8932.687355942577;8969.461832290084;8991.775205619912;40265.59734402648;40658.39012414816;40752.184956301004;40988.59011409878;41035.1792964139;3496.6852983231634;3622.6739593436455;3633.6729694327637;3748.850045998157;3998.100427403811;32679.83660081708;33031.50452743211;33146.17134571655;33181.6681833181;34360.140996475035;4358.717930945708;4476.7090139140855;4510.25580779171;4515.187266292066;4677.7661116944355;341480;342097;343679.9999999975;343743;344804;55890;56203.71898140483;56298.43701562932;56372;56405.15392269119;34743.65256347455;35655.64344356575;35780.64219357825;37519.62480375216;37608.811955940044;17149.399771007957;17229.483115506555;17250.568735781704;17254.654906902044;17256.65486690259;13105.14816536922;13249.933750331187;13271.402786874422;13272;13285.73785490377;19331.92456124712;19353.612927741367;19361.419157425298;19378.70931936022;19383.806161938483;7176.246494118042;7372;7417.888731669029;7481.251874812584;7601.163871974079;952.6046690623452;1003.172382784206;1054.2409465185056;1059.5285098131412;1089.9782004359868;31891.564879580474;32803.67196328006;32853.17867053343;32965.17587060295;32977.83511082429;1110.1784679337231;1116.0569318925495;1134.5575225661999;1139.880312567185;1231.378154032208;16751.6649667006;17152.05663688484;17192.373315558318;17202.483925482255;17203.311867525408;542816;545068;555748;556849;560010;56727.43272567304;57391.13913291304;57496.425035749104;57522.7123864378;57611.13583296254;1341.704824938512;1342.2416334770824;1347.723716638087;1348.629126990066;1359.4766015084265;30173.49132543408;31931.201719956258;32159.67840321567;32443.351132976735;32529.186770329983;50495.49504504908;50600;50657;50687;50719.74640126776;453065;453318;454459;455232;455583;26987;27027.91888324445;27503.58744618792;27609.309767255978;27633.585496217976;46532.7673361631;47094.52905471039;47634.76182619065;47674.76162619304;48894.75552622214;41109.794451027556;41437.79281103575;42067;42345.364819528346;43305.13389732251;41488.585114148475;41510.37734433926;41925;42143;42389.78805105955;34785;39082.80458597689;39505.012374689715;39755.80122099371;41571;35176.64823351836;37370;37860.41121345926;37983.81008094942;38087;27725.584116237867;27776.583351250152;27827.16518504448;27859;28159.43681126325 22:22:56 INFO - PROCESS | 3430 | |2;jquery.html;379.9943000854986;389.7875657766519;399.57644896409806;403.541979852867;415.98324210027073;14203.360848761804;18345.357912473068;20356.491087722814;20417.979101044944;20648.38054858353;19168.13743381548;19595.510112247164;19874.602507949832;20415.897920510375;20633.793662063374;5764.106563482667;5796.855078623026;5840.123981402781;5854;5906.37983011784;5761.827145185651;5822.417758224186;5835.241418615596;5875.9118613220835;5877.794277200299;5727.513161381266;5734.827955161351;5741.339745929209;5742.253507044078;5766.452187042245;5675.091985282355;5794.797182098607;5803.9709801451145;5807.303123625171;5831.79588714393;4898.240772680205;5017.698938063728;5028.723420211887;5039.47085556015;5052;23185.188518401945;23745.287641370785;23873.761262387503;24094.759052409427;24209.273721788373;2404.6513255577843;2544.9745502544924;2964.7479964202853;3082.8766849326;3185.3629274145264;14388.208648524327;14497;14968.775468367983;15163.545093647097;15217.31522081509;1840.6502764474708;1878.389523404899;1880.689686201775;1916.434651777729;1919.5968846542244;53659.73170134125;70514;84094.15905840925;85690.57154714187;87050;10374.740631484123;10637.202209834139;10791.46042697862;11071.50178241965;11188.832167517494;14897.70204595902;15027.849721502645;15095.849041509664;15125.621859453382;15186.316615752317;8019.7995050123045;8158.469699469515;8226.588670566447;8272.33821294307;8301.6679332826;7729.5748733819055;7756.185600511865;7990.680372785024;8105.473144245605;8197.426180167331;6349.714262858182;6516.611362091686;6542.771003014872;6641.1366522352755;6838.700646877177;1900.2588990293796;1974.3385965701364;1982.7224188613607;1998.5003749062735;2041.7958204179463;931.6878845586805;985.1872205430549;1000.2648053680539;1019.6533178719271;1089.6839916424149;20360.083796229206;20742.377728668165;20754.48113797167;20826.917308269356;21092.47268818261;987.9851802222972;1012.6405126180247;1022.5909636145601;1051.5361744301288;1057.9788404232027;11051.502682379312;11549.76900461986;11835.763284734428;11864.762704745857;11927.642170734893;408715;416414;551747;577599;583253;89180.5540972304;91664.54167729252;97556;102375;103699.48150259201;989.5744829723288;1028.4651980970004;1042.6663467690419;1057.94710264488;1058.3544038136731;15353.923230383776;17825.286988520314;17881.28474860991;18133.546661333043;18200.544986375447;43887.78056109699;44236.55763442325;44425.55574444344;44935.55064449314;46626.300605490265;60026.699866500385;63289.36710632835;63354;64106.23043926069;64330.356696432435;8024.598770061591;8238.340932725487;8336.916630833857;8359.832803344021;8463.111373305714;12311.75376492483;13494.595162145151;13607.387667554785;13607.455701772215;13776.793348099987;11679.240849344766;11766.882331176921;11818.409079545816;11831.881681183078;12453.75092498163;10889.727756806144;11026.393548354597;11769.234999724991;11870.643880683594;12057.15599907998;11683.474243659226;11750.23623464472;11819.586314478953;11947.522099116286;12159.878401216229;8258.793530161794;8329.37529685288;8381.078081411162;8423.873641895254;8547.313361863984;9055.501947392957;11510.194286399872;11653.76692466163;11679.240849344766;12266.26402415858 22:22:56 INFO - PROCESS | 3430 | |3;mootools.html;1480.4300344367418;1505.3602219056897;1641.4337053716472;1685.6965746165686;1777.777777777777;7820.8044798880055;8766.167214114665;8866.556672166398;9364.6254149834;9378.57796399161;8888.866667;9232.215261702764;9242.491662958551;9249.260059195265;9252.074792520745;2402.531506356261;2429.7813196812285;2435.281591930385;2435.841670291434;2436.378723425524;2340.953180936389;2361.4332560185603;2396.18529699902;2415.2271273192587;2417.0452671194957;2355.1286024170995;2387.3076807725824;2389.95220095598;2400.987995060031;2418.504206637635;2344.812415006795;2348.8473249238746;2352.9764702352927;2353.1293421434093;2383.4041489627593;2210.73471183459;2229.052652622645;2255.9661605075935;2268.194790849258;2277.567262220173;9146.405483643608;9325.114114159143;9353.298502612332;9370.953145234298;9418.905810941871;5502.779888804443;5595.888082238333;5620.662760234399;5642.097264437712;5643.661380317194;3439.4324936385465;3477.9826100869586;3485.4597537381746;3487.2851065531504;3522.418800897849;1191.142377488207;1202.6331968749528;1239.4050855589278;1271;1271.0975207602614;13011.479540818356;13049.739005219843;13067;13125.146865453715;13150.276734779583;4648.96973114331;4721.858344249678;4761.428628564525;4807.831725889577;4936.506349365035;10878.891211087946;10879.401632910265;10888;10895.727606809893;10904.836427453594;5485.61600687948;5489.149181876815;5512.889742205133;5527;5533.47431993968;4332.935005974913;4906.2640603909695;5032.144535428913;5075.847724568269;5078.771455284521;4134.669226461876;4186.434831297796;4199.727017743898;4260.190563792869;4279.786010699452;1339.5512503311304;1346.1721041559567;1356.9932150339382;1368.00135900791;1368.8152099466445;963.3738070254251;978.6281213138961;989.0999190736395;994.7015895231401;1016.9694909152737;5979.9402005980255;6080.422359875804;6152.78465253714;6217.626942383471;6221.377862213742;389.7271909663247;392.8762439831444;395.87727804380734;1028.6194108179873;1040.3965699894043;4115.938260926088;4245.150969805989;4333.71830830995;4352.064306174188;4353.804078816461;11367.54529818798;11618.419079046014;11643.650690479299;11712.472938717778;11748.647540573797;11580.536778528933;11628;11632.127590430751;11633.941830290794;11638.534458621729;8158.510489370538;8462.915370846213;8478.067412584734;8569.400141990001;8787.428817126865;6065.3934606539;6099.542534310033;6170.321264660974;6250.124982502544;6344.71448784815;10452.477376131315;10712.625058122929;10821.51303191374;10835.349879007284;10915.890841091805;10953.726156846142;10957.452127393757;10973.177011724312;10989.890101098887;10999.23005389647;7212.350888420067;7965.084015338311;7996.720114795954;8261.752147435587;8318.958405207935;9130.04134565861;9182.816343672956;9193.678221262224;9203.263738900738;9210.861837072316;7952.960235198787;8016.559089250147;8092.878606820785;8227.588620569068;8268.627911744106;7535.660895259617;7731.110922244014;8051.2351326623875;8108.554029528196;8222.794430139294;7069;7093.412764778462;7149.106361704787;7166.784996450115;7201.927980720126;5086;5116.258142569381;5198.506141916512;5272.787258930391;5346.9465305348;5484.561235101102;5530.55755539564;5578.274824272981;5581.916271255853;5720.2849643794525 22:22:56 INFO - PROCESS | 3430 | |4;prototype.html;318.7402267152271;323.89959112675075;327.58724007750243;329.72962171019776;332.09007319943356;5441.530786687592;5472.890542189164;5546.112621980479;5666.914996275054;5729.570282228834;5481.643693159952;5535.391106978229;5540.944590554093;5550.944490555094;5557.7209446426605;4342.8480003199975;4355.629771469429;4362.956370436303;4386.934195987063;4398.73607583546;4090.6727461803134;4283.978580107095;4352.586504282088;4405.3391991201415;4412.75729834859;4261.382099595557;4291.721038132527;4295.849645262417;4297.398364228995;4305.935410968822;4263.531011588739;4294.9570504295025;4306.892327691801;4340.891477713051;4345.326474396474;3490.2147016921244;3788.355979483467;3789.730440302502;3798.9620103798884;3800.923981520354;5428.5385742211565;5434.375046869621;5437.782488700447;5456.809011684572;5477.233187353773;4804.975975120102;4860.611151107938;4877.146499362633;4888.266759985995;4890.755462226874;5552.639078459887;5808.912866307009;5823;5826.796062137805;5852.531797456193;4094.1197642506704;4101.282275601758;4101.794910254505;4109.8972525686795;4121.5878412158845;6032;6037.909431358533;6072.848178795522;6111.2055432793895;6162.291336496361;4055.8174882130374;4131.648809851136;4141.275276826544;4166.437530933345;4184.03767133561;4155.210510003089;4166.854160104382;4170.416141740159;4174.144300418407;4189.539150693421;4815.253635686474;4824;4840.443349014838;4878.634102442331;4898.90202195954;5662.801801936913;5843.444872737083;5940;5942.58401911862;5960.4635582797755;1405.775075987837;1416.277698373816;1418.8155539779762;1427.1365823676733;1428.5214453158308;868.870468391089;903.1260135219446;909.4361495872602;921.15714121578;929.869818225449;5015.04112573576;5031.82388616404;5054.292399064134;5057.418396884405;5089.287499750038;5241.711705856138;5397.298351214317;5418.317932623377;5436.184572314184;5459.808906688247;5029.220470827027;5049.520295571988;5052.494750524918;5309.495597918191;5690.857728556819;3196.2329041030453;3197.952030719541;3213.614366276062;3218.726408255278;3224.8712950467616;6071.965891426422;6244.812655620339;6262.780802671976;6265.592736472115;6290.55966082379;6046.2139921809885;6081.239845019372;6101.298350689639;6101.725422355916;6106.511479081662;6190.802265195881;6193.274959018107;6193.969030154821;6197.349278325711;6221.471174950089;5838.357780644211;6005.309389420097;6015.218021657157;6018.578699490994;6038.5169186465855;5965.105234214902;5976.970115149396;5991.562025114028;5999.490298175616;6013.368596297326;5991.970040149946;6098.451139397298;6246.3134953561375;6257.906131408124;6262.561620686508;4050.8784736457956;4107.507099148122;4148.647364974011;4155.667546596325;4156.272652285898;6058.242719660043;6062.90905636407;6065.090236464565;6076.90884636722;6086.056661217518;5769.884602308014;5845.941540584709;5900.350961394329;5908.822735317947;5937.910931335947;5840.240768700041;5852.209951656642;5861.296644402699;5867;5881.529477641864;5749.453801888814;5786.392428795084;5796.681182535001;5798.56510761703;5806.606414460583;5732.197492350991;5851.473367396955;5909.990603194934;5917.940820591739;5960.254968128984;5626.718664066862;5804.941950580609;5809.970950145223;5847.970760146172;5883.146943693226 22:22:56 INFO - PROCESS | 3430 | |5;yui.html;773.4276635289883;790.0361558898146;807.7132617920641;811.3144392987925;846.8896908802988;15713.371465141396;16396.18019099046;16536.090515021668;16620.91689541552;16648.750268745964;14770;14839.406423743038;14982.026168299053;14997.250137493109;15024.248787560633;648.7859006527834;792.5046845721439;795.9482633628825;803.4295650088415;808.4340961327065;807.5679511461369;809.0291650019999;809.3161278719473;814.0597609760736;814.1858141858141;851.6508231625035;859.4413631139747;859.6991053131385;859.8710193470966;862.1766213266347;851.5274022917279;857.3269983063296;857.4255248983134;859.2352806002663;859.6518410043942;629.6159342800888;629.6411045703951;630.4515071887441;633.1737083106544;633.7116611941556;15121.848781512264;15156.31796569146;15156.696866062728;15196.468123615621;15235.466758663393;14992;15110.54668359951;15162.696746065018;15207.543773686808;15223.695526089416;12282.943547987603;12431.689207769788;12453.626391208278;12720.872791272153;12826.871731282661;3857.6720978716844;3891.3579259422163;3942.0539070622845;3959.78221197834;4065.8170382332864;76724;76893.23106768916;78311.608441958;78417.60791196064;81610.59194703988;10343.689689309333;10347.793044139074;10460.68617941463;10485.580576776843;10626.256162068581;20970.580588388148;21034.89482552608;21064.789352106283;21098.472538186666;21109.89445052765;8311.750647480585;8325.084240733515;8410.285125764256;8434.789130271793;8526.488410695254;4731.243001119804;4898;4919.1391506486225;4924.4090709115135;4932.605391568738;3193.217661672905;3265.151060724181;3292.3415316936707;3296.522004309362;3299.1257316811066;608.9330173680892;619.2568917299259;622.4802290087737;624.2758400255682;641.7016087519326;615.3600255733986;626.5301024231826;668.4552090046597;677.1332694151494;709.606168576445;9525.904740952501;9558.562566545625;9671.419714817133;9732.12410883024;9783.41299522031;675.4967549175896;705.3757424679093;717.3292971072065;719.9964000179965;730.7223255163004;4929.260610908321;5106.821261255838;5464.180372944011;5539.723013849291;5607.551395888319;106492;128728;129489;129641;131344;64333;100097.49951250198;103413.48293258635;107062.46468767762;107471;99172.50413747886;99726.5013674927;100442.4977875106;100480.49759751154;101424.43590038318;10474.947625261824;10593.417362045162;10716.624918127829;10732.624358147428;10752.838707419238;55704.72147639236;56531.27720432782;57426;57558.136627949774;58437.70781146067;76895.61552192204;77102.61448692721;83825;85331;85931.14068859481;13230.536931207362;13383.53157639478;13502.527411540548;13858.722825543231;13954.162750235018;15211.923940380228;15645.765313520078;15849.286782094603;15919.522414327588;16677.08276044829;13905.096168749;14101.788473172706;14248.501302454364;14540.49108281205;14919;13743.742995890923;13882.837327363892;14155.575332740034;14242.643933901736;14460.566183014527;13182.472701091849;14299.428022878968;14509.274536273355;14634.634134146732;14748.188849613374;11013.229073964745;11173.944130279297;11193.328400296008;11350.262232954832;12260.38698065111;11367.488463019019;11745.47145378443;12309.75380492403;12316.322602256962;12430 22:22:56 INFO - PROCESS | 3430 | __end_tp_report 22:22:56 INFO - PROCESS | 3430 | __start_cc_report 22:22:56 INFO - PROCESS | 3430 | _x_x_mozilla_cycle_collect,360 22:22:56 INFO - PROCESS | 3430 | __end_cc_report 22:22:56 INFO - PROCESS | 3430 | __startTimestamp1469596976682__endTimestamp 22:22:56 INFO - PROCESS | 3430 | 22:22:56 INFO - PROCESS | 3430 | ------- Summary: start ------- 22:22:56 INFO - PROCESS | 3430 | Number of tests: 6 22:22:56 INFO - PROCESS | 3430 | 22:22:56 INFO - PROCESS | 3430 | [#0] dojo.html Cycles:165 Average:5454.32 Median:5703.49 stddev:624.21 (10.9%) stddev-sans-first:598.55 22:22:56 INFO - PROCESS | 3430 | Values: 3115.6 3125.2 3130.5 3136.0 3139.8 5390.0 5407.8 5424.5 5485.6 5486.9 5302.4 5351.6 5446.2 5495.7 5562.7 4877.5 4925.2 5022.3 5090.4 5124.6 4937.2 5162.7 5166.0 5180.4 5187.7 4829.0 4980.3 4991.5 5035.8 5080.8 4968.9 5058.8 5066.2 5070.8 5092.1 5027.8 5038.3 5040.4 5051.6 5055.2 5312.5 5375.7 5486.7 5513.5 5569.5 5758.6 5799.1 5808.3 5817.2 5819.4 5807.3 5829.1 5862.8 5883.3 5892.4 5320.1 5419.0 5475.3 5526.8 5534.2 6087.2 6087.4 6089.6 6092.8 6100.1 5819.7 5827.9 5829.9 5834.6 5855.9 5688.2 5775.9 5816.9 5824.5 5920.2 5662.8 5811.3 5817.9 5828.1 5883.6 5835.0 5852.7 5909.6 5920.3 5946.0 4381.2 4387.5 4424.3 4461.8 4487.6 3915.3 3954.1 3999.5 4003.0 4014.9 5527.6 5566.7 5580.7 5584.4 5634.3 5723.8 5842.9 5851.1 5853.9 5858.2 5584.3 5602.8 5618.5 5673.7 5709.4 4976.8 5046.3 5051.2 5105.9 5156.7 5905.8 6025.8 6091.5 6106.1 6118.8 5837.4 5853.9 5916.8 5925.5 5991.8 6017.2 6056.3 6062.5 6065.2 6073.0 5671.4 5775.6 5822.2 5826.4 5858.6 5785.0 5832.4 5839.0 5872.4 5925.0 5699.3 5703.8 5742.2 5746.3 5764.1 5644.5 5708.1 5712.9 5714.7 5719.1 5615.0 5703.5 5721.2 5722.5 5731.0 5794.6 5815.4 5816.6 5820.8 5867.1 5539.4 5591.1 5737.8 5752.3 5753.9 22:22:56 INFO - PROCESS | 3430 | 22:22:56 INFO - PROCESS | 3430 | [#1] ext.html Cycles:180 Average:60221.42 Median:41463.19 stddev:121572.55 (293.2%) stddev-sans-first:121831.05 22:22:56 INFO - PROCESS | 3430 | Values: 552.6 588.0 593.3 606.3 637.4 39130.4 42530.0 42632.4 42636.4 42644.0 42281.0 42774.0 42981.1 43041.4 43131.8 8871.5 8965.7 8992.8 9034.8 9090.2 8869.0 9009.1 9021.0 9024.5 9033.3 9036.3 9046.7 9060.9 9090.5 9095.2 8899.2 8929.1 8947.4 9008.0 9027.1 8886.3 8903.2 8932.7 8969.5 8991.8 40265.6 40658.4 40752.2 40988.6 41035.2 3496.7 3622.7 3633.7 3748.9 3998.1 32679.8 33031.5 33146.2 33181.7 34360.1 4358.7 4476.7 4510.3 4515.2 4677.8 341480.0 342097.0 343680.0 343743.0 344804.0 55890.0 56203.7 56298.4 56372.0 56405.2 34743.7 35655.6 35780.6 37519.6 37608.8 17149.4 17229.5 17250.6 17254.7 17256.7 13105.1 13249.9 13271.4 13272.0 13285.7 19331.9 19353.6 19361.4 19378.7 19383.8 7176.2 7372.0 7417.9 7481.3 7601.2 952.6 1003.2 1054.2 1059.5 1090.0 31891.6 32803.7 32853.2 32965.2 32977.8 1110.2 1116.1 1134.6 1139.9 1231.4 16751.7 17152.1 17192.4 17202.5 17203.3 542816.0 545068.0 555748.0 556849.0 560010.0 56727.4 57391.1 57496.4 57522.7 57611.1 1341.7 1342.2 1347.7 1348.6 1359.5 30173.5 31931.2 32159.7 32443.4 32529.2 50495.5 50600.0 50657.0 50687.0 50719.7 453065.0 453318.0 454459.0 455232.0 455583.0 26987.0 27027.9 27503.6 27609.3 27633.6 46532.8 47094.5 47634.8 47674.8 48894.8 41109.8 41437.8 42067.0 42345.4 43305.1 41488.6 41510.4 41925.0 42143.0 42389.8 34785.0 39082.8 39505.0 39755.8 41571.0 35176.6 37370.0 37860.4 37983.8 38087.0 27725.6 27776.6 27827.2 27859.0 28159.4 22:22:56 INFO - PROCESS | 3430 | 22:22:56 INFO - PROCESS | 3430 | [#2] jquery.html Cycles:180 Average:29980.92 Median:13249.71 stddev:84682.59 (639.1%) stddev-sans-first:84890.98 22:22:56 INFO - PROCESS | 3430 | Values: 380.0 389.8 399.6 403.5 416.0 14203.4 18345.4 20356.5 20418.0 20648.4 19168.1 19595.5 19874.6 20415.9 20633.8 5764.1 5796.9 5840.1 5854.0 5906.4 5761.8 5822.4 5835.2 5875.9 5877.8 5727.5 5734.8 5741.3 5742.3 5766.5 5675.1 5794.8 5804.0 5807.3 5831.8 4898.2 5017.7 5028.7 5039.5 5052.0 23185.2 23745.3 23873.8 24094.8 24209.3 2404.7 2545.0 2964.7 3082.9 3185.4 14388.2 14497.0 14968.8 15163.5 15217.3 1840.7 1878.4 1880.7 1916.4 1919.6 53659.7 70514.0 84094.2 85690.6 87050.0 10374.7 10637.2 10791.5 11071.5 11188.8 14897.7 15027.8 15095.8 15125.6 15186.3 8019.8 8158.5 8226.6 8272.3 8301.7 7729.6 7756.2 7990.7 8105.5 8197.4 6349.7 6516.6 6542.8 6641.1 6838.7 1900.3 1974.3 1982.7 1998.5 2041.8 931.7 985.2 1000.3 1019.7 1089.7 20360.1 20742.4 20754.5 20826.9 21092.5 988.0 1012.6 1022.6 1051.5 1058.0 11051.5 11549.8 11835.8 11864.8 11927.6 408715.0 416414.0 551747.0 577599.0 583253.0 89180.6 91664.5 97556.0 102375.0 103699.5 989.6 1028.5 1042.7 1057.9 1058.4 15353.9 17825.3 17881.3 18133.5 18200.5 43887.8 44236.6 44425.6 44935.6 46626.3 60026.7 63289.4 63354.0 64106.2 64330.4 8024.6 8238.3 8336.9 8359.8 8463.1 12311.8 13494.6 13607.4 13607.5 13776.8 11679.2 11766.9 11818.4 11831.9 12453.8 10889.7 11026.4 11769.2 11870.6 12057.2 11683.5 11750.2 11819.6 11947.5 12159.9 8258.8 8329.4 8381.1 8423.9 8547.3 9055.5 11510.2 11653.8 11679.2 12266.3 22:22:56 INFO - PROCESS | 3430 | 22:22:56 INFO - PROCESS | 3430 | [#3] mootools.html Cycles:180 Average:6071.52 Median:4352.93 stddev:3521.53 (80.9%) stddev-sans-first:3514.51 22:22:56 INFO - PROCESS | 3430 | Values: 1480.4 1505.4 1641.4 1685.7 1777.8 7820.8 8766.2 8866.6 9364.6 9378.6 8888.9 9232.2 9242.5 9249.3 9252.1 2402.5 2429.8 2435.3 2435.8 2436.4 2341.0 2361.4 2396.2 2415.2 2417.0 2355.1 2387.3 2390.0 2401.0 2418.5 2344.8 2348.8 2353.0 2353.1 2383.4 2210.7 2229.1 2256.0 2268.2 2277.6 9146.4 9325.1 9353.3 9371.0 9418.9 5502.8 5595.9 5620.7 5642.1 5643.7 3439.4 3478.0 3485.5 3487.3 3522.4 1191.1 1202.6 1239.4 1271.0 1271.1 13011.5 13049.7 13067.0 13125.1 13150.3 4649.0 4721.9 4761.4 4807.8 4936.5 10878.9 10879.4 10888.0 10895.7 10904.8 5485.6 5489.1 5512.9 5527.0 5533.5 4332.9 4906.3 5032.1 5075.8 5078.8 4134.7 4186.4 4199.7 4260.2 4279.8 1339.6 1346.2 1357.0 1368.0 1368.8 963.4 978.6 989.1 994.7 1017.0 5979.9 6080.4 6152.8 6217.6 6221.4 389.7 392.9 395.9 1028.6 1040.4 4115.9 4245.2 4333.7 4352.1 4353.8 11367.5 11618.4 11643.7 11712.5 11748.6 11580.5 11628.0 11632.1 11633.9 11638.5 8158.5 8462.9 8478.1 8569.4 8787.4 6065.4 6099.5 6170.3 6250.1 6344.7 10452.5 10712.6 10821.5 10835.3 10915.9 10953.7 10957.5 10973.2 10989.9 10999.2 7212.4 7965.1 7996.7 8261.8 8319.0 9130.0 9182.8 9193.7 9203.3 9210.9 7953.0 8016.6 8092.9 8227.6 8268.6 7535.7 7731.1 8051.2 8108.6 8222.8 7069.0 7093.4 7149.1 7166.8 7201.9 5086.0 5116.3 5198.5 5272.8 5346.9 5484.6 5530.6 5578.3 5581.9 5720.3 22:22:56 INFO - PROCESS | 3430 | 22:22:56 INFO - PROCESS | 3430 | [#4] prototype.html Cycles:180 Average:4860.81 Median:5479.44 stddev:1459.58 (26.6%) stddev-sans-first:1423.31 22:22:56 INFO - PROCESS | 3430 | Values: 318.7 323.9 327.6 329.7 332.1 5441.5 5472.9 5546.1 5666.9 5729.6 5481.6 5535.4 5540.9 5550.9 5557.7 4342.8 4355.6 4363.0 4386.9 4398.7 4090.7 4284.0 4352.6 4405.3 4412.8 4261.4 4291.7 4295.8 4297.4 4305.9 4263.5 4295.0 4306.9 4340.9 4345.3 3490.2 3788.4 3789.7 3799.0 3800.9 5428.5 5434.4 5437.8 5456.8 5477.2 4805.0 4860.6 4877.1 4888.3 4890.8 5552.6 5808.9 5823.0 5826.8 5852.5 4094.1 4101.3 4101.8 4109.9 4121.6 6032.0 6037.9 6072.8 6111.2 6162.3 4055.8 4131.6 4141.3 4166.4 4184.0 4155.2 4166.9 4170.4 4174.1 4189.5 4815.3 4824.0 4840.4 4878.6 4898.9 5662.8 5843.4 5940.0 5942.6 5960.5 1405.8 1416.3 1418.8 1427.1 1428.5 868.9 903.1 909.4 921.2 929.9 5015.0 5031.8 5054.3 5057.4 5089.3 5241.7 5397.3 5418.3 5436.2 5459.8 5029.2 5049.5 5052.5 5309.5 5690.9 3196.2 3198.0 3213.6 3218.7 3224.9 6072.0 6244.8 6262.8 6265.6 6290.6 6046.2 6081.2 6101.3 6101.7 6106.5 6190.8 6193.3 6194.0 6197.3 6221.5 5838.4 6005.3 6015.2 6018.6 6038.5 5965.1 5977.0 5991.6 5999.5 6013.4 5992.0 6098.5 6246.3 6257.9 6262.6 4050.9 4107.5 4148.6 4155.7 4156.3 6058.2 6062.9 6065.1 6076.9 6086.1 5769.9 5845.9 5900.4 5908.8 5937.9 5840.2 5852.2 5861.3 5867.0 5881.5 5749.5 5786.4 5796.7 5798.6 5806.6 5732.2 5851.5 5910.0 5917.9 5960.3 5626.7 5804.9 5810.0 5848.0 5883.1 22:22:56 INFO - PROCESS | 3430 | 22:22:56 INFO - PROCESS | 3430 | [#5] yui.html Cycles:180 Average:22036.84 Median:3297.82 stddev:32301.62 (979.5%) stddev-sans-first:32352.78 22:22:56 INFO - PROCESS | 3430 | Values: 773.4 790.0 807.7 811.3 846.9 15713.4 16396.2 16536.1 16620.9 16648.8 14770.0 14839.4 14982.0 14997.3 15024.2 648.8 792.5 795.9 803.4 808.4 807.6 809.0 809.3 814.1 814.2 851.7 859.4 859.7 859.9 862.2 851.5 857.3 857.4 859.2 859.7 629.6 629.6 630.5 633.2 633.7 15121.8 15156.3 15156.7 15196.5 15235.5 14992.0 15110.5 15162.7 15207.5 15223.7 12282.9 12431.7 12453.6 12720.9 12826.9 3857.7 3891.4 3942.1 3959.8 4065.8 76724.0 76893.2 78311.6 78417.6 81610.6 10343.7 10347.8 10460.7 10485.6 10626.3 20970.6 21034.9 21064.8 21098.5 21109.9 8311.8 8325.1 8410.3 8434.8 8526.5 4731.2 4898.0 4919.1 4924.4 4932.6 3193.2 3265.2 3292.3 3296.5 3299.1 608.9 619.3 622.5 624.3 641.7 615.4 626.5 668.5 677.1 709.6 9525.9 9558.6 9671.4 9732.1 9783.4 675.5 705.4 717.3 720.0 730.7 4929.3 5106.8 5464.2 5539.7 5607.6 106492.0 128728.0 129489.0 129641.0 131344.0 64333.0 100097.5 103413.5 107062.5 107471.0 99172.5 99726.5 100442.5 100480.5 101424.4 10474.9 10593.4 10716.6 10732.6 10752.8 55704.7 56531.3 57426.0 57558.1 58437.7 76895.6 77102.6 83825.0 85331.0 85931.1 13230.5 13383.5 13502.5 13858.7 13954.2 15211.9 15645.8 15849.3 15919.5 16677.1 13905.1 14101.8 14248.5 14540.5 14919.0 13743.7 13882.8 14155.6 14242.6 14460.6 13182.5 14299.4 14509.3 14634.6 14748.2 11013.2 11173.9 11193.3 11350.3 12260.4 11367.5 11745.5 12309.8 12316.3 12430.0 22:22:56 INFO - PROCESS | 3430 | -------- Summary: end -------- 22:22:56 INFO - PROCESS | 3430 | 22:22:57 INFO - TEST-INFO | 3430: exit 0 22:22:57 INFO - TEST-OK | dromaeo_css | took 1113162ms 22:22:57 INFO - TEST-START | kraken 22:22:57 INFO - operating with platform_type : linux_ 22:22:57 INFO - Initialising browser for kraken test... 22:22:57 INFO - TEST-INFO | started process 3678 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpCqcTqJ/profile http://localhost:46961/getInfo.html) 22:23:00 INFO - PROCESS | 3678 | JavaScript error: , line 0: uncaught exception: undefined 22:23:01 INFO - PROCESS | 3678 | __metrics Screen width/height:1600/1200 22:23:01 INFO - PROCESS | 3678 | colorDepth:24 22:23:01 INFO - PROCESS | 3678 | Browser inner width/height: 1024/697 22:23:01 INFO - PROCESS | 3678 | __metrics 22:23:02 ERROR - PROCESS | 3678 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2967: TypeError: this.tabs is undefined 22:23:03 INFO - TEST-INFO | 3678: exit 0 22:23:03 INFO - Browser initialized. 22:23:03 INFO - Running cycle 1/1 for kraken test... 22:23:03 INFO - TEST-INFO | started process 3799 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpCqcTqJ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:23:15 INFO - PROCESS | 3799 | RSS: Main: 167534592 22:23:15 INFO - PROCESS | 3799 | 22:23:17 INFO - PROCESS | 3799 | Cycle 1(1): loaded http://localhost:46961/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:46961/tests/kraken/driver.html?testName=audio-beat-detection) 22:23:18 INFO - PROCESS | 3799 | RSS: Main: 173019136 22:23:18 INFO - PROCESS | 3799 | 22:23:21 INFO - PROCESS | 3799 | Cycle 1(1): loaded http://localhost:46961/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:46961/tests/kraken/driver.html?testName=audio-dft) 22:23:21 INFO - PROCESS | 3799 | RSS: Main: 171868160 22:23:21 INFO - PROCESS | 3799 | 22:23:24 INFO - PROCESS | 3799 | Cycle 1(1): loaded http://localhost:46961/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:46961/tests/kraken/driver.html?testName=audio-fft) 22:23:25 INFO - PROCESS | 3799 | RSS: Main: 167886848 22:23:25 INFO - PROCESS | 3799 | 22:23:27 INFO - PROCESS | 3799 | Cycle 1(1): loaded http://localhost:46961/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:46961/tests/kraken/driver.html?testName=audio-oscillator) 22:23:28 INFO - PROCESS | 3799 | RSS: Main: 169074688 22:23:28 INFO - PROCESS | 3799 | 22:23:30 INFO - PROCESS | 3799 | Cycle 1(1): loaded http://localhost:46961/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:46961/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:23:30 INFO - PROCESS | 3799 | RSS: Main: 169398272 22:23:30 INFO - PROCESS | 3799 | 22:23:33 INFO - PROCESS | 3799 | Cycle 1(1): loaded http://localhost:46961/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:46961/tests/kraken/driver.html?testName=imaging-darkroom) 22:23:34 INFO - PROCESS | 3799 | RSS: Main: 168570880 22:23:34 INFO - PROCESS | 3799 | 22:23:38 INFO - PROCESS | 3799 | Cycle 1(1): loaded http://localhost:46961/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:46961/tests/kraken/driver.html?testName=imaging-desaturate) 22:23:38 INFO - PROCESS | 3799 | RSS: Main: 170180608 22:23:38 INFO - PROCESS | 3799 | 22:23:41 INFO - PROCESS | 3799 | Cycle 1(1): loaded http://localhost:46961/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:46961/tests/kraken/driver.html?testName=json-parse-financial) 22:23:42 INFO - PROCESS | 3799 | RSS: Main: 170229760 22:23:42 INFO - PROCESS | 3799 | 22:23:43 INFO - PROCESS | 3799 | Cycle 1(1): loaded http://localhost:46961/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:46961/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:23:44 INFO - PROCESS | 3799 | RSS: Main: 170196992 22:23:44 INFO - PROCESS | 3799 | 22:23:46 INFO - PROCESS | 3799 | Cycle 1(1): loaded http://localhost:46961/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:46961/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:23:46 INFO - PROCESS | 3799 | RSS: Main: 170471424 22:23:46 INFO - PROCESS | 3799 | 22:23:48 INFO - PROCESS | 3799 | Cycle 1(1): loaded http://localhost:46961/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:46961/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:23:49 INFO - PROCESS | 3799 | RSS: Main: 170577920 22:23:49 INFO - PROCESS | 3799 | 22:23:52 INFO - PROCESS | 3799 | Cycle 1(1): loaded http://localhost:46961/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:46961/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:23:52 INFO - PROCESS | 3799 | RSS: Main: 170762240 22:23:52 INFO - PROCESS | 3799 | 22:23:55 INFO - PROCESS | 3799 | Cycle 1(1): loaded http://localhost:46961/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:46961/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:23:56 INFO - PROCESS | 3799 | RSS: Main: 170373120 22:23:56 INFO - PROCESS | 3799 | 22:23:57 INFO - PROCESS | 3799 | Cycle 1(1): loaded http://localhost:46961/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:46961/tests/kraken/driver.html?testName=ai-astar) 22:23:58 INFO - PROCESS | 3799 | RSS: Main: 170905600 22:23:58 INFO - PROCESS | 3799 | 22:23:58 INFO - PROCESS | 3799 | __start_tp_report 22:23:58 INFO - PROCESS | 3799 | _x_x_mozilla_page_load 22:23:58 INFO - PROCESS | 3799 | _x_x_mozilla_page_load_details 22:23:58 INFO - PROCESS | 3799 | |i|pagename|runs| 22:23:58 INFO - PROCESS | 3799 | |0;ai-astar;101;113;106;112;113;112;114;111;111;111 22:23:58 INFO - PROCESS | 3799 | |1;audio-beat-detection;148;121;148;192;125;146;148;119;186;118 22:23:58 INFO - PROCESS | 3799 | |2;audio-dft;161;162;163;162;162;163;176;165;163;162 22:23:58 INFO - PROCESS | 3799 | |3;audio-fft;75;76;75;162;77;75;75;74;155;76 22:23:58 INFO - PROCESS | 3799 | |4;audio-oscillator;85;84;149;85;83;84;148;84;84;145 22:23:58 INFO - PROCESS | 3799 | |5;imaging-gaussian-blur;117;118;117;117;117;117;117;118;118;120 22:23:58 INFO - PROCESS | 3799 | |6;imaging-darkroom;147;147;147;149;148;147;147;148;146;147 22:23:58 INFO - PROCESS | 3799 | |7;imaging-desaturate;96;97;95;95;95;96;96;95;96;96 22:23:58 INFO - PROCESS | 3799 | |8;json-parse-financial;53;53;53;53;53;53;57;54;53;55 22:23:58 INFO - PROCESS | 3799 | |9;json-stringify-tinderbox;62;63;63;62;67;62;62;63;63;63 22:23:58 INFO - PROCESS | 3799 | |10;stanford-crypto-aes;82;77;78;77;81;76;80;83;81;76 22:23:58 INFO - PROCESS | 3799 | |11;stanford-crypto-ccm;123;130;132;126;122;121;127;126;178;134 22:23:58 INFO - PROCESS | 3799 | |12;stanford-crypto-pbkdf2;164;160;157;160;155;156;163;158;156;160 22:23:58 INFO - PROCESS | 3799 | |13;stanford-crypto-sha256-iterative;67;66;65;64;65;63;67;76;63;63 22:23:58 INFO - PROCESS | 3799 | __end_tp_report 22:23:58 INFO - PROCESS | 3799 | __start_cc_report 22:23:58 INFO - PROCESS | 3799 | _x_x_mozilla_cycle_collect,945 22:23:58 INFO - PROCESS | 3799 | __end_cc_report 22:23:58 INFO - PROCESS | 3799 | __startTimestamp1469597038125__endTimestamp 22:23:58 INFO - PROCESS | 3799 | 22:23:58 INFO - PROCESS | 3799 | ------- Summary: start ------- 22:23:58 INFO - PROCESS | 3799 | Number of tests: 14 22:23:58 INFO - PROCESS | 3799 | 22:23:58 INFO - PROCESS | 3799 | [#0] ai-astar Cycles:10 Average:110.40 Median:112.00 stddev:3.95 (3.5%) stddev-sans-first:2.30 22:23:58 INFO - PROCESS | 3799 | Values: 101.0 113.0 106.0 112.0 113.0 112.0 114.0 111.0 111.0 111.0 22:23:58 INFO - PROCESS | 3799 | 22:23:58 INFO - PROCESS | 3799 | [#1] audio-beat-detection Cycles:10 Average:145.10 Median:148.00 stddev:26.46 (17.9%) stddev-sans-first:28.04 22:23:58 INFO - PROCESS | 3799 | Values: 148.0 121.0 148.0 192.0 125.0 146.0 148.0 119.0 186.0 118.0 22:23:58 INFO - PROCESS | 3799 | 22:23:58 INFO - PROCESS | 3799 | [#2] audio-dft Cycles:10 Average:163.90 Median:163.00 stddev:4.38 (2.7%) stddev-sans-first:4.52 22:23:58 INFO - PROCESS | 3799 | Values: 161.0 162.0 163.0 162.0 162.0 163.0 176.0 165.0 163.0 162.0 22:23:58 INFO - PROCESS | 3799 | 22:23:58 INFO - PROCESS | 3799 | [#3] audio-fft Cycles:10 Average:92.00 Median:75.00 stddev:35.10 (46.8%) stddev-sans-first:36.68 22:23:58 INFO - PROCESS | 3799 | Values: 75.0 76.0 75.0 162.0 77.0 75.0 75.0 74.0 155.0 76.0 22:23:58 INFO - PROCESS | 3799 | 22:23:58 INFO - PROCESS | 3799 | [#4] audio-oscillator Cycles:10 Average:103.10 Median:84.00 stddev:30.54 (36.4%) stddev-sans-first:31.69 22:23:58 INFO - PROCESS | 3799 | Values: 85.0 84.0 149.0 85.0 83.0 84.0 148.0 84.0 84.0 145.0 22:23:58 INFO - PROCESS | 3799 | 22:23:58 INFO - PROCESS | 3799 | [#5] imaging-gaussian-blur Cycles:10 Average:117.60 Median:117.50 stddev:0.97 (0.8%) stddev-sans-first:1.00 22:23:58 INFO - PROCESS | 3799 | Values: 117.0 118.0 117.0 117.0 117.0 117.0 117.0 118.0 118.0 120.0 22:23:58 INFO - PROCESS | 3799 | 22:23:58 INFO - PROCESS | 3799 | [#6] imaging-darkroom Cycles:10 Average:147.30 Median:147.00 stddev:0.82 (0.6%) stddev-sans-first:0.87 22:23:58 INFO - PROCESS | 3799 | Values: 147.0 147.0 147.0 149.0 148.0 147.0 147.0 148.0 146.0 147.0 22:23:58 INFO - PROCESS | 3799 | 22:23:58 INFO - PROCESS | 3799 | [#7] imaging-desaturate Cycles:10 Average:95.70 Median:96.00 stddev:0.67 (0.7%) stddev-sans-first:0.71 22:23:58 INFO - PROCESS | 3799 | Values: 96.0 97.0 95.0 95.0 95.0 96.0 96.0 95.0 96.0 96.0 22:23:58 INFO - PROCESS | 3799 | 22:23:58 INFO - PROCESS | 3799 | [#8] json-parse-financial Cycles:10 Average:53.70 Median:53.00 stddev:1.34 (2.5%) stddev-sans-first:1.39 22:23:58 INFO - PROCESS | 3799 | Values: 53.0 53.0 53.0 53.0 53.0 53.0 57.0 54.0 53.0 55.0 22:23:58 INFO - PROCESS | 3799 | 22:23:58 INFO - PROCESS | 3799 | [#9] json-stringify-tinderbox Cycles:10 Average:63.00 Median:63.00 stddev:1.49 (2.4%) stddev-sans-first:1.54 22:23:58 INFO - PROCESS | 3799 | Values: 62.0 63.0 63.0 62.0 67.0 62.0 62.0 63.0 63.0 63.0 22:23:58 INFO - PROCESS | 3799 | 22:23:58 INFO - PROCESS | 3799 | [#10] stanford-crypto-aes Cycles:10 Average:79.10 Median:80.50 stddev:2.60 (3.2%) stddev-sans-first:2.54 22:23:58 INFO - PROCESS | 3799 | Values: 82.0 77.0 78.0 77.0 81.0 76.0 80.0 83.0 81.0 76.0 22:23:58 INFO - PROCESS | 3799 | 22:23:58 INFO - PROCESS | 3799 | [#11] stanford-crypto-ccm Cycles:10 Average:131.90 Median:128.50 stddev:16.74 (13.0%) stddev-sans-first:17.45 22:23:58 INFO - PROCESS | 3799 | Values: 123.0 130.0 132.0 126.0 122.0 121.0 127.0 126.0 178.0 134.0 22:23:58 INFO - PROCESS | 3799 | 22:23:58 INFO - PROCESS | 3799 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:158.90 Median:160.00 stddev:3.03 (1.9%) stddev-sans-first:2.60 22:23:58 INFO - PROCESS | 3799 | Values: 164.0 160.0 157.0 160.0 155.0 156.0 163.0 158.0 156.0 160.0 22:23:58 INFO - PROCESS | 3799 | 22:23:58 INFO - PROCESS | 3799 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.90 Median:65.50 stddev:3.87 (5.9%) stddev-sans-first:4.09 22:23:58 INFO - PROCESS | 3799 | Values: 67.0 66.0 65.0 64.0 65.0 63.0 67.0 76.0 63.0 63.0 22:23:58 INFO - PROCESS | 3799 | -------- Summary: end -------- 22:23:58 INFO - PROCESS | 3799 | 22:23:59 INFO - TEST-INFO | 3799: exit 0 22:23:59 INFO - TEST-OK | kraken | took 61688ms 22:23:59 INFO - SUITE-END | took 1174s 22:23:59 INFO - Completed test suite (00:19:35) 22:23:59 INFO - javascript benchmark 22:23:59 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7428.984564321458, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3115.6105486814145, 3125.2186953261685, 3130.530420436934, 3135.9686403135975, 3139.8430078496085, 5389.973050134754, 5407.810726624569, 5424.5117939385445, 5485.561155107592, 5486.862828429291, 5302.416734159249, 5351.598630102739, 5446.210299506579, 5495.670259784406, 5562.694051827158, 4877.463479017305, 4925.187344088239, 5022.296878437021, 5090.44005159434, 5124.615653825959, 4937.23472861707, 5162.716050617215, 5166.044281807859, 5180.378354597435, 5187.6628019178825, 4828.951710482886, 4980.252962055721, 4991.5258050485145, 5035.77339019745, 5080.822171224008, 4968.925466118011, 5058.7976480940715, 5066.214736715815, 5070.8225212117395, 5092.134337162691, 5027.824026159104, 5038.3198268234055, 5040.445550989388, 5051.595872330205, 5055.216441451581, 5312.4687531246955, 5375.731213439312, 5486.725663716838, 5513.5037846594, 5569.470900264469, 5758.596898217126, 5799.130130480462, 5808.3030036395485, 5817.185594016841, 5819.418058194189, 5807.274090738658, 5829.09649004405, 5862.824115276549, 5883.3234178069215, 5892.351841297454, 5320.122179840322, 5418.972905135489, 5475.342958844964, 5526.751296191681, 5534.225208470817, 6087.178230938766, 6087.391260873966, 6089.573729838958, 6092.786752463642, 6100.054491553817, 5819.73811178498, 5827.912581311284, 5829.854253643608, 5834.620749651259, 5855.882882342329, 5688.23208866806, 5775.942240577624, 5816.88366232673, 5824.534037277008, 5920.2303700519515, 5662.801801936913, 5811.27359080115, 5817.94182058174, 5828.125781132864, 5883.588148829626, 5835.0, 5852.707364631751, 5909.645421274738, 5920.31916329619, 5946.048632218824, 4381.211381951216, 4387.517373088957, 4424.269995450714, 4461.754603496774, 4487.618552423015, 3915.295246855593, 3954.0510277533185, 3999.5000624921886, 4002.9799851000557, 4014.879553613396, 5527.585431092684, 5566.721663916869, 5580.748866301026, 5584.441555844464, 5634.32388113429, 5723.77104915807, 5842.853928651818, 5851.063829787214, 5853.9414605854245, 5858.150568167677, 5584.301962254718, 5602.831915042555, 5618.522425593788, 5673.744681489343, 5709.429057094258, 4976.800927962913, 5046.26829109777, 5051.191809310566, 5105.8723531911255, 5156.690598564098, 5905.793297234576, 6025.789097381658, 6091.51267898567, 6106.114613381035, 6118.816435506942, 5837.357890632027, 5853.9121913172185, 5916.792912248223, 5925.496332811586, 5991.760329586942, 6017.217761690952, 6056.273247210187, 6062.454379105901, 6065.211522502046, 6072.969635151972, 5671.44035390271, 5775.566832487496, 5822.155787410887, 5826.388229235869, 5858.648481091148, 5785.0, 5832.358440571618, 5838.970805145947, 5872.354041055566, 5925.022371308858, 5699.344575373885, 5703.800366987136, 5742.224799651994, 5746.281714785652, 5764.13537969308, 5644.491995720405, 5708.115242137475, 5712.914306285492, 5714.685692286964, 5719.084946408721, 5615.0, 5703.486686198262, 5721.170430287501, 5722.513586345207, 5731.0, 5794.594378393472, 5815.3603103657715, 5816.621919575214, 5820.82537523875, 5867.090600956859, 5539.418361072029, 5591.077472217201, 5737.799177028784, 5752.30972283312, 5753.942460575508], "value": 5411.418234851947, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [552.579264800027, 587.9794207202748, 593.2999061107894, 606.2724730323612, 637.3690046853617, 39130.413043804365, 42530.0, 42632.36051459231, 42636.3604545932, 42644.0, 42281.0, 42774.0, 42981.14037719244, 43041.35437968425, 43131.78434107833, 8871.467711937272, 8965.731028069169, 8992.775180620503, 9034.774130646721, 9090.181883630472, 8868.955655221715, 9009.144131307516, 9021.0, 9024.548772561378, 9033.322500812432, 9036.322275829307, 9046.683366082189, 9060.864087038699, 9090.500022498793, 9095.181433670969, 8899.15458031486, 8929.107089291052, 8947.418417802855, 9008.0, 9027.14242146995, 8886.333524985652, 8903.198712115876, 8932.687355942577, 8969.461832290084, 8991.775205619912, 40265.59734402648, 40658.39012414816, 40752.184956301004, 40988.59011409878, 41035.1792964139, 3496.6852983231634, 3622.6739593436455, 3633.6729694327637, 3748.850045998157, 3998.100427403811, 32679.83660081708, 33031.50452743211, 33146.17134571655, 33181.6681833181, 34360.140996475035, 4358.717930945708, 4476.7090139140855, 4510.25580779171, 4515.187266292066, 4677.7661116944355, 341480.0, 342097.0, 343679.9999999975, 343743.0, 344804.0, 55890.0, 56203.71898140483, 56298.43701562932, 56372.0, 56405.15392269119, 34743.65256347455, 35655.64344356575, 35780.64219357825, 37519.62480375216, 37608.811955940044, 17149.399771007957, 17229.483115506555, 17250.568735781704, 17254.654906902044, 17256.65486690259, 13105.14816536922, 13249.933750331187, 13271.402786874422, 13272.0, 13285.73785490377, 19331.92456124712, 19353.612927741367, 19361.419157425298, 19378.70931936022, 19383.806161938483, 7176.246494118042, 7372.0, 7417.888731669029, 7481.251874812584, 7601.163871974079, 952.6046690623452, 1003.172382784206, 1054.2409465185056, 1059.5285098131412, 1089.9782004359868, 31891.564879580474, 32803.67196328006, 32853.17867053343, 32965.17587060295, 32977.83511082429, 1110.1784679337231, 1116.0569318925495, 1134.5575225661999, 1139.880312567185, 1231.378154032208, 16751.6649667006, 17152.05663688484, 17192.373315558318, 17202.483925482255, 17203.311867525408, 542816.0, 545068.0, 555748.0, 556849.0, 560010.0, 56727.43272567304, 57391.13913291304, 57496.425035749104, 57522.7123864378, 57611.13583296254, 1341.704824938512, 1342.2416334770824, 1347.723716638087, 1348.629126990066, 1359.4766015084265, 30173.49132543408, 31931.201719956258, 32159.67840321567, 32443.351132976735, 32529.186770329983, 50495.49504504908, 50600.0, 50657.0, 50687.0, 50719.74640126776, 453065.0, 453318.0, 454459.0, 455232.0, 455583.0, 26987.0, 27027.91888324445, 27503.58744618792, 27609.309767255978, 27633.585496217976, 46532.7673361631, 47094.52905471039, 47634.76182619065, 47674.76162619304, 48894.75552622214, 41109.794451027556, 41437.79281103575, 42067.0, 42345.364819528346, 43305.13389732251, 41488.585114148475, 41510.37734433926, 41925.0, 42143.0, 42389.78805105955, 34785.0, 39082.80458597689, 39505.012374689715, 39755.80122099371, 41571.0, 35176.64823351836, 37370.0, 37860.41121345926, 37983.81008094942, 38087.0, 27725.584116237867, 27776.583351250152, 27827.16518504448, 27859.0, 28159.43681126325], "value": 19948.424227752235, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [379.9943000854986, 389.7875657766519, 399.57644896409806, 403.541979852867, 415.98324210027073, 14203.360848761804, 18345.357912473068, 20356.491087722814, 20417.979101044944, 20648.38054858353, 19168.13743381548, 19595.510112247164, 19874.602507949832, 20415.897920510375, 20633.793662063374, 5764.106563482667, 5796.855078623026, 5840.123981402781, 5854.0, 5906.37983011784, 5761.827145185651, 5822.417758224186, 5835.241418615596, 5875.9118613220835, 5877.794277200299, 5727.513161381266, 5734.827955161351, 5741.339745929209, 5742.253507044078, 5766.452187042245, 5675.091985282355, 5794.797182098607, 5803.9709801451145, 5807.303123625171, 5831.79588714393, 4898.240772680205, 5017.698938063728, 5028.723420211887, 5039.47085556015, 5052.0, 23185.188518401945, 23745.287641370785, 23873.761262387503, 24094.759052409427, 24209.273721788373, 2404.6513255577843, 2544.9745502544924, 2964.7479964202853, 3082.8766849326, 3185.3629274145264, 14388.208648524327, 14497.0, 14968.775468367983, 15163.545093647097, 15217.31522081509, 1840.6502764474708, 1878.389523404899, 1880.689686201775, 1916.434651777729, 1919.5968846542244, 53659.73170134125, 70514.0, 84094.15905840925, 85690.57154714187, 87050.0, 10374.740631484123, 10637.202209834139, 10791.46042697862, 11071.50178241965, 11188.832167517494, 14897.70204595902, 15027.849721502645, 15095.849041509664, 15125.621859453382, 15186.316615752317, 8019.7995050123045, 8158.469699469515, 8226.588670566447, 8272.33821294307, 8301.6679332826, 7729.5748733819055, 7756.185600511865, 7990.680372785024, 8105.473144245605, 8197.426180167331, 6349.714262858182, 6516.611362091686, 6542.771003014872, 6641.1366522352755, 6838.700646877177, 1900.2588990293796, 1974.3385965701364, 1982.7224188613607, 1998.5003749062735, 2041.7958204179463, 931.6878845586805, 985.1872205430549, 1000.2648053680539, 1019.6533178719271, 1089.6839916424149, 20360.083796229206, 20742.377728668165, 20754.48113797167, 20826.917308269356, 21092.47268818261, 987.9851802222972, 1012.6405126180247, 1022.5909636145601, 1051.5361744301288, 1057.9788404232027, 11051.502682379312, 11549.76900461986, 11835.763284734428, 11864.762704745857, 11927.642170734893, 408715.0, 416414.0, 551747.0, 577599.0, 583253.0, 89180.5540972304, 91664.54167729252, 97556.0, 102375.0, 103699.48150259201, 989.5744829723288, 1028.4651980970004, 1042.6663467690419, 1057.94710264488, 1058.3544038136731, 15353.923230383776, 17825.286988520314, 17881.28474860991, 18133.546661333043, 18200.544986375447, 43887.78056109699, 44236.55763442325, 44425.55574444344, 44935.55064449314, 46626.300605490265, 60026.699866500385, 63289.36710632835, 63354.0, 64106.23043926069, 64330.356696432435, 8024.598770061591, 8238.340932725487, 8336.916630833857, 8359.832803344021, 8463.111373305714, 12311.75376492483, 13494.595162145151, 13607.387667554785, 13607.455701772215, 13776.793348099987, 11679.240849344766, 11766.882331176921, 11818.409079545816, 11831.881681183078, 12453.75092498163, 10889.727756806144, 11026.393548354597, 11769.234999724991, 11870.643880683594, 12057.15599907998, 11683.474243659226, 11750.23623464472, 11819.586314478953, 11947.522099116286, 12159.878401216229, 8258.793530161794, 8329.37529685288, 8381.078081411162, 8423.873641895254, 8547.313361863984, 9055.501947392957, 11510.194286399872, 11653.76692466163, 11679.240849344766, 12266.26402415858], "value": 9535.31725590403, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1480.4300344367418, 1505.3602219056897, 1641.4337053716472, 1685.6965746165686, 1777.777777777777, 7820.8044798880055, 8766.167214114665, 8866.556672166398, 9364.6254149834, 9378.57796399161, 8888.866667, 9232.215261702764, 9242.491662958551, 9249.260059195265, 9252.074792520745, 2402.531506356261, 2429.7813196812285, 2435.281591930385, 2435.841670291434, 2436.378723425524, 2340.953180936389, 2361.4332560185603, 2396.18529699902, 2415.2271273192587, 2417.0452671194957, 2355.1286024170995, 2387.3076807725824, 2389.95220095598, 2400.987995060031, 2418.504206637635, 2344.812415006795, 2348.8473249238746, 2352.9764702352927, 2353.1293421434093, 2383.4041489627593, 2210.73471183459, 2229.052652622645, 2255.9661605075935, 2268.194790849258, 2277.567262220173, 9146.405483643608, 9325.114114159143, 9353.298502612332, 9370.953145234298, 9418.905810941871, 5502.779888804443, 5595.888082238333, 5620.662760234399, 5642.097264437712, 5643.661380317194, 3439.4324936385465, 3477.9826100869586, 3485.4597537381746, 3487.2851065531504, 3522.418800897849, 1191.142377488207, 1202.6331968749528, 1239.4050855589278, 1271.0, 1271.0975207602614, 13011.479540818356, 13049.739005219843, 13067.0, 13125.146865453715, 13150.276734779583, 4648.96973114331, 4721.858344249678, 4761.428628564525, 4807.831725889577, 4936.506349365035, 10878.891211087946, 10879.401632910265, 10888.0, 10895.727606809893, 10904.836427453594, 5485.61600687948, 5489.149181876815, 5512.889742205133, 5527.0, 5533.47431993968, 4332.935005974913, 4906.2640603909695, 5032.144535428913, 5075.847724568269, 5078.771455284521, 4134.669226461876, 4186.434831297796, 4199.727017743898, 4260.190563792869, 4279.786010699452, 1339.5512503311304, 1346.1721041559567, 1356.9932150339382, 1368.00135900791, 1368.8152099466445, 963.3738070254251, 978.6281213138961, 989.0999190736395, 994.7015895231401, 1016.9694909152737, 5979.9402005980255, 6080.422359875804, 6152.78465253714, 6217.626942383471, 6221.377862213742, 389.7271909663247, 392.8762439831444, 395.87727804380734, 1028.6194108179873, 1040.3965699894043, 4115.938260926088, 4245.150969805989, 4333.71830830995, 4352.064306174188, 4353.804078816461, 11367.54529818798, 11618.419079046014, 11643.650690479299, 11712.472938717778, 11748.647540573797, 11580.536778528933, 11628.0, 11632.127590430751, 11633.941830290794, 11638.534458621729, 8158.510489370538, 8462.915370846213, 8478.067412584734, 8569.400141990001, 8787.428817126865, 6065.3934606539, 6099.542534310033, 6170.321264660974, 6250.124982502544, 6344.71448784815, 10452.477376131315, 10712.625058122929, 10821.51303191374, 10835.349879007284, 10915.890841091805, 10953.726156846142, 10957.452127393757, 10973.177011724312, 10989.890101098887, 10999.23005389647, 7212.350888420067, 7965.084015338311, 7996.720114795954, 8261.752147435587, 8318.958405207935, 9130.04134565861, 9182.816343672956, 9193.678221262224, 9203.263738900738, 9210.861837072316, 7952.960235198787, 8016.559089250147, 8092.878606820785, 8227.588620569068, 8268.627911744106, 7535.660895259617, 7731.110922244014, 8051.2351326623875, 8108.554029528196, 8222.794430139294, 7069.0, 7093.412764778462, 7149.106361704787, 7166.784996450115, 7201.927980720126, 5086.0, 5116.258142569381, 5198.506141916512, 5272.787258930391, 5346.9465305348, 5484.561235101102, 5530.55755539564, 5578.274824272981, 5581.916271255853, 5720.2849643794525], "value": 4790.253061084752, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [318.7402267152271, 323.89959112675075, 327.58724007750243, 329.72962171019776, 332.09007319943356, 5441.530786687592, 5472.890542189164, 5546.112621980479, 5666.914996275054, 5729.570282228834, 5481.643693159952, 5535.391106978229, 5540.944590554093, 5550.944490555094, 5557.7209446426605, 4342.8480003199975, 4355.629771469429, 4362.956370436303, 4386.934195987063, 4398.73607583546, 4090.6727461803134, 4283.978580107095, 4352.586504282088, 4405.3391991201415, 4412.75729834859, 4261.382099595557, 4291.721038132527, 4295.849645262417, 4297.398364228995, 4305.935410968822, 4263.531011588739, 4294.9570504295025, 4306.892327691801, 4340.891477713051, 4345.326474396474, 3490.2147016921244, 3788.355979483467, 3789.730440302502, 3798.9620103798884, 3800.923981520354, 5428.5385742211565, 5434.375046869621, 5437.782488700447, 5456.809011684572, 5477.233187353773, 4804.975975120102, 4860.611151107938, 4877.146499362633, 4888.266759985995, 4890.755462226874, 5552.639078459887, 5808.912866307009, 5823.0, 5826.796062137805, 5852.531797456193, 4094.1197642506704, 4101.282275601758, 4101.794910254505, 4109.8972525686795, 4121.5878412158845, 6032.0, 6037.909431358533, 6072.848178795522, 6111.2055432793895, 6162.291336496361, 4055.8174882130374, 4131.648809851136, 4141.275276826544, 4166.437530933345, 4184.03767133561, 4155.210510003089, 4166.854160104382, 4170.416141740159, 4174.144300418407, 4189.539150693421, 4815.253635686474, 4824.0, 4840.443349014838, 4878.634102442331, 4898.90202195954, 5662.801801936913, 5843.444872737083, 5940.0, 5942.58401911862, 5960.4635582797755, 1405.775075987837, 1416.277698373816, 1418.8155539779762, 1427.1365823676733, 1428.5214453158308, 868.870468391089, 903.1260135219446, 909.4361495872602, 921.15714121578, 929.869818225449, 5015.04112573576, 5031.82388616404, 5054.292399064134, 5057.418396884405, 5089.287499750038, 5241.711705856138, 5397.298351214317, 5418.317932623377, 5436.184572314184, 5459.808906688247, 5029.220470827027, 5049.520295571988, 5052.494750524918, 5309.495597918191, 5690.857728556819, 3196.2329041030453, 3197.952030719541, 3213.614366276062, 3218.726408255278, 3224.8712950467616, 6071.965891426422, 6244.812655620339, 6262.780802671976, 6265.592736472115, 6290.55966082379, 6046.2139921809885, 6081.239845019372, 6101.298350689639, 6101.725422355916, 6106.511479081662, 6190.802265195881, 6193.274959018107, 6193.969030154821, 6197.349278325711, 6221.471174950089, 5838.357780644211, 6005.309389420097, 6015.218021657157, 6018.578699490994, 6038.5169186465855, 5965.105234214902, 5976.970115149396, 5991.562025114028, 5999.490298175616, 6013.368596297326, 5991.970040149946, 6098.451139397298, 6246.3134953561375, 6257.906131408124, 6262.561620686508, 4050.8784736457956, 4107.507099148122, 4148.647364974011, 4155.667546596325, 4156.272652285898, 6058.242719660043, 6062.90905636407, 6065.090236464565, 6076.90884636722, 6086.056661217518, 5769.884602308014, 5845.941540584709, 5900.350961394329, 5908.822735317947, 5937.910931335947, 5840.240768700041, 5852.209951656642, 5861.296644402699, 5867.0, 5881.529477641864, 5749.453801888814, 5786.392428795084, 5796.681182535001, 5798.56510761703, 5806.606414460583, 5732.197492350991, 5851.473367396955, 5909.990603194934, 5917.940820591739, 5960.254968128984, 5626.718664066862, 5804.941950580609, 5809.970950145223, 5847.970760146172, 5883.146943693226], "value": 4383.626759773569, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [773.4276635289883, 790.0361558898146, 807.7132617920641, 811.3144392987925, 846.8896908802988, 15713.371465141396, 16396.18019099046, 16536.090515021668, 16620.91689541552, 16648.750268745964, 14770.0, 14839.406423743038, 14982.026168299053, 14997.250137493109, 15024.248787560633, 648.7859006527834, 792.5046845721439, 795.9482633628825, 803.4295650088415, 808.4340961327065, 807.5679511461369, 809.0291650019999, 809.3161278719473, 814.0597609760736, 814.1858141858141, 851.6508231625035, 859.4413631139747, 859.6991053131385, 859.8710193470966, 862.1766213266347, 851.5274022917279, 857.3269983063296, 857.4255248983134, 859.2352806002663, 859.6518410043942, 629.6159342800888, 629.6411045703951, 630.4515071887441, 633.1737083106544, 633.7116611941556, 15121.848781512264, 15156.31796569146, 15156.696866062728, 15196.468123615621, 15235.466758663393, 14992.0, 15110.54668359951, 15162.696746065018, 15207.543773686808, 15223.695526089416, 12282.943547987603, 12431.689207769788, 12453.626391208278, 12720.872791272153, 12826.871731282661, 3857.6720978716844, 3891.3579259422163, 3942.0539070622845, 3959.78221197834, 4065.8170382332864, 76724.0, 76893.23106768916, 78311.608441958, 78417.60791196064, 81610.59194703988, 10343.689689309333, 10347.793044139074, 10460.68617941463, 10485.580576776843, 10626.256162068581, 20970.580588388148, 21034.89482552608, 21064.789352106283, 21098.472538186666, 21109.89445052765, 8311.750647480585, 8325.084240733515, 8410.285125764256, 8434.789130271793, 8526.488410695254, 4731.243001119804, 4898.0, 4919.1391506486225, 4924.4090709115135, 4932.605391568738, 3193.217661672905, 3265.151060724181, 3292.3415316936707, 3296.522004309362, 3299.1257316811066, 608.9330173680892, 619.2568917299259, 622.4802290087737, 624.2758400255682, 641.7016087519326, 615.3600255733986, 626.5301024231826, 668.4552090046597, 677.1332694151494, 709.606168576445, 9525.904740952501, 9558.562566545625, 9671.419714817133, 9732.12410883024, 9783.41299522031, 675.4967549175896, 705.3757424679093, 717.3292971072065, 719.9964000179965, 730.7223255163004, 4929.260610908321, 5106.821261255838, 5464.180372944011, 5539.723013849291, 5607.551395888319, 106492.0, 128728.0, 129489.0, 129641.0, 131344.0, 64333.0, 100097.49951250198, 103413.48293258635, 107062.46468767762, 107471.0, 99172.50413747886, 99726.5013674927, 100442.4977875106, 100480.49759751154, 101424.43590038318, 10474.947625261824, 10593.417362045162, 10716.624918127829, 10732.624358147428, 10752.838707419238, 55704.72147639236, 56531.27720432782, 57426.0, 57558.136627949774, 58437.70781146067, 76895.61552192204, 77102.61448692721, 83825.0, 85331.0, 85931.14068859481, 13230.536931207362, 13383.53157639478, 13502.527411540548, 13858.722825543231, 13954.162750235018, 15211.923940380228, 15645.765313520078, 15849.286782094603, 15919.522414327588, 16677.08276044829, 13905.096168749, 14101.788473172706, 14248.501302454364, 14540.49108281205, 14919.0, 13743.742995890923, 13882.837327363892, 14155.575332740034, 14242.643933901736, 14460.566183014527, 13182.472701091849, 14299.428022878968, 14509.274536273355, 14634.634134146732, 14748.188849613374, 11013.229073964745, 11173.944130279297, 11193.328400296008, 11350.262232954832, 12260.38698065111, 11367.488463019019, 11745.47145378443, 12309.75380492403, 12316.322602256962, 12430.0], "value": 7776.559176788562, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1527.6000000000004, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 113.0, 106.0, 112.0, 113.0, 112.0, 114.0, 111.0, 111.0, 111.0], "value": 110.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 121.0, 148.0, 192.0, 125.0, 146.0, 148.0, 119.0, 186.0, 118.0], "value": 145.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 162.0, 163.0, 162.0, 162.0, 163.0, 176.0, 165.0, 163.0, 162.0], "value": 163.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 75.0, 162.0, 77.0, 75.0, 75.0, 74.0, 155.0, 76.0], "value": 92.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 149.0, 85.0, 83.0, 84.0, 148.0, 84.0, 84.0, 145.0], "value": 103.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 118.0, 117.0, 117.0, 117.0, 117.0, 117.0, 118.0, 118.0, 120.0], "value": 117.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 147.0, 149.0, 148.0, 147.0, 147.0, 148.0, 146.0, 147.0], "value": 147.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 97.0, 95.0, 95.0, 95.0, 96.0, 96.0, 95.0, 96.0, 96.0], "value": 95.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 53.0, 53.0, 53.0, 53.0, 53.0, 57.0, 54.0, 53.0, 55.0], "value": 53.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 63.0, 63.0, 62.0, 67.0, 62.0, 62.0, 63.0, 63.0, 63.0], "value": 63.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 77.0, 78.0, 77.0, 81.0, 76.0, 80.0, 83.0, 81.0, 76.0], "value": 79.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [123.0, 130.0, 132.0, 126.0, 122.0, 121.0, 127.0, 126.0, 178.0, 134.0], "value": 131.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 160.0, 157.0, 160.0, 155.0, 156.0, 163.0, 158.0, 156.0, 160.0], "value": 158.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 65.0, 64.0, 65.0, 63.0, 67.0, 76.0, 63.0, 63.0], "value": 65.9, "unit": "score"}]}]} 22:23:59 INFO - Return code: 0 22:23:59 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:23:59 ERROR - # TBPL SUCCESS # 22:23:59 INFO - Running post-action listener: _resource_record_post_action 22:23:59 INFO - [mozharness: 2016-07-27 05:23:59.415807Z] Finished run-tests step (success) 22:23:59 INFO - Running post-run listener: _resource_record_post_run 22:23:59 INFO - Total resource usage - Wall time: 1193s; CPU: 13.0%; Read bytes: 7811072; Write bytes: 423088128; Read time: 1684; Write time: 359624 22:23:59 INFO - TinderboxPrint: CPU usage
13.3% 22:23:59 INFO - TinderboxPrint: I/O read bytes / time
7,811,072 / 1,684 22:23:59 INFO - TinderboxPrint: I/O write bytes / time
423,088,128 / 359,624 22:23:59 INFO - TinderboxPrint: CPU idle
8,247.1 (86.6%) 22:23:59 INFO - TinderboxPrint: CPU user
1,212.4 (12.7%) 22:23:59 INFO - TinderboxPrint: Swap in / out
0 / 0 22:23:59 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 4096; Write bytes: 82575360; Read time: 116; Write time: 44128 22:23:59 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 5570560; Write bytes: 340512768; Read time: 1400; Write time: 315496 22:24:00 INFO - Running post-run listener: _upload_blobber_files 22:24:00 INFO - Blob upload gear active. 22:24:00 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:24:00 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 22:24:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Inbound', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 22:24:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Inbound -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 22:24:00 INFO - (blobuploader) - INFO - Open directory for files ... 22:24:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 22:24:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:24:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:24:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 22:24:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:24:01 INFO - (blobuploader) - INFO - Done attempting. 22:24:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:24:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:24:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:24:06 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:24:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:24:06 INFO - (blobuploader) - INFO - Done attempting. 22:24:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 22:24:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:24:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:24:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 22:24:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:24:06 INFO - (blobuploader) - INFO - Done attempting. 22:24:06 INFO - (blobuploader) - INFO - Iteration through files over. 22:24:06 INFO - Return code: 0 22:24:06 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:24:06 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:24:06 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/b2990ec9928ecaab5be5abc60f722b1902304c3993a64e4c11cf254f5c99412743d820e3fa728d0624c9956d3ec9282101ca6f466b6252d63762fff06524fd9f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/ad6dc63597b44a525afb196ce053a796ebd0b354686e398e0d08edb27a48d1ced084e06d4423f9323a350d11fcad7c0c55027354eca32bfb8c762b9f936b9cbc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/dc03b1e1b48ee3d3b3064b3f21c97cd41aaebc5992194e699eb6cee41437d99c0f1099b14da835c0c1f2a79f109805d8f4a77814efd0e120ddf1af5166e1bda5"} 22:24:06 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:24:06 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:24:06 INFO - Contents: 22:24:06 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/b2990ec9928ecaab5be5abc60f722b1902304c3993a64e4c11cf254f5c99412743d820e3fa728d0624c9956d3ec9282101ca6f466b6252d63762fff06524fd9f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/ad6dc63597b44a525afb196ce053a796ebd0b354686e398e0d08edb27a48d1ced084e06d4423f9323a350d11fcad7c0c55027354eca32bfb8c762b9f936b9cbc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/dc03b1e1b48ee3d3b3064b3f21c97cd41aaebc5992194e699eb6cee41437d99c0f1099b14da835c0c1f2a79f109805d8f4a77814efd0e120ddf1af5166e1bda5"} 22:24:06 INFO - Running post-run listener: copy_logs_to_upload_dir 22:24:06 INFO - Copying logs to upload dir... 22:24:06 INFO - mkdir: /builds/slave/test/build/upload/logs 22:24:06 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1251.142096 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 51 secs) (at 2016-07-26 22:24:06.931032) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-26 22:24:06.937332) ========= 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=516f8d7ca085b4d087ab0a71000001a4-1469594776.346728-895458149 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/b2990ec9928ecaab5be5abc60f722b1902304c3993a64e4c11cf254f5c99412743d820e3fa728d0624c9956d3ec9282101ca6f466b6252d63762fff06524fd9f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/ad6dc63597b44a525afb196ce053a796ebd0b354686e398e0d08edb27a48d1ced084e06d4423f9323a350d11fcad7c0c55027354eca32bfb8c762b9f936b9cbc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/dc03b1e1b48ee3d3b3064b3f21c97cd41aaebc5992194e699eb6cee41437d99c0f1099b14da835c0c1f2a79f109805d8f4a77814efd0e120ddf1af5166e1bda5"} 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.011130 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-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/b2990ec9928ecaab5be5abc60f722b1902304c3993a64e4c11cf254f5c99412743d820e3fa728d0624c9956d3ec9282101ca6f466b6252d63762fff06524fd9f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/ad6dc63597b44a525afb196ce053a796ebd0b354686e398e0d08edb27a48d1ced084e06d4423f9323a350d11fcad7c0c55027354eca32bfb8c762b9f936b9cbc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/dc03b1e1b48ee3d3b3064b3f21c97cd41aaebc5992194e699eb6cee41437d99c0f1099b14da835c0c1f2a79f109805d8f4a77814efd0e120ddf1af5166e1bda5"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-26 22:24:06.962112) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-26 22:24:06.962436) ========= 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=516f8d7ca085b4d087ab0a71000001a4-1469594776.346728-895458149 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004476 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-26 22:24:07.049645) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-26 22:24:07.050022) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-26 22:24:07.050314) ========= ========= Total master_lag: 0.24 =========