builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-046 starttime: 1474336367.39 results: success (0) buildid: 20160919153004 builduid: d0d9df26992a4fa4a3250d6fd1cab0ca revision: b6fda98133de09b80069e0b921c866b6a0ee7e86 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-19 18:52:47.389578) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-19 18:52:47.390188) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-19 18:52:47.390599) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-19 18:52:47.581781) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 18:52:47.582548) ========= 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=652eb03db935cfc1c15ebda0000001ad-1474336365.109353-1011090097 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-19 18:52:47-- 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% 40.3M=0s 2016-09-19 18:52:47 (40.3 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.117165 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 18:52:47.719721) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 18:52:47.720500) ========= 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=652eb03db935cfc1c15ebda0000001ad-1474336365.109353-1011090097 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014432 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 18:52:47.775321) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-19 18:52:47.776165) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev b6fda98133de09b80069e0b921c866b6a0ee7e86 --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 b6fda98133de09b80069e0b921c866b6a0ee7e86 --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=652eb03db935cfc1c15ebda0000001ad-1474336365.109353-1011090097 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-19 18:52:48,085 truncating revision to first 12 chars 2016-09-19 18:52:48,085 Setting DEBUG logging. 2016-09-19 18:52:48,085 attempt 1/10 2016-09-19 18:52:48,085 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/b6fda98133de?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-19 18:52:49,038 unpacking tar archive at: mozilla-inbound-b6fda98133de/testing/mozharness/ program finished with exit code 0 elapsedTime=1.296116 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-19 18:52:49.092689) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-19 18:52:49.093455) ========= script_repo_revision: b6fda98133de09b80069e0b921c866b6a0ee7e86 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-19 18:52:49.094450) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-19 18:52:49.095100) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-19 18:52:49.106838) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2016-09-19 18:52:49.107634) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Inbound', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Inbound'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=652eb03db935cfc1c15ebda0000001ad-1474336365.109353-1011090097 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:52:49 INFO - MultiFileLogger online at 20160919 18:52:49 in /builds/slave/test 18:52:49 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 18:52:49 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:52:49 INFO - {'append_to_log': False, 18:52:49 INFO - 'base_work_dir': '/builds/slave/test', 18:52:49 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 18:52:49 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:52:49 INFO - 'branch': 'Mozilla-Inbound', 18:52:49 INFO - 'buildbot_json_path': 'buildprops.json', 18:52:49 INFO - 'config_files': ('talos/linux_config.py',), 18:52:49 INFO - 'default_actions': ('clobber', 18:52:49 INFO - 'read-buildbot-config', 18:52:49 INFO - 'download-and-extract', 18:52:49 INFO - 'populate-webroot', 18:52:49 INFO - 'create-virtualenv', 18:52:49 INFO - 'install', 18:52:49 INFO - 'run-tests'), 18:52:49 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:52:49 INFO - 'download_minidump_stackwalk': True, 18:52:49 INFO - 'download_symbols': 'ondemand', 18:52:49 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:52:49 INFO - 'tooltool.py': '/tools/tooltool.py', 18:52:49 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:52:49 INFO - '/tools/misc-python/virtualenv.py')}, 18:52:49 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:52:49 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:52:49 INFO - 'installer_path': 'installer.exe', 18:52:49 INFO - 'log_level': 'info', 18:52:49 INFO - 'log_name': 'talos', 18:52:49 INFO - 'log_to_console': True, 18:52:49 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:52:49 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:52:49 INFO - 'opt_config_files': (), 18:52:49 INFO - 'pip_index': False, 18:52:49 INFO - 'sps_profile': False, 18:52:49 INFO - 'sps_profile_interval': 0, 18:52:49 INFO - 'suite': 'dromaeojs', 18:52:49 INFO - 'system_bits': '32', 18:52:49 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:52:49 INFO - 'title': 'talos-linux64-ix-046', 18:52:49 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:52:49 INFO - 'use_talos_json': True, 18:52:49 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:52:49 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:52:49 INFO - 'work_dir': 'build'} 18:52:49 INFO - [mozharness: 2016-09-20 01:52:49.514248Z] Running clobber step. 18:52:49 INFO - Running pre-action listener: _resource_record_pre_action 18:52:49 INFO - Running main action method: clobber 18:52:49 INFO - rmtree: /builds/slave/test/build 18:52:49 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:52:50 INFO - Running post-action listener: _resource_record_post_action 18:52:50 INFO - [mozharness: 2016-09-20 01:52:50.554045Z] Finished clobber step (success) 18:52:50 INFO - [mozharness: 2016-09-20 01:52:50.554145Z] Running read-buildbot-config step. 18:52:50 INFO - Running pre-action listener: _resource_record_pre_action 18:52:50 INFO - Running main action method: read_buildbot_config 18:52:50 INFO - Using buildbot properties: 18:52:50 INFO - { 18:52:50 INFO - "project": "", 18:52:50 INFO - "product": "firefox", 18:52:50 INFO - "script_repo_revision": "production", 18:52:50 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs", 18:52:50 INFO - "repository": "", 18:52:50 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs", 18:52:50 INFO - "buildid": "20160919153004", 18:52:50 INFO - "pgo_build": "True", 18:52:50 INFO - "basedir": "/builds/slave/test", 18:52:50 INFO - "buildnumber": 1560, 18:52:50 INFO - "slavename": "talos-linux64-ix-046", 18:52:50 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 18:52:50 INFO - "platform": "ubuntu64_hw", 18:52:50 INFO - "scheduler": "tests-mozilla-inbound-linux64-pgo-talos", 18:52:50 INFO - "branch": "mozilla-inbound", 18:52:50 INFO - "repo_path": "integration/mozilla-inbound", 18:52:50 INFO - "revision": "b6fda98133de09b80069e0b921c866b6a0ee7e86", 18:52:50 INFO - "stage_platform": "linux64-pgo", 18:52:50 INFO - "builduid": "d0d9df26992a4fa4a3250d6fd1cab0ca", 18:52:50 INFO - "slavebuilddir": "test" 18:52:50 INFO - } 18:52:50 INFO - Found installer url https://queue.taskcluster.net/v1/task/aRfrZtnETwOiO50MVN1ZcA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 18:52:50 INFO - Running post-action listener: _resource_record_post_action 18:52:50 INFO - [mozharness: 2016-09-20 01:52:50.556591Z] Finished read-buildbot-config step (success) 18:52:50 INFO - [mozharness: 2016-09-20 01:52:50.556680Z] Running download-and-extract step. 18:52:50 INFO - Running pre-action listener: _resource_record_pre_action 18:52:50 INFO - Running main action method: download_and_extract 18:52:50 INFO - mkdir: /builds/slave/test/build/tests 18:52: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:52:50 INFO - trying https://queue.taskcluster.net/v1/task/aRfrZtnETwOiO50MVN1ZcA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 18:52:50 INFO - Downloading https://queue.taskcluster.net/v1/task/aRfrZtnETwOiO50MVN1ZcA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 18:52:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aRfrZtnETwOiO50MVN1ZcA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 18:52:51 INFO - Downloaded 1361 bytes. 18:52:51 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 18:52:51 INFO - Using the following test package requirements: 18:52:51 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 18:52:51 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 18:52:51 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 18:52:51 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 18:52:51 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 18:52:51 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 18:52:51 INFO - u'jsshell-linux-x86_64.zip'], 18:52:51 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 18:52:51 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 18:52:51 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 18:52:51 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 18:52:51 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 18:52:51 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 18:52:51 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 18:52:51 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 18:52:51 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 18:52:51 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 18:52:51 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 18:52:51 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 18:52:51 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/aRfrZtnETwOiO50MVN1ZcA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 18:52:51 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/aRfrZtnETwOiO50MVN1ZcA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 18:52:51 INFO - Fetch https://queue.taskcluster.net/v1/task/aRfrZtnETwOiO50MVN1ZcA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip into memory 18:52:52 INFO - Expected file size: 21937332 18:52:56 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 18:52:56 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/aRfrZtnETwOiO50MVN1ZcA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 18:52:56 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/aRfrZtnETwOiO50MVN1ZcA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 18:52:56 INFO - Fetch https://queue.taskcluster.net/v1/task/aRfrZtnETwOiO50MVN1ZcA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip into memory 18:52:57 INFO - Expected file size: 21937332 18:53:02 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/aRfrZtnETwOiO50MVN1ZcA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 18:53:02 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/aRfrZtnETwOiO50MVN1ZcA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 18:53:02 INFO - Fetch https://queue.taskcluster.net/v1/task/aRfrZtnETwOiO50MVN1ZcA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip into memory 18:53:03 INFO - Expected file size: 14414059 18:53:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:53:03 INFO - trying https://queue.taskcluster.net/v1/task/aRfrZtnETwOiO50MVN1ZcA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 18:53:03 INFO - Downloading https://queue.taskcluster.net/v1/task/aRfrZtnETwOiO50MVN1ZcA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 18:53:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aRfrZtnETwOiO50MVN1ZcA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 18:53:11 INFO - Downloaded 59559105 bytes. 18:53:11 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/aRfrZtnETwOiO50MVN1ZcA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 18:53:11 INFO - mkdir: /builds/slave/test/properties 18:53:11 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:53:11 INFO - Writing to file /builds/slave/test/properties/build_url 18:53:11 INFO - Contents: 18:53:11 INFO - build_url:https://queue.taskcluster.net/v1/task/aRfrZtnETwOiO50MVN1ZcA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 18:53:12 INFO - Running post-action listener: _resource_record_post_action 18:53:12 INFO - Running post-action listener: set_extra_try_arguments 18:53:12 INFO - [mozharness: 2016-09-20 01:53:12.377557Z] Finished download-and-extract step (success) 18:53:12 INFO - [mozharness: 2016-09-20 01:53:12.377786Z] Running populate-webroot step. 18:53:12 INFO - Running pre-action listener: _resource_record_pre_action 18:53:12 INFO - Running main action method: populate_webroot 18:53:12 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:53:12 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:53:12 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:53:12 INFO - '--tppagecycles', 18:53:12 INFO - '7'], 18:53:12 INFO - 'tests': ['tsvgm']}}, 18:53:12 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:53:12 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:53:12 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:53:12 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:53:12 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:53:12 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:53:12 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:53:12 INFO - 'talos_options': ['--disable-e10s'], 18:53:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:53:12 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:53:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:53:12 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:53:12 INFO - 'talos_options': ['--disable-e10s'], 18:53:12 INFO - 'tests': ['damp', 'tps']}, 18:53:12 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:53:12 INFO - 'tests': ['damp', 'tps']}, 18:53:12 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:53:12 INFO - 'tests': ['dromaeo_dom']}, 18:53:12 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:53:12 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:53:12 INFO - 'tests': ['basic_compositor_video']}, 18:53:12 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 18:53:12 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:53:12 INFO - 'tests': ['a11yr', 18:53:12 INFO - 'ts_paint', 18:53:12 INFO - 'tpaint', 18:53:12 INFO - 'sessionrestore', 18:53:12 INFO - 'sessionrestore_no_auto_restore', 18:53:12 INFO - 'tabpaint']}, 18:53:12 INFO - 'other-e10s': {'tests': ['a11yr', 18:53:12 INFO - 'ts_paint', 18:53:12 INFO - 'tpaint', 18:53:12 INFO - 'sessionrestore', 18:53:12 INFO - 'sessionrestore_no_auto_restore', 18:53:12 INFO - 'tabpaint']}, 18:53:12 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:53:12 INFO - 'ts_paint', 18:53:12 INFO - 'tpaint', 18:53:12 INFO - 'sessionrestore', 18:53:12 INFO - 'sessionrestore_no_auto_restore', 18:53:12 INFO - 'tabpaint']}, 18:53:12 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:53:12 INFO - 'ts_paint', 18:53:12 INFO - 'tpaint', 18:53:12 INFO - 'sessionrestore', 18:53:12 INFO - 'sessionrestore_no_auto_restore', 18:53:12 INFO - 'tabpaint']}, 18:53:12 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:53:12 INFO - 'tests': ['a11yr', 18:53:12 INFO - 'ts_paint', 18:53:12 INFO - 'tpaint', 18:53:12 INFO - 'sessionrestore', 18:53:12 INFO - 'sessionrestore_no_auto_restore', 18:53:12 INFO - 'tabpaint']}, 18:53:12 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:53:12 INFO - 'tests': ['a11yr', 18:53:12 INFO - 'ts_paint', 18:53:12 INFO - 'tpaint', 18:53:12 INFO - 'sessionrestore', 18:53:12 INFO - 'sessionrestore_no_auto_restore', 18:53:12 INFO - 'tabpaint']}, 18:53:12 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:53:12 INFO - 'tests': ['tsvgx', 18:53:12 INFO - 'tsvgr_opacity', 18:53:12 INFO - 'tart', 18:53:12 INFO - 'tscrollx', 18:53:12 INFO - 'cart']}, 18:53:12 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:53:12 INFO - 'tsvgr_opacity', 18:53:12 INFO - 'tart', 18:53:12 INFO - 'tscrollx', 18:53:12 INFO - 'cart']}, 18:53:12 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:53:12 INFO - 'talos_options': ['--disable-e10s'], 18:53:12 INFO - 'tests': ['tp5o']}, 18:53:12 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:53:12 INFO - 'tests': ['tp5o']}, 18:53:12 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:53:12 INFO - 'talos_options': ['--disable-e10s', 18:53:12 INFO - '--xperf_path', 18:53:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:53:12 INFO - 'tests': ['tp5n']}, 18:53:12 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:53:12 INFO - 'talos_options': ['--xperf_path', 18:53:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:53:12 INFO - 'tests': ['tp5n']}}, 18:53:12 INFO - 'talos.zip': {'path': '', 18:53:12 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:53:12 INFO - Running post-action listener: _resource_record_post_action 18:53:12 INFO - [mozharness: 2016-09-20 01:53:12.395486Z] Finished populate-webroot step (success) 18:53:12 INFO - [mozharness: 2016-09-20 01:53:12.395575Z] Running create-virtualenv step. 18:53:12 INFO - Running pre-action listener: _resource_record_pre_action 18:53:12 INFO - Running main action method: create_virtualenv 18:53:12 INFO - Creating virtualenv /builds/slave/test/build/venv 18:53:12 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 18:53:12 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:53:12 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:53:12 INFO - Using real prefix '/usr' 18:53:12 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:53:13 INFO - Installing distribute.............................................................................................................................................................................................done. 18:53:14 INFO - Installing pip.................done. 18:53:14 INFO - Return code: 0 18:53:14 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:53:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:53:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:53:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:53:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:53:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:53:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:53:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f1d72139f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ed28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f9fca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f9cf60>, '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 0x1f9dd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f9e1d0>, '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': '652eb03db935cfc1c15ebda0000001ad-1474336365.109353-1011090097', '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 18:53:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 18:53:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 18:53:14 INFO - Using env: {'DISPLAY': ':0', 18:53:14 INFO - 'HOME': '/home/cltbld', 18:53:14 INFO - 'LANG': 'en_US.UTF-8', 18:53:14 INFO - 'LANGUAGE': 'en_US:en', 18:53:14 INFO - 'LOGNAME': 'cltbld', 18:53:14 INFO - 'MAIL': '/var/mail/cltbld', 18:53:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:53:14 INFO - 'MOZ_NO_REMOTE': '1', 18:53:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:53:14 INFO - 'NO_EM_RESTART': '1', 18:53:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:53:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:53:14 INFO - 'PWD': '/builds/slave/test', 18:53:14 INFO - 'SHELL': '/bin/bash', 18:53:14 INFO - 'SHLVL': '1', 18:53:14 INFO - 'TERM': 'linux', 18:53:14 INFO - 'TMOUT': '86400', 18:53:14 INFO - 'USER': 'cltbld', 18:53:14 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1474336365.109353-1011090097', 18:53:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:53:14 INFO - '_': '/tools/buildbot/bin/python'} 18:53:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:53:15 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 18:53:15 INFO - Cleaning up... 18:53:15 INFO - Return code: 0 18:53:15 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:53:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:53:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:53:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:53:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:53:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:53:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:53:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f1d72139f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ed28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f9fca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f9cf60>, '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 0x1f9dd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f9e1d0>, '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': '652eb03db935cfc1c15ebda0000001ad-1474336365.109353-1011090097', '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 18:53:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 18:53:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 18:53:15 INFO - Using env: {'DISPLAY': ':0', 18:53:15 INFO - 'HOME': '/home/cltbld', 18:53:15 INFO - 'LANG': 'en_US.UTF-8', 18:53:15 INFO - 'LANGUAGE': 'en_US:en', 18:53:15 INFO - 'LOGNAME': 'cltbld', 18:53:15 INFO - 'MAIL': '/var/mail/cltbld', 18:53:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:53:15 INFO - 'MOZ_NO_REMOTE': '1', 18:53:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:53:15 INFO - 'NO_EM_RESTART': '1', 18:53:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:53:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:53:15 INFO - 'PWD': '/builds/slave/test', 18:53:15 INFO - 'SHELL': '/bin/bash', 18:53:15 INFO - 'SHLVL': '1', 18:53:15 INFO - 'TERM': 'linux', 18:53:15 INFO - 'TMOUT': '86400', 18:53:15 INFO - 'USER': 'cltbld', 18:53:15 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1474336365.109353-1011090097', 18:53:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:53:15 INFO - '_': '/tools/buildbot/bin/python'} 18:53:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:53:15 INFO - Downloading/unpacking psutil>=3.1.1 18:53:15 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 18:53:15 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 18:53:15 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 18:53:15 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 18:53:15 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 18:53:15 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 18:53:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:53:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:53:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:53:18 INFO - Installing collected packages: psutil 18:53:18 INFO - Running setup.py install for psutil 18:53:18 INFO - building 'psutil._psutil_linux' extension 18:53:18 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 18:53:18 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 18:53:18 INFO - building 'psutil._psutil_posix' extension 18:53:18 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 18:53:18 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 18:53:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:53:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:53:18 INFO - Successfully installed psutil 18:53:18 INFO - Cleaning up... 18:53:18 INFO - Return code: 0 18:53:18 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:53:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:53:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:53:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:53:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:53:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:53:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:53:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f1d72139f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ed28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f9fca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f9cf60>, '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 0x1f9dd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f9e1d0>, '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': '652eb03db935cfc1c15ebda0000001ad-1474336365.109353-1011090097', '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 18:53:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 18:53:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 18:53:18 INFO - Using env: {'DISPLAY': ':0', 18:53:18 INFO - 'HOME': '/home/cltbld', 18:53:18 INFO - 'LANG': 'en_US.UTF-8', 18:53:18 INFO - 'LANGUAGE': 'en_US:en', 18:53:18 INFO - 'LOGNAME': 'cltbld', 18:53:18 INFO - 'MAIL': '/var/mail/cltbld', 18:53:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:53:18 INFO - 'MOZ_NO_REMOTE': '1', 18:53:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:53:18 INFO - 'NO_EM_RESTART': '1', 18:53:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:53:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:53:18 INFO - 'PWD': '/builds/slave/test', 18:53:18 INFO - 'SHELL': '/bin/bash', 18:53:18 INFO - 'SHLVL': '1', 18:53:18 INFO - 'TERM': 'linux', 18:53:18 INFO - 'TMOUT': '86400', 18:53:18 INFO - 'USER': 'cltbld', 18:53:18 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1474336365.109353-1011090097', 18:53:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:53:18 INFO - '_': '/tools/buildbot/bin/python'} 18:53:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:53:19 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:53:19 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 18:53:19 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 18:53:19 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 18:53:19 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 18:53:19 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 18:53:19 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 18:53:22 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:53:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:53:22 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:53:22 INFO - Installing collected packages: mozsystemmonitor 18:53:22 INFO - Running setup.py install for mozsystemmonitor 18:53:22 INFO - Successfully installed mozsystemmonitor 18:53:22 INFO - Cleaning up... 18:53:22 INFO - Return code: 0 18:53:22 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:53:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:53:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:53:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:53:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:53:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:53:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:53:22 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 0x7f1d72139f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ed28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f9fca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f9cf60>, '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 0x1f9dd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f9e1d0>, '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': '652eb03db935cfc1c15ebda0000001ad-1474336365.109353-1011090097', '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 18:53:22 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 18:53:22 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 18:53:22 INFO - Using env: {'DISPLAY': ':0', 18:53:22 INFO - 'HOME': '/home/cltbld', 18:53:22 INFO - 'LANG': 'en_US.UTF-8', 18:53:22 INFO - 'LANGUAGE': 'en_US:en', 18:53:22 INFO - 'LOGNAME': 'cltbld', 18:53:22 INFO - 'MAIL': '/var/mail/cltbld', 18:53:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:53:22 INFO - 'MOZ_NO_REMOTE': '1', 18:53:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:53:22 INFO - 'NO_EM_RESTART': '1', 18:53:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:53:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:53:22 INFO - 'PWD': '/builds/slave/test', 18:53:22 INFO - 'SHELL': '/bin/bash', 18:53:22 INFO - 'SHLVL': '1', 18:53:22 INFO - 'TERM': 'linux', 18:53:22 INFO - 'TMOUT': '86400', 18:53:22 INFO - 'USER': 'cltbld', 18:53:22 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1474336365.109353-1011090097', 18:53:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:53:22 INFO - '_': '/tools/buildbot/bin/python'} 18:53:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:53:22 INFO - Downloading/unpacking jsonschema==2.5.1 18:53:22 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 18:53:22 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 18:53:22 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 18:53:22 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 18:53:22 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 18:53:22 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 18:53:25 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:53:25 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:53:25 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 18:53:25 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 18:53:25 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 18:53:25 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 18:53:25 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 18:53:25 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 18:53:25 INFO - Downloading functools32-3.2.3-2.tar.gz 18:53:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:53:25 INFO - warning: no files found matching '*.txt' 18:53:25 INFO - no previously-included directories found matching 'build' 18:53:25 INFO - no previously-included directories found matching 'dist' 18:53:25 INFO - no previously-included directories found matching '.git*' 18:53:25 INFO - Installing collected packages: jsonschema, functools32 18:53:25 INFO - Running setup.py install for functools32 18:53:25 INFO - warning: no files found matching '*.txt' 18:53:25 INFO - no previously-included directories found matching 'build' 18:53:25 INFO - no previously-included directories found matching 'dist' 18:53:25 INFO - no previously-included directories found matching '.git*' 18:53:25 INFO - Successfully installed jsonschema functools32 18:53:25 INFO - Cleaning up... 18:53:25 INFO - Return code: 0 18:53:25 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:53: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:53:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:53:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:53: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:53:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:53:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:53:25 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', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f1d72139f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ed28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f9fca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f9cf60>, '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 0x1f9dd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f9e1d0>, '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': '652eb03db935cfc1c15ebda0000001ad-1474336365.109353-1011090097', '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 18:53:25 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', 'functools32==3.2.3-2'] in /builds/slave/test/build 18:53:25 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 functools32==3.2.3-2 18:53:25 INFO - Using env: {'DISPLAY': ':0', 18:53:25 INFO - 'HOME': '/home/cltbld', 18:53:25 INFO - 'LANG': 'en_US.UTF-8', 18:53:25 INFO - 'LANGUAGE': 'en_US:en', 18:53:25 INFO - 'LOGNAME': 'cltbld', 18:53:25 INFO - 'MAIL': '/var/mail/cltbld', 18:53:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:53:25 INFO - 'MOZ_NO_REMOTE': '1', 18:53:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:53:25 INFO - 'NO_EM_RESTART': '1', 18:53:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:53:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:53:25 INFO - 'PWD': '/builds/slave/test', 18:53:25 INFO - 'SHELL': '/bin/bash', 18:53:25 INFO - 'SHLVL': '1', 18:53:25 INFO - 'TERM': 'linux', 18:53:25 INFO - 'TMOUT': '86400', 18:53:25 INFO - 'USER': 'cltbld', 18:53:25 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1474336365.109353-1011090097', 18:53:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:53:25 INFO - '_': '/tools/buildbot/bin/python'} 18:53:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:53:25 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:53:25 INFO - Cleaning up... 18:53:25 INFO - Return code: 0 18:53:25 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:53: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:53:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:53:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:53: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:53:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:53:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:53:25 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 0x7f1d72139f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ed28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f9fca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f9cf60>, '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 0x1f9dd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f9e1d0>, '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': '652eb03db935cfc1c15ebda0000001ad-1474336365.109353-1011090097', '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 18:53:25 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 18:53:25 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 18:53:25 INFO - Using env: {'DISPLAY': ':0', 18:53:25 INFO - 'HOME': '/home/cltbld', 18:53:25 INFO - 'LANG': 'en_US.UTF-8', 18:53:25 INFO - 'LANGUAGE': 'en_US:en', 18:53:25 INFO - 'LOGNAME': 'cltbld', 18:53:25 INFO - 'MAIL': '/var/mail/cltbld', 18:53:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:53:25 INFO - 'MOZ_NO_REMOTE': '1', 18:53:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:53:25 INFO - 'NO_EM_RESTART': '1', 18:53:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:53:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:53:25 INFO - 'PWD': '/builds/slave/test', 18:53:25 INFO - 'SHELL': '/bin/bash', 18:53:25 INFO - 'SHLVL': '1', 18:53:25 INFO - 'TERM': 'linux', 18:53:25 INFO - 'TMOUT': '86400', 18:53:25 INFO - 'USER': 'cltbld', 18:53:25 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1474336365.109353-1011090097', 18:53:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:53:25 INFO - '_': '/tools/buildbot/bin/python'} 18:53:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:53:26 INFO - Downloading/unpacking blobuploader==1.2.4 18:53:26 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 18:53:26 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 18:53:26 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 18:53:26 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 18:53:26 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 18:53:26 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 18:53:29 INFO - Downloading blobuploader-1.2.4.tar.gz 18:53:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:53:29 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:53:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:53:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:53:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:53:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:53:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:53:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:53:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:53:30 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:53:30 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 18:53:30 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 18:53:30 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 18:53:30 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 18:53:30 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 18:53:30 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 18:53:30 INFO - Downloading docopt-0.6.1.tar.gz 18:53:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:53:30 INFO - Installing collected packages: blobuploader, requests, docopt 18:53:30 INFO - Running setup.py install for blobuploader 18:53:31 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:53:31 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:53:31 INFO - Running setup.py install for requests 18:53:31 INFO - Running setup.py install for docopt 18:53:31 INFO - Successfully installed blobuploader requests docopt 18:53:31 INFO - Cleaning up... 18:53:31 INFO - Return code: 0 18:53:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:53:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:53:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:53:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:53:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:53:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:53:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:53:31 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 0x7f1d72139f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ed28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f9fca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f9cf60>, '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 0x1f9dd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f9e1d0>, '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': '652eb03db935cfc1c15ebda0000001ad-1474336365.109353-1011090097', '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 18:53:31 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 18:53:31 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 18:53:31 INFO - Using env: {'DISPLAY': ':0', 18:53:31 INFO - 'HOME': '/home/cltbld', 18:53:31 INFO - 'LANG': 'en_US.UTF-8', 18:53:31 INFO - 'LANGUAGE': 'en_US:en', 18:53:31 INFO - 'LOGNAME': 'cltbld', 18:53:31 INFO - 'MAIL': '/var/mail/cltbld', 18:53:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:53:31 INFO - 'MOZ_NO_REMOTE': '1', 18:53:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:53:31 INFO - 'NO_EM_RESTART': '1', 18:53:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:53:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:53:31 INFO - 'PWD': '/builds/slave/test', 18:53:31 INFO - 'SHELL': '/bin/bash', 18:53:31 INFO - 'SHLVL': '1', 18:53:31 INFO - 'TERM': 'linux', 18:53:31 INFO - 'TMOUT': '86400', 18:53:31 INFO - 'USER': 'cltbld', 18:53:31 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1474336365.109353-1011090097', 18:53:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:53:31 INFO - '_': '/tools/buildbot/bin/python'} 18:53:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:53:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:53:31 INFO - Running setup.py (path:/tmp/pip-mBFjjW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:53:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:53:31 INFO - Running setup.py (path:/tmp/pip-tC8saU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:53:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:53:31 INFO - Running setup.py (path:/tmp/pip-tSf0cg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:53:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:53:31 INFO - Running setup.py (path:/tmp/pip-h_ZOaI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:53:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:53:32 INFO - Running setup.py (path:/tmp/pip-KyZgJF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:53:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:53:32 INFO - Running setup.py (path:/tmp/pip-vZYON4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:53:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:53:32 INFO - Running setup.py (path:/tmp/pip-u27w98-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:53:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:53:32 INFO - Running setup.py (path:/tmp/pip-YtVqDh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:53:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:53:32 INFO - Running setup.py (path:/tmp/pip-MMKARN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:53:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:53:32 INFO - Running setup.py (path:/tmp/pip-yVP7OT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:53:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:53:32 INFO - Running setup.py (path:/tmp/pip-AaRLV5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:53:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:53:32 INFO - Running setup.py (path:/tmp/pip-7wjAKA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:53:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:53:32 INFO - Running setup.py (path:/tmp/pip-I4q4ft-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:53:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:53:32 INFO - Running setup.py (path:/tmp/pip-7U0FkN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:53:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:53:32 INFO - Running setup.py (path:/tmp/pip-_wfLPl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:53:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:53:32 INFO - Running setup.py (path:/tmp/pip-4reF71-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:53:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:53:32 INFO - Running setup.py (path:/tmp/pip-Ehrj9b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:53:32 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:53:32 INFO - Running setup.py install for manifestparser 18:53:33 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:53:33 INFO - Running setup.py install for mozcrash 18:53:33 INFO - Running setup.py install for mozdebug 18:53:33 INFO - Running setup.py install for mozdevice 18:53:33 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:53:33 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:53:33 INFO - Running setup.py install for mozfile 18:53:33 INFO - Running setup.py install for mozhttpd 18:53:33 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:53:33 INFO - Running setup.py install for mozinfo 18:53:33 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:53:33 INFO - Running setup.py install for mozInstall 18:53:33 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:53:33 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:53:33 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:53:33 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:53:33 INFO - Running setup.py install for mozleak 18:53:33 INFO - Running setup.py install for mozlog 18:53:33 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:53:34 INFO - Running setup.py install for moznetwork 18:53:34 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:53:34 INFO - Running setup.py install for mozprocess 18:53:34 INFO - Running setup.py install for mozprofile 18:53:34 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:53:34 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:53:34 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:53:34 INFO - Running setup.py install for mozrunner 18:53:34 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:53:34 INFO - Running setup.py install for mozscreenshot 18:53:34 INFO - Running setup.py install for moztest 18:53:34 INFO - Running setup.py install for mozversion 18:53:34 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:53:34 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:53:34 INFO - Cleaning up... 18:53:34 INFO - Return code: 0 18:53:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:53:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:53:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:53:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:53:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:53:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:53:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:53:34 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 0x7f1d72139f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ed28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f9fca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f9cf60>, '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 0x1f9dd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f9e1d0>, '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': '652eb03db935cfc1c15ebda0000001ad-1474336365.109353-1011090097', '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 18:53:34 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 18:53:34 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 18:53:34 INFO - Using env: {'DISPLAY': ':0', 18:53:34 INFO - 'HOME': '/home/cltbld', 18:53:34 INFO - 'LANG': 'en_US.UTF-8', 18:53:34 INFO - 'LANGUAGE': 'en_US:en', 18:53:34 INFO - 'LOGNAME': 'cltbld', 18:53:34 INFO - 'MAIL': '/var/mail/cltbld', 18:53:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:53:34 INFO - 'MOZ_NO_REMOTE': '1', 18:53:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:53:34 INFO - 'NO_EM_RESTART': '1', 18:53:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:53:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:53:34 INFO - 'PWD': '/builds/slave/test', 18:53:34 INFO - 'SHELL': '/bin/bash', 18:53:34 INFO - 'SHLVL': '1', 18:53:34 INFO - 'TERM': 'linux', 18:53:34 INFO - 'TMOUT': '86400', 18:53:34 INFO - 'USER': 'cltbld', 18:53:34 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1474336365.109353-1011090097', 18:53:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:53:34 INFO - '_': '/tools/buildbot/bin/python'} 18:53:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:53:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:53:35 INFO - Running setup.py (path:/tmp/pip-sPK7OQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:53:35 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)) 18:53:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:53:35 INFO - Running setup.py (path:/tmp/pip-pQu4JJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:53:35 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)) 18:53:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:53:35 INFO - Running setup.py (path:/tmp/pip-9V_0y2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:53:35 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)) 18:53:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:53:35 INFO - Running setup.py (path:/tmp/pip-TvCN7z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:53:35 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)) 18:53:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:53:35 INFO - Running setup.py (path:/tmp/pip-s3vp7Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:53:35 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)) 18:53:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:53:35 INFO - Running setup.py (path:/tmp/pip-TKCW20-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:53:35 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)) 18:53:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:53:35 INFO - Running setup.py (path:/tmp/pip-jWvFGN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:53:35 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)) 18:53:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:53:35 INFO - Running setup.py (path:/tmp/pip-j1ZkF4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:53:35 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)) 18:53:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:53:35 INFO - Running setup.py (path:/tmp/pip-yMEoPj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:53:35 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)) 18:53:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:53:35 INFO - Running setup.py (path:/tmp/pip-WfGY5m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:53:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 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)) 18:53:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:53:35 INFO - Running setup.py (path:/tmp/pip-DnbYsr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:53:35 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)) 18:53:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:53:35 INFO - Running setup.py (path:/tmp/pip-1dxlgt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:53:36 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)) 18:53:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:53:36 INFO - Running setup.py (path:/tmp/pip-MXgd4u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:53:36 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)) 18:53:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:53:36 INFO - Running setup.py (path:/tmp/pip-rxZtx5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:53:36 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)) 18:53:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:53:36 INFO - Running setup.py (path:/tmp/pip-hEC3L7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:53:36 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)) 18:53:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:53:36 INFO - Running setup.py (path:/tmp/pip-47iY3B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:53:36 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)) 18:53:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:53:36 INFO - Running setup.py (path:/tmp/pip-H8qRPT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:53:36 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)) 18:53:36 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)) 18:53:36 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)) 18:53:36 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)) 18:53:36 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)) 18:53:36 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)) 18:53:36 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:53:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:53:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:53:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:53:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:53:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:53:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:53:38 INFO - Downloading blessings-1.6.tar.gz 18:53:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:53:38 INFO - Installing collected packages: blessings 18:53:38 INFO - Running setup.py install for blessings 18:53:38 INFO - Successfully installed blessings 18:53:38 INFO - Cleaning up... 18:53:38 INFO - Return code: 0 18:53:38 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:53:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:53:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:53:39 INFO - Reading from file tmpfile_stdout 18:53:39 INFO - Current package versions: 18:53:39 INFO - argparse == 1.2.1 18:53:39 INFO - blessings == 1.6 18:53:39 INFO - blobuploader == 1.2.4 18:53:39 INFO - docopt == 0.6.1 18:53:39 INFO - functools32 == 3.2.3-2 18:53:39 INFO - jsonschema == 2.5.1 18:53:39 INFO - manifestparser == 1.1 18:53:39 INFO - mozInstall == 1.12 18:53:39 INFO - mozcrash == 0.17 18:53:39 INFO - mozdebug == 0.1 18:53:39 INFO - mozdevice == 0.48 18:53:39 INFO - mozfile == 1.2 18:53:39 INFO - mozhttpd == 0.7 18:53:39 INFO - mozinfo == 0.9 18:53:39 INFO - mozleak == 0.1 18:53:39 INFO - mozlog == 3.4 18:53:39 INFO - moznetwork == 0.27 18:53:39 INFO - mozprocess == 0.23 18:53:39 INFO - mozprofile == 0.28 18:53:39 INFO - mozrunner == 6.12 18:53:39 INFO - mozscreenshot == 0.1 18:53:39 INFO - mozsystemmonitor == 0.3 18:53:39 INFO - moztest == 0.7 18:53:39 INFO - mozversion == 1.4 18:53:39 INFO - psutil == 3.1.1 18:53:39 INFO - requests == 1.2.3 18:53:39 INFO - wsgiref == 0.1.2 18:53:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:53: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:53:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:53:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:53: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:53:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:53:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:53:39 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 0x7f1d72139f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ed28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f9fca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f9cf60>, '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 0x1f9dd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f9e1d0>, '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': '652eb03db935cfc1c15ebda0000001ad-1474336365.109353-1011090097', '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 18:53:39 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 18:53:39 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 18:53:39 INFO - Using env: {'DISPLAY': ':0', 18:53:39 INFO - 'HOME': '/home/cltbld', 18:53:39 INFO - 'LANG': 'en_US.UTF-8', 18:53:39 INFO - 'LANGUAGE': 'en_US:en', 18:53:39 INFO - 'LOGNAME': 'cltbld', 18:53:39 INFO - 'MAIL': '/var/mail/cltbld', 18:53:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:53:39 INFO - 'MOZ_NO_REMOTE': '1', 18:53:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:53:39 INFO - 'NO_EM_RESTART': '1', 18:53:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:53:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:53:39 INFO - 'PWD': '/builds/slave/test', 18:53:39 INFO - 'SHELL': '/bin/bash', 18:53:39 INFO - 'SHLVL': '1', 18:53:39 INFO - 'TERM': 'linux', 18:53:39 INFO - 'TMOUT': '86400', 18:53:39 INFO - 'USER': 'cltbld', 18:53:39 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1474336365.109353-1011090097', 18:53:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:53:39 INFO - '_': '/tools/buildbot/bin/python'} 18:53:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:53:39 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)) 18:53:39 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)) 18:53:39 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)) 18:53:39 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)) 18:53:39 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)) 18:53:39 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)) 18:53:39 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)) 18:53:39 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)) 18:53:39 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)) 18:53:39 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)) 18:53:39 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)) 18:53:39 INFO - Cleaning up... 18:53:39 INFO - Return code: 0 18:53:39 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:53: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:53:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:53:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:53: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:53:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:53:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:53:39 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 0x7f1d72139f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ed28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f9fca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f9cf60>, '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 0x1f9dd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f9e1d0>, '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': '652eb03db935cfc1c15ebda0000001ad-1474336365.109353-1011090097', '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 18:53:39 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 18:53:39 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 18:53:39 INFO - Using env: {'DISPLAY': ':0', 18:53:39 INFO - 'HOME': '/home/cltbld', 18:53:39 INFO - 'LANG': 'en_US.UTF-8', 18:53:39 INFO - 'LANGUAGE': 'en_US:en', 18:53:39 INFO - 'LOGNAME': 'cltbld', 18:53:39 INFO - 'MAIL': '/var/mail/cltbld', 18:53:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:53:39 INFO - 'MOZ_NO_REMOTE': '1', 18:53:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:53:39 INFO - 'NO_EM_RESTART': '1', 18:53:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:53:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:53:39 INFO - 'PWD': '/builds/slave/test', 18:53:39 INFO - 'SHELL': '/bin/bash', 18:53:39 INFO - 'SHLVL': '1', 18:53:39 INFO - 'TERM': 'linux', 18:53:39 INFO - 'TMOUT': '86400', 18:53:39 INFO - 'USER': 'cltbld', 18:53:39 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1474336365.109353-1011090097', 18:53:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:53:39 INFO - '_': '/tools/buildbot/bin/python'} 18:53:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:53:39 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:53:39 INFO - Cleaning up... 18:53:39 INFO - Return code: 0 18:53:39 INFO - Running post-action listener: _resource_record_post_action 18:53:39 INFO - Running post-action listener: _start_resource_monitoring 18:53:39 INFO - Starting resource monitoring. 18:53:39 INFO - [mozharness: 2016-09-20 01:53:39.520424Z] Finished create-virtualenv step (success) 18:53:39 INFO - [mozharness: 2016-09-20 01:53:39.520992Z] Running install step. 18:53:39 INFO - Running pre-action listener: _resource_record_pre_action 18:53:39 INFO - Running main action method: install 18:53:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:53:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:53:39 INFO - Reading from file tmpfile_stdout 18:53:39 INFO - Detecting whether we're running mozinstall >=1.0... 18:53:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:53:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:53:39 INFO - Reading from file tmpfile_stdout 18:53:39 INFO - Output received: 18:53:39 INFO - Usage: mozinstall [options] installer 18:53:39 INFO - Options: 18:53:39 INFO - -h, --help show this help message and exit 18:53:39 INFO - -d DEST, --destination=DEST 18:53:39 INFO - Directory to install application into. [default: 18:53:39 INFO - "/builds/slave/test"] 18:53:39 INFO - --app=APP Application being installed. [default: firefox] 18:53:39 INFO - mkdir: /builds/slave/test/build/application 18:53:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 18:53:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 18:53:56 INFO - Reading from file tmpfile_stdout 18:53:56 INFO - Output received: 18:53:56 INFO - /builds/slave/test/build/application/firefox/firefox 18:53:56 INFO - Running post-action listener: _resource_record_post_action 18:53:56 INFO - [mozharness: 2016-09-20 01:53:56.782458Z] Finished install step (success) 18:53:56 INFO - [mozharness: 2016-09-20 01:53:56.782707Z] Running run-tests step. 18:53:56 INFO - Running pre-action listener: _resource_record_pre_action 18:53:56 INFO - Running main action method: run_tests 18:53:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:53:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:53:56 INFO - Python 2.7.3 18:53:56 INFO - Return code: 0 18:53:56 INFO - grabbing minidump binary from tooltool 18:53:56 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:53:56 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 0x1f9cf60>, '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 0x1f9dd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f9e1d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:53:56 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 18:53:56 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 18:53:56 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 18:53:56 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:53:56 INFO - Return code: 0 18:53:56 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 18:53:56 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:53:56 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:53:56 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 18:53:56 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:53:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/aRfrZtnETwOiO50MVN1ZcA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-046', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 18:53:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Inbound --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/aRfrZtnETwOiO50MVN1ZcA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-046 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 18:53:56 INFO - Using env: {'DISPLAY': ':0', 18:53:56 INFO - 'HOME': '/home/cltbld', 18:53:56 INFO - 'LANG': 'en_US.UTF-8', 18:53:56 INFO - 'LANGUAGE': 'en_US:en', 18:53:56 INFO - 'LOGNAME': 'cltbld', 18:53:56 INFO - 'MAIL': '/var/mail/cltbld', 18:53:56 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:53:56 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:53:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:53:56 INFO - 'MOZ_NO_REMOTE': '1', 18:53:56 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:53:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:53:56 INFO - 'NO_EM_RESTART': '1', 18:53:56 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:53:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:53:56 INFO - 'PWD': '/builds/slave/test', 18:53:56 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:53:56 INFO - 'SHELL': '/bin/bash', 18:53:56 INFO - 'SHLVL': '1', 18:53:56 INFO - 'TERM': 'linux', 18:53:56 INFO - 'TMOUT': '86400', 18:53:56 INFO - 'USER': 'cltbld', 18:53:56 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1474336365.109353-1011090097', 18:53:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:53:56 INFO - '_': '/tools/buildbot/bin/python'} 18:53:56 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/aRfrZtnETwOiO50MVN1ZcA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-046', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 18:53:57 INFO - mozversion application_buildid: 20160919153004 18:53:57 INFO - mozversion application_changeset: b6fda98133de09b80069e0b921c866b6a0ee7e86 18:53:57 INFO - mozversion application_display_name: Nightly 18:53:57 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:53:57 INFO - mozversion application_name: Firefox 18:53:57 INFO - mozversion application_remotingname: firefox 18:53:57 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 18:53:57 INFO - mozversion application_vendor: Mozilla 18:53:57 INFO - mozversion application_version: 51.0a1 18:53:57 INFO - mozversion platform_buildid: 20160919153004 18:53:57 INFO - mozversion platform_changeset: b6fda98133de09b80069e0b921c866b6a0ee7e86 18:53:57 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 18:53:57 INFO - mozversion platform_version: 51.0a1 18:53:57 INFO - using testdate: 1474336437 18:53:57 INFO - actual date: 1474336437 18:53:57 INFO - starting webserver on 'localhost:56993' 18:53:57 INFO - SUITE-START | Running 2 tests 18:53:57 INFO - TEST-START | dromaeo_css 18:53:57 INFO - operating with platform_type : linux_ 18:53:57 INFO - Initialising browser for dromaeo_css test... 18:53:57 INFO - TEST-INFO | started process 23776 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpescX0B/profile http://localhost:56993/getInfo.html) 18:54:01 INFO - PROCESS | 23776 | __metrics Screen width/height:1600/1200 18:54:01 INFO - PROCESS | 23776 | colorDepth:24 18:54:01 INFO - PROCESS | 23776 | Browser inner width/height: 1024/697 18:54:01 INFO - PROCESS | 23776 | __metrics 18:54:02 INFO - PROCESS | 23776 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 18:54:02 INFO - PROCESS | 23776 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:54:02 INFO - PROCESS | 23776 | console.error: PushService: 18:54:02 INFO - PROCESS | 23776 | stateChangeProcessEnqueue: Error transitioning state 18:54:02 INFO - PROCESS | 23776 | UnknownError 18:54:03 INFO - TEST-INFO | 23776: exit 0 18:54:03 INFO - Browser initialized. 18:54:03 INFO - Running cycle 1/1 for dromaeo_css test... 18:54:03 INFO - TEST-INFO | started process 23861 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpescX0B/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:54:15 INFO - PROCESS | 23861 | RSS: Main: 173481984 18:54:15 INFO - PROCESS | 23861 | 18:54:19 INFO - PROCESS | 23861 | [GFX1-]: Refresh driver waiting for the compositor for 1.15003 seconds. 18:54:28 INFO - PROCESS | 23861 | [GFX1-]: Refresh driver waiting for the compositor for 1.03334 seconds. 18:54:44 INFO - PROCESS | 23861 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 18:55:01 INFO - PROCESS | 23861 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 18:55:04 INFO - PROCESS | 23861 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 18:55:06 INFO - PROCESS | 23861 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 18:55:06 INFO - PROCESS | 23861 | 1474336506497 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] 18:55:07 INFO - PROCESS | 23861 | [GFX1-]: Refresh driver waiting for the compositor for 1.16669 seconds. 18:55:09 INFO - PROCESS | 23861 | [GFX1-]: Refresh driver waiting for the compositor for 1.08333 seconds. 18:55:23 INFO - PROCESS | 23861 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 18:55:40 INFO - PROCESS | 23861 | [GFX1-]: Refresh driver waiting for the compositor for 1.03337 seconds. 18:55:44 INFO - PROCESS | 23861 | [GFX1-]: Refresh driver waiting for the compositor for 1.01667 seconds. 18:55:45 INFO - PROCESS | 23861 | [GFX1-]: Refresh driver waiting for the compositor for 2.03335 seconds. 18:55:47 INFO - PROCESS | 23861 | [GFX1-]: Refresh driver waiting for the compositor for 1.1667 seconds. 18:55:58 INFO - PROCESS | 23861 | [GFX1-]: Refresh driver waiting for the compositor for 1.04999 seconds. 18:56:08 INFO - PROCESS | 23861 | [GFX1-]: Refresh driver waiting for the compositor for 1.03337 seconds. 18:56:21 INFO - PROCESS | 23861 | [GFX1-]: Refresh driver waiting for the compositor for 1.18333 seconds. 18:56:24 INFO - PROCESS | 23861 | [GFX1-]: Refresh driver waiting for the compositor for 2.00005 seconds. 18:56:27 INFO - PROCESS | 23861 | [GFX1-]: Refresh driver waiting for the compositor for 1.05003 seconds. 18:56:31 INFO - PROCESS | 23861 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 18:56:33 INFO - PROCESS | 23861 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 18:56:35 INFO - PROCESS | 23861 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 18:57:08 INFO - PROCESS | 23861 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 18:57:16 INFO - PROCESS | 23861 | Cycle 1(1): loaded http://localhost:56993/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56993/tests/dromaeo/cssquery-ext.html) 18:57:17 INFO - PROCESS | 23861 | RSS: Main: 292507648 18:57:17 INFO - PROCESS | 23861 | 18:57:20 INFO - PROCESS | 23861 | [GFX1-]: Refresh driver waiting for the compositor for 1.01668 seconds. 18:57:27 INFO - PROCESS | 23861 | [GFX1-]: Refresh driver waiting for the compositor for 1.03336 seconds. 18:57:37 INFO - PROCESS | 23861 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 18:57:42 INFO - PROCESS | 23861 | [GFX1-]: Refresh driver waiting for the compositor for 1.03334 seconds. 18:57:44 INFO - PROCESS | 23861 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 18:57:46 INFO - PROCESS | 23861 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 18:57:50 INFO - PROCESS | 23861 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 18:57:58 INFO - PROCESS | 23861 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 18:58:10 INFO - PROCESS | 23861 | [GFX1-]: Refresh driver waiting for the compositor for 1.01668 seconds. 18:58:14 INFO - PROCESS | 23861 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 18:58:56 INFO - PROCESS | 23861 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 18:59:03 INFO - PROCESS | 23861 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 18:59:12 INFO - PROCESS | 23861 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 18:59:35 INFO - PROCESS | 23861 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 18:59:37 INFO - PROCESS | 23861 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 18:59:40 INFO - PROCESS | 23861 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 18:59:41 INFO - PROCESS | 23861 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 18:59:45 INFO - PROCESS | 23861 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 19:00:07 INFO - PROCESS | 23861 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 19:00:19 INFO - PROCESS | 23861 | Cycle 1(1): loaded http://localhost:56993/tests/dromaeo/cssquery-ext.html (next: http://localhost:56993/tests/dromaeo/cssquery-jquery.html) 19:00:20 INFO - PROCESS | 23861 | RSS: Main: 279433216 19:00:20 INFO - PROCESS | 23861 | 19:00:22 INFO - PROCESS | 23861 | [GFX1-]: Refresh driver waiting for the compositor for 1.01674 seconds. 19:00:26 INFO - PROCESS | 23861 | [GFX1-]: Refresh driver waiting for the compositor for 1.03335 seconds. 19:00:34 INFO - PROCESS | 23861 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 19:00:36 INFO - PROCESS | 23861 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 19:00:44 INFO - PROCESS | 23861 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 19:00:47 INFO - PROCESS | 23861 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 19:00:48 INFO - PROCESS | 23861 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 19:00:58 INFO - PROCESS | 23861 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 19:01:07 INFO - PROCESS | 23861 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 19:01:17 INFO - PROCESS | 23861 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 19:01:55 INFO - PROCESS | 23861 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 19:01:57 INFO - PROCESS | 23861 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 19:02:31 INFO - PROCESS | 23861 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 19:02:41 INFO - PROCESS | 23861 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 19:02:49 INFO - PROCESS | 23861 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 19:02:54 INFO - PROCESS | 23861 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 19:03:02 INFO - PROCESS | 23861 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 19:03:04 INFO - PROCESS | 23861 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 19:03:10 INFO - PROCESS | 23861 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 19:03:20 INFO - PROCESS | 23861 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 19:03:22 INFO - PROCESS | 23861 | Cycle 1(1): loaded http://localhost:56993/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56993/tests/dromaeo/cssquery-mootools.html) 19:03:22 INFO - PROCESS | 23861 | RSS: Main: 278638592 19:03:22 INFO - PROCESS | 23861 | 19:03:28 INFO - PROCESS | 23861 | [GFX1-]: Refresh driver waiting for the compositor for 1.03337 seconds. 19:03:35 INFO - PROCESS | 23861 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 19:03:45 INFO - PROCESS | 23861 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 19:04:39 INFO - PROCESS | 23861 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 19:04:58 INFO - PROCESS | 23861 | [GFX1-]: Refresh driver waiting for the compositor for 1.00012 seconds. 19:05:00 INFO - PROCESS | 23861 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 19:05:14 INFO - PROCESS | 23861 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 19:05:24 INFO - PROCESS | 23861 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 19:05:32 INFO - PROCESS | 23861 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 19:05:52 INFO - PROCESS | 23861 | [GFX1-]: Refresh driver waiting for the compositor for 1.01666 seconds. 19:06:00 INFO - PROCESS | 23861 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 19:06:02 INFO - PROCESS | 23861 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 19:06:06 INFO - PROCESS | 23861 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 19:06:22 INFO - PROCESS | 23861 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 19:06:24 INFO - PROCESS | 23861 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 19:06:24 INFO - PROCESS | 23861 | Cycle 1(1): loaded http://localhost:56993/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56993/tests/dromaeo/cssquery-prototype.html) 19:06:25 INFO - PROCESS | 23861 | RSS: Main: 221310976 19:06:25 INFO - PROCESS | 23861 | 19:06:38 INFO - PROCESS | 23861 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 19:06:47 INFO - PROCESS | 23861 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 19:06:52 INFO - PROCESS | 23861 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 19:06:54 INFO - PROCESS | 23861 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 19:06:58 INFO - PROCESS | 23861 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 19:07:12 INFO - PROCESS | 23861 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 19:07:20 INFO - PROCESS | 23861 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 19:07:22 INFO - PROCESS | 23861 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 19:07:24 INFO - PROCESS | 23861 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 19:07:26 INFO - PROCESS | 23861 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 19:07:33 INFO - PROCESS | 23861 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 19:07:43 INFO - PROCESS | 23861 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 19:08:03 INFO - PROCESS | 23861 | [GFX1-]: Refresh driver waiting for the compositor for 1.01668 seconds. 19:08:12 INFO - PROCESS | 23861 | [GFX1-]: Refresh driver waiting for the compositor for 1.01668 seconds. 19:08:19 INFO - PROCESS | 23861 | [GFX1-]: Refresh driver waiting for the compositor for 1.01668 seconds. 19:08:29 INFO - PROCESS | 23861 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 19:08:45 INFO - PROCESS | 23861 | [GFX1-]: Refresh driver waiting for the compositor for 1.01667 seconds. 19:08:49 INFO - PROCESS | 23861 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 19:08:53 INFO - PROCESS | 23861 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 19:09:28 INFO - PROCESS | 23861 | Cycle 1(1): loaded http://localhost:56993/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56993/tests/dromaeo/cssquery-yui.html) 19:09:28 INFO - PROCESS | 23861 | RSS: Main: 222498816 19:09:28 INFO - PROCESS | 23861 | 19:09:32 INFO - PROCESS | 23861 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 19:09:37 INFO - PROCESS | 23861 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 19:09:44 INFO - PROCESS | 23861 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 19:09:52 INFO - PROCESS | 23861 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 19:09:59 INFO - PROCESS | 23861 | [GFX1-]: Refresh driver waiting for the compositor for 1.00007 seconds. 19:10:01 INFO - PROCESS | 23861 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 19:10:15 INFO - PROCESS | 23861 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 19:10:42 INFO - PROCESS | 23861 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 19:11:00 INFO - PROCESS | 23861 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 19:11:08 INFO - PROCESS | 23861 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 19:11:42 INFO - PROCESS | 23861 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 19:11:50 INFO - PROCESS | 23861 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 19:11:57 INFO - PROCESS | 23861 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 19:12:07 INFO - PROCESS | 23861 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 19:12:31 INFO - PROCESS | 23861 | Cycle 1(1): loaded http://localhost:56993/tests/dromaeo/cssquery-yui.html (next: http://localhost:56993/tests/dromaeo/cssquery-dojo.html) 19:12:32 INFO - PROCESS | 23861 | RSS: Main: 310218752 19:12:32 INFO - PROCESS | 23861 | 19:12:32 INFO - PROCESS | 23861 | __start_tp_report 19:12:32 INFO - PROCESS | 23861 | _x_x_mozilla_page_load 19:12:32 INFO - PROCESS | 23861 | _x_x_mozilla_page_load_details 19:12:32 INFO - PROCESS | 23861 | |i|pagename|runs| 19:12:32 INFO - PROCESS | 23861 | |0;dojo.html;2949.6607890092637;3001.9249518762026;3028.106708520985;3061.984690076549;3069.95395069074;5155.278261043457;5217.113090774568;5243.842684719455;5286.497782710641;5344;5243.501867322608;5304.230886521462;5335.039692855285;5338.359396872371;5433.130699088147;4791.28130780384;4795.2807078938085;4834.299026641143;4850.126977144113;4855.247436647325;4765.046990601893;4858.1983972644475;4890.853274401774;4894.608431325485;4928.482509336504;4850.684705494132;4924.803007879698;4940.827071052514;4979.7261150636705;4990.900181996358;4612.838550650745;4622.884427889296;4646.581807637329;4768.094062128184;4871.756412179412;4755.0489902019735;4769.57073863354;4779.354787103731;4806.038792241566;4857.979824236914;5259.710715910624;5299.390570084451;5299.867503312448;5301.73491325436;5328.866778330534;5444.128939369722;5498.862528436821;5632.802851900164;5638.830835074954;5640.915386269209;5623.465770751772;5660.066089095294;5663.235463212496;5673.773049078032;5734.885302293931;5284.392294886099;5287.021900948337;5289.656172348862;5292.629515933886;5292.788288468418;5491.75287112073;5786.132080188006;5786.1785106076195;5795.304563452412;5849.707514624337;5324.653897496728;5332.9733351333;5378.650387724785;5379.67721936685;5386.569074474032;5444.646098003617;5612;5613.438656134354;5661.830145095653;5739.827805165852;5626.774929002876;5633.042382794934;5636.605437619327;5638.6052976292085;5641.548676105902;5609.242752228397;5671.120976248688;5672.914906276326;5673.801416950387;5731.570132240098;4266.765327906933;4315.28797748368;4353.434053573079;4395.7362558246605;4407.6914615976575;3912.39357899526;3919.9804000980384;3924.50943632046;3957.5053118360206;3973.304671682502;5369.48989845964;5382.381026181961;5386.946130538723;5387.326584176978;5449.182622606561;5410.83767486976;5431.456854314537;5443.42844001382;5545.057340252167;5628.97185514078;5391.433899440581;5391.622586418992;5392.137258038773;5430.15832545956;5457.2359869618285;4946.431160416526;4957.752112394366;4958.157113290749;4959.628027897922;5014.323066386064;5711.742971566289;5749.741261643152;5762.769489220468;5878.412158784173;5909.231799866076;5365.275687782099;5604.383517812956;5606;5675.77296908119;5677.858053548694;5792.0443126885275;5797.391273916178;5798.536117110537;5800.4489573490455;5800.796972105956;5372.382176049648;5467.234587157722;5507.697076660662;5509.834704958857;5516.365617953987;5137.897242055213;5603.243562119224;5659.349174844946;5708.372079071381;5769.07694768835;5551.084071128219;5566.081596536526;5566.109422492544;5576.10782274834;5578.944210558005;5387.191921211849;5537.6954267515675;5541.667499950016;5546.057170280981;5555.777768889361;5364.30264065669;5389.973050134724;5409.107497263065;5424.891502170013;5513.61404701667;5465.508104270635;5496.285482887199;5532.087205611029;5541.445855414427;5551.417101204315;5211.166213405837;5328.893422131613;5354.3842458117815;5388.110961691277;5397.163439666858 19:12:32 INFO - PROCESS | 23861 | |1;ext.html;568.2612603615299;581.8690794571222;583.7635757518207;596.0671549025773;598.7365559153972;31372.84313578432;42156.36765448517;43837;43866.342004869955;47189.76405117969;44389.33415998763;45041;45060.549394506044;45077.54922450775;45089.32366014504;7974.800629984268;8862.689805856797;9007.459552426842;9036.50299233542;9036.77408064797;8004.679812807481;8243.175682431769;8250.711225107123;10589.1528677706;10868.239223254375;10801;10872.347659140438;10898.455077246106;10899;10900.236983411165;10597.470126493645;10681.572737090584;10699.090577300938;10706.41114738689;10772.353658780497;10524.473776311153;10595.205359598061;10601.363918164934;10611.681649550526;10614.097801686867;40944.181116377506;41039.58960410417;41250.58749412497;41333.379999300036;41337.79331103355;3368.9663103369144;3463.930721385583;3469.774464659789;3495.598006229265;3529.858805647771;31703.84148079268;32054.35891282161;32903.6709632903;33227.50158747621;33944.15139621504;4263.850765223202;4354.259775838115;4370.978145109286;4379.032338234269;4386.495553011413;332860;333706;334206;334233;334568;57342.71328643413;57447;57774;57978.42021579815;58145.70927145337;59586;59697;60056;60076.69961650164;60091.39908600946;16635.41776037833;16920.15399230058;16925.830741692425;16943.915280423764;16944.49166525006;12850.357482125857;13005.024623153302;13008.284544350152;13008.674783130498;13013.47946082165;15289.235538223043;15633.296501657453;16955.745663815054;17280.481585552454;17417.129143543025;4967.627427942846;6043.305019922677;6091.451769340781;6152.661603611844;6260;930.437085563238;1017.4048181813574;1063.5745701719375;1073.2004656530935;1114.4761961877905;30729.69270307313;31556;31647.367052658818;31692.683073169435;32348.514772278435;1024.5031159887449;1071.23406764164;1100.2078503477483;1132.5130194016651;1144.8494263265366;15356.61608459775;16211.594710132342;16327;16366.263518141712;16435.212897561745;483278;487897.4523057256;488098;488506;489258;50027;51018;55653.72173139108;56500.71749641225;56719.716401418555;1305.3147097773744;1327.9468821247042;1333.4666133546466;1339.3102552185633;1354.2622362280179;29988.621852998247;32513.512297315086;32813.67186328106;32845.50731739074;33186.17034574155;51262;53722;55155.72422137864;55565.72217138889;61365;456402;459407;460269;460520;461170;28301.43397132087;28499.572506412005;28552.42895142044;28774.424511509234;28905.277368065967;46380.30429543627;47556;48206.93827148099;48339.75830120827;49660.25509617286;40645.39031914583;41222.38166427566;41936.790316048224;42867.785661071495;43522.12955740931;40815.38776918408;41447.90801946622;41688.58311416847;41897.87736084812;42613.57386426096;40115.79942100271;40784.79607601943;41228.58771412368;41269.793651031556;41410.58589414188;34652.82673586616;36199.63800362068;36320;36900.096722406866;37438.43842342422;27117.322066948484;27301.726982729917;27376.863115684293;27402.862985684944;27454.313642158308 19:12:32 INFO - PROCESS | 23861 | |2;jquery.html;362.7043959173272;379.49906123916423;386.80659670164926;389.95320561532617;415.61970796721;14211.502597409093;17966.281348746066;18244.17901194446;18375.44873653789;19498.317558885443;18112.90943545284;19100.235990560395;19137.52156196096;19282;19466.221351145938;5639.7180140993;5892.558058145635;5896.587238893279;5902.144189092579;5902.645841249518;5612.07400778871;5777.1623114648355;5783.21926539916;5826.563007774413;5877;5780.595358324919;5782.9421705782825;5783.277090363705;5783.479486846183;5788.102844059156;5590.720463976805;5700.201971723962;5778.624389414675;5779.855503612402;5783.5951483396175;5123.2827404163445;5126.6923984561045;5131.076406246874;5141;5141.048905952411;22615.434614134614;22626.434339141488;22655.546889062127;22736.65895011576;22928.31215063551;2303.2514432809235;2502.311864237331;3049.6797836227097;3054.8930787422555;3221.9677803221903;14181.78727319091;14275.14349139055;14463.27683615815;14510.709785804225;14531.709365812625;1884.5571290746664;1900.4488698277482;1955;1965.5380985468403;2017.505711100775;80408.79386809202;80977.59511202465;81055;82950;83040;9649.276304277208;9831.360961537477;9964.850527242097;10126.291159618904;10271.897281027244;13677.24775137357;13693.589192324247;13703.45186192561;13708.24604646734;13733.107348022346;7757.534547927143;7985.760427187193;8085.272325490734;8234.752957411167;8266;7837.843243135219;7845.7253996111;7996.760097197093;8143.389245806588;8171.713990010321;6074.54440916933;6162.845928851814;6248.656323902138;6322.146510221153;6424.196975378078;1816.1191821966336;1824.744535764994;1834.8990805505825;1855.6103218324304;1880.2009146112848;928.8420968435382;935.4340623922565;944.2351695126969;976.1653786012998;1051.75283808305;18222.817771822378;19712.507187320145;19843.30548430798;19851.20595176206;20223.4944126398;969.4667932637021;990.4354517924858;993.6373223773415;1000.8174006658894;1027.3938376357985;10519.31624444424;10916.945415272872;11236.628711609903;11253.606123785628;11308.86911308879;475653;480030;488259;488457;498446;45391.773041134584;45962.08075838465;46288.53711462842;52049;82376;1001.5142655811925;1013.6654903881702;1024.7233247023412;1034.1003327105316;1039.2050081687514;16595.751063733813;16611.750823737413;17357.13214339303;17702.91148544249;17816.46550603484;39343.60656393514;39593;39982.60017399905;40889.795551022056;41068;51228.48771512386;51688;51754;52032.73983630209;52110.73944630253;8355.206255405727;8407.2433480987;8435.789105272417;8601.82796344082;8845.159709827556;13243.536476223286;13369.866301336862;13465.798013029616;13602.115862468909;13710.725785484434;11300.152488563555;11372.374519401512;11717.414129293671;11720.88279117198;12296;11466.140039496904;11558.248713833575;11739.413029348669;11751.706207344885;12223.44994475233;10688.465576721288;11550.59572914944;11664.76670466569;11973.341466219093;12025.278483291031;7362.3741981932135;7688.730894418669;7875.094364147967;8428.283595894172;8936.821263574822;8824.720010794888;10966.287191332538;11314.321140731583;11542.769144617228;11852.881471185523 19:12:32 INFO - PROCESS | 23861 | |3;mootools.html;1341.7182391697743;1436.3033928544658;1481.6221863424828;1592.2198122919772;1739.2083912322385;7734.574598397093;8473.78815529612;8632.913670863281;8758.343124265673;8997.190252877239;8797.472151670889;8841.5579221039;8868.423552469085;8870.246029087535;8872.556372181398;2339.3215967369442;2354.3290162303747;2361.421451744325;2365.31405892291;2378.369732021016;2258.762829902861;2280.5894938910988;2332.5334933013464;2334.708161479815;2342.051469154995;2308.849924754894;2319.82601304902;2337.7662233776655;2340.1341503643675;2340.871252081135;2257.0294773247497;2258.8644681319092;2271.44349634339;2280.6464997925264;2283.794458498735;2254.334971183505;2256.323103069089;2258.2209137847417;2260.7626199249007;2263.8868056597103;8583.871241931376;8782.209601135928;8793.120687931156;8844.955775221148;8856.51289179095;5236.423993360765;5447.727613619343;5448.182772584105;5461.863453413696;5466.398696143461;3406.2165701888703;3432.1076520104702;3466.9826650866835;3469.8612055517747;3485.3900567400606;1221.688469440287;1223.3027174510532;1264.500522293698;1267.993660031694;2136.9786302136936;11741.941290293495;12261.203021803554;12266.448009839578;12277.38613069352;12281.938590307082;4543.477500087466;4667.066586682677;4685.1332503486965;4723.527647235249;4753.453352864396;9805.509724513746;9860.556274967643;9872.753181170385;9881.901180988241;9884.30809843304;5289.814856480005;5290.1800220965815;5304.814331498379;5308.654937429054;5314.0700377433795;4398.8020539075815;4540.205464043779;4785.186518291899;4889.437714662859;5001.44984051754;3672.889813305605;3797.3924172132324;3838.7312888097563;3843.3466310727244;3902.3366027775346;1188.8989435897872;1200.4177973682756;1201.8377519034818;1218.79889818179;1225.3811825028304;906.4651855405342;954.3319676226669;972.5380444288907;980.0689345122163;983.0562659846628;5907.1139329100115;5946.464818166386;6001.219841420588;6001.399860014051;6273.404026617464;456.2881904229413;461.73681001829306;480.6299149654816;489.11714355587793;490.69576862345576;4200.075983283673;4261.27558315081;4280.72175308604;4305.332673435622;4306.246406878847;10655.254132210826;10897.073748731287;10898.564057437772;10963.342199467897;10980.450977451255;10184.745381365525;10703.571857125784;10910.945445272722;10925.453727313603;10944.726381840517;7990.64042118106;8144.429889907649;8147.633356498972;8154.918450815535;8182.590870456454;6022.518198544193;6045.546584006305;6103.847403814762;6129.969350153221;6270.843228919313;9653.203426038783;9975.750606234902;9993.500324983866;10088.747781305527;10140.949295253477;10336.948315258376;10341.689749307532;10366.378017318984;10378.636747713794;10438.425886576008;7040.258350834222;7155.534890232118;7604.923950760643;7784.805379865548;7906.5651389174145;8377.45546539473;8569.82860342802;8571.057183709912;8576;8579.485230886166;7516.73040152961;7691.30778229962;7850.057993040871;7902.407319450949;7909.209079092045;7503.362214211853;7505.624718763931;7541.245875412635;7591.506552074097;7607.23927607257;6908.758193463204;6988.510804243654;6988.720451182099;7006.124234470692;7060.71757129709;5064;5096.337476128079;5112.207607820794;5120.641555091108;5375.704336261387;5110.872228194325;5166;5242.711650859087;5261.079311120615;5387.92241551699 19:12:32 INFO - PROCESS | 23861 | |4;prototype.html;324.4711120872977;327.08579520240926;334.92464195555993;337.9307242015384;339.7248228934563;5155.845324640262;5164.9741751291285;5170.457102004292;5196.350456192976;5202.609804264681;5137.58899288057;5165.922511162326;5166.844994650157;5188.195829646411;5200.765965531551;3840.961590384088;4115.773632450214;4119.8970025749295;4127.834886604533;4150.377443383486;4048.149888523399;4087.816048277835;4089.9386509202386;4090.4477895484174;4095.201435720036;4007.298722723526;4023.6982226333;4037.535683396403;4038.2125485530332;4051.898702532431;3815.9046023849346;3933.547642021147;3957.1096503286817;4032.1976226522434;4066.207089617526;3547.9822600886832;3549.396602577568;3559.555055618048;3561.1631266652316;3565.449029672101;5008;5039.546440820307;5039.72281524516;5068.3917929848285;5078.365204349457;4319.704088773356;4425.557444255581;4605.562471565229;4636.884077898046;4648.930266046012;5345.251664766935;5363.383210930715;5365.919511207335;5372.811951581676;5391.730413479349;3894.279558281727;3894.5910679378826;3903.355946268862;3914.608539146091;3922.8038598069984;5539.224508568804;5560.555155587584;5566.387697353288;5582.581306402036;5624.21261023457;3959.1685745993664;3965.7025723070883;3969.325214713506;3988;3988.222296652125;4021.1354558770004;4042.939355909664;4044.5550989391145;4045.6561192298986;4045.7774822384463;4554.248548989446;4602.700824446467;4650.372199753057;4658.883527911829;4658.976705116453;5333.359996800331;5377.7580008899695;5386.165144402624;5406.4321346809775;5432.456754324615;1369.7123604043263;1387.6045327081733;1389.4650559534653;1394.4730520080495;1397;845.9746207613781;899.3030401438937;902.5126431726925;907.5008745190119;913.2191975860676;4590.334401511762;4640.9535904640525;4644.95621926358;4663.836765713184;4695.131400690883;4886.193778026657;4897.804087836446;4899.58353539946;4925.901481970341;4945.604351651859;4364.847230346986;4654.743989080633;4668.276417155346;4679.485256621769;4680.578747912636;2592.611108333765;2815.943681126366;2864.4271145770513;2868.922008045747;2875.9856200718864;5588.357338905997;5628.352739434936;5723.5707321950185;5761.452661997104;5786.971065144731;5502.119660854244;5592.748326325325;5627.859303517363;5631.859203519863;5663.876046482569;5723.656580605094;5754.309482862;5782.942170578241;5790.536757059509;5833.82498525045;5508.256385388082;5549.195366671891;5564.38791732901;5567.218490083134;5570.470805273492;5302.030862716891;5411.132099987013;5460.781568737206;5462.8361149165585;5501.386841250374;5700.828975130753;5731.226284451713;5752.252207213036;5769.451902069297;5778.768849245983;3983.50206224222;4026.8590599330055;4032.858849940238;4038.273110840077;4038.757674539537;5312.389075256395;5570.637908535932;5588.720563971867;5612.186232996111;5628.324601047901;5450.863728406822;5464.617476776588;5468.578169675992;5470.890582188414;5478.698671573102;5236.162214045735;5453.672779633234;5459.863503412446;5474.835754927359;5485.533729632866;5350.625456218183;5356.705381204071;5364.746806341424;5391.272178255886;5400.513953744182;5493.450654934634;5498;5502.449755024466;5520.917186242129;5531.557475402039;5237;5358.089124848707;5362.892742145214;5374.220737992891;5381.67709937405 19:12:32 INFO - PROCESS | 23861 | |5;yui.html;781.2968328504342;784.3489903380193;791.3866753266218;822.8847961285418;836.5858899844574;15689;16707.83292167078;16714.41499547516;16720.498385048468;16734.66530669389;14907.478238261663;15094.09435433872;15199.696006079872;15252.466163684274;15700.607484812885;798.6006996501749;824.9546274954876;829.0217543298935;829.4359835312016;830.024720952881;658.5357323087236;679.1239301301315;818.5702506184241;819.1685439279137;819.9180081991813;812.4069429316603;846.4540371460404;851.5657014922373;851.6210286422545;851.6465666748323;845.8392905347964;849.2865992566274;849.371465115816;850.090403268503;850.6129710981488;498.16308601549383;511.3327108123897;619.2321521313539;619.2661695890384;619.2785405003166;14325.85674143266;14747.55757327282;14764.483243086548;15106;15129.243537823175;13534.458621655122;14024.929875350557;14605.415783368653;14877;14887.925560372129;11857.881421185764;12144.757104857941;12758.872411275861;12860.913380788637;13032.934835325857;3788.4317352397084;3824.9235015299537;3856.8071596420345;3907.651860108258;4037.1118353962083;87678.123218767;87700;88388.55805720994;89402.10597894003;89435;11285.097192224766;11316.151288653384;11413.372264525366;11522.654320370402;11618.88381116195;21926.122955081937;21948.122075116815;21995.890020549796;22019.779802201774;22026.779732202795;7258.854822903512;8073;8431.831363372698;8464.830703385898;8475.83048339042;4411.713238639478;4617.7691115444095;4665.930011049837;4703.388559487314;4856.975715121402;3642.4718415829557;3745.831437585315;3747.512823333004;3756.966834120639;3759.4360845872793;596.9820905372845;600.4055984575326;602.3073465514693;623.1058431151313;624.2290770897937;673.4074014866886;746.119578896896;756.1417790807474;781.6209138567904;783.115079959642;9786.461744604116;9998.350107243006;10031.247656425798;10066.44634545107;10166.746641074933;618.3538202578317;635.6345101566618;707.1090426063197;729.4492658043249;750.8610906982226;4996.250562415594;5044.873878153002;5118.744062796845;5132.10188217068;5149;108731.45634271778;118858;123151;123600.38199808943;127554;102139.48930255302;103616;108705;110226;115260.42369788098;90891;93965;95308.57037144448;95449.318547701;96258;8980.416272942239;9029.909700902906;9126.17864392208;9157.816843662957;9209.815803683754;55306;55338;55538;56080;59146.704266478395;76618;77222.22777772375;82186;84463.57768211365;85144;11689.941550292195;11868.465919033491;12986.350682466027;13262.53581124656;13395.598132056053;15168.469103581763;15209.847901520843;15236.162011089496;15559.688806224038;15752.527424177293;13368.398422070835;13675.384607692478;14297.35661895238;14433;14509.782353264498;12951;13272;13585.728285434434;13852.792208117087;14484.637884052983;13091.149075310075;13351.933240333736;13533.932330338286;14122.646933826736;14138.717225655224;11572.305661660328;11789.587364442203;11876.346800926032;11955.76088478243;12136.453859576164;11769.823452648388;11976.221545599508;12082.697932551757;12136.33250171249;12422.316772577595 19:12:32 INFO - PROCESS | 23861 | __end_tp_report 19:12:32 INFO - PROCESS | 23861 | __start_cc_report 19:12:32 INFO - PROCESS | 23861 | _x_x_mozilla_cycle_collect,960 19:12:32 INFO - PROCESS | 23861 | __end_cc_report 19:12:32 INFO - PROCESS | 23861 | __startTimestamp1474337552165__endTimestamp 19:12:32 INFO - PROCESS | 23861 | 19:12:32 INFO - PROCESS | 23861 | ------- Summary: start ------- 19:12:32 INFO - PROCESS | 23861 | Number of tests: 6 19:12:32 INFO - PROCESS | 23861 | 19:12:32 INFO - PROCESS | 23861 | [#0] dojo.html Cycles:165 Average:5234.16 Median:5389.97 stddev:577.14 (10.7%) stddev-sans-first:550.38 19:12:32 INFO - PROCESS | 23861 | Values: 2949.7 3001.9 3028.1 3062.0 3070.0 5155.3 5217.1 5243.8 5286.5 5344.0 5243.5 5304.2 5335.0 5338.4 5433.1 4791.3 4795.3 4834.3 4850.1 4855.2 4765.0 4858.2 4890.9 4894.6 4928.5 4850.7 4924.8 4940.8 4979.7 4990.9 4612.8 4622.9 4646.6 4768.1 4871.8 4755.0 4769.6 4779.4 4806.0 4858.0 5259.7 5299.4 5299.9 5301.7 5328.9 5444.1 5498.9 5632.8 5638.8 5640.9 5623.5 5660.1 5663.2 5673.8 5734.9 5284.4 5287.0 5289.7 5292.6 5292.8 5491.8 5786.1 5786.2 5795.3 5849.7 5324.7 5333.0 5378.7 5379.7 5386.6 5444.6 5612.0 5613.4 5661.8 5739.8 5626.8 5633.0 5636.6 5638.6 5641.5 5609.2 5671.1 5672.9 5673.8 5731.6 4266.8 4315.3 4353.4 4395.7 4407.7 3912.4 3920.0 3924.5 3957.5 3973.3 5369.5 5382.4 5386.9 5387.3 5449.2 5410.8 5431.5 5443.4 5545.1 5629.0 5391.4 5391.6 5392.1 5430.2 5457.2 4946.4 4957.8 4958.2 4959.6 5014.3 5711.7 5749.7 5762.8 5878.4 5909.2 5365.3 5604.4 5606.0 5675.8 5677.9 5792.0 5797.4 5798.5 5800.4 5800.8 5372.4 5467.2 5507.7 5509.8 5516.4 5137.9 5603.2 5659.3 5708.4 5769.1 5551.1 5566.1 5566.1 5576.1 5578.9 5387.2 5537.7 5541.7 5546.1 5555.8 5364.3 5390.0 5409.1 5424.9 5513.6 5465.5 5496.3 5532.1 5541.4 5551.4 5211.2 5328.9 5354.4 5388.1 5397.2 19:12:32 INFO - PROCESS | 23861 | 19:12:32 INFO - PROCESS | 23861 | [#1] ext.html Cycles:180 Average:59103.04 Median:37169.27 stddev:114332.45 (307.6%) stddev-sans-first:114568.72 19:12:32 INFO - PROCESS | 23861 | Values: 568.3 581.9 583.8 596.1 598.7 31372.8 42156.4 43837.0 43866.3 47189.8 44389.3 45041.0 45060.5 45077.5 45089.3 7974.8 8862.7 9007.5 9036.5 9036.8 8004.7 8243.2 8250.7 10589.2 10868.2 10801.0 10872.3 10898.5 10899.0 10900.2 10597.5 10681.6 10699.1 10706.4 10772.4 10524.5 10595.2 10601.4 10611.7 10614.1 40944.2 41039.6 41250.6 41333.4 41337.8 3369.0 3463.9 3469.8 3495.6 3529.9 31703.8 32054.4 32903.7 33227.5 33944.2 4263.9 4354.3 4371.0 4379.0 4386.5 332860.0 333706.0 334206.0 334233.0 334568.0 57342.7 57447.0 57774.0 57978.4 58145.7 59586.0 59697.0 60056.0 60076.7 60091.4 16635.4 16920.2 16925.8 16943.9 16944.5 12850.4 13005.0 13008.3 13008.7 13013.5 15289.2 15633.3 16955.7 17280.5 17417.1 4967.6 6043.3 6091.5 6152.7 6260.0 930.4 1017.4 1063.6 1073.2 1114.5 30729.7 31556.0 31647.4 31692.7 32348.5 1024.5 1071.2 1100.2 1132.5 1144.8 15356.6 16211.6 16327.0 16366.3 16435.2 483278.0 487897.5 488098.0 488506.0 489258.0 50027.0 51018.0 55653.7 56500.7 56719.7 1305.3 1327.9 1333.5 1339.3 1354.3 29988.6 32513.5 32813.7 32845.5 33186.2 51262.0 53722.0 55155.7 55565.7 61365.0 456402.0 459407.0 460269.0 460520.0 461170.0 28301.4 28499.6 28552.4 28774.4 28905.3 46380.3 47556.0 48206.9 48339.8 49660.3 40645.4 41222.4 41936.8 42867.8 43522.1 40815.4 41447.9 41688.6 41897.9 42613.6 40115.8 40784.8 41228.6 41269.8 41410.6 34652.8 36199.6 36320.0 36900.1 37438.4 27117.3 27301.7 27376.9 27402.9 27454.3 19:12:32 INFO - PROCESS | 23861 | 19:12:32 INFO - PROCESS | 23861 | [#2] jquery.html Cycles:180 Average:27667.66 Median:383.15 stddev:79572.71 (20767.9%) stddev-sans-first:79769.52 19:12:32 INFO - PROCESS | 23861 | Values: 362.7 379.5 386.8 390.0 415.6 14211.5 17966.3 18244.2 18375.4 19498.3 18112.9 19100.2 19137.5 19282.0 19466.2 5639.7 5892.6 5896.6 5902.1 5902.6 5612.1 5777.2 5783.2 5826.6 5877.0 5780.6 5782.9 5783.3 5783.5 5788.1 5590.7 5700.2 5778.6 5779.9 5783.6 5123.3 5126.7 5131.1 5141.0 5141.0 22615.4 22626.4 22655.5 22736.7 22928.3 2303.3 2502.3 3049.7 3054.9 3222.0 14181.8 14275.1 14463.3 14510.7 14531.7 1884.6 1900.4 1955.0 1965.5 2017.5 80408.8 80977.6 81055.0 82950.0 83040.0 9649.3 9831.4 9964.9 10126.3 10271.9 13677.2 13693.6 13703.5 13708.2 13733.1 7757.5 7985.8 8085.3 8234.8 8266.0 7837.8 7845.7 7996.8 8143.4 8171.7 6074.5 6162.8 6248.7 6322.1 6424.2 1816.1 1824.7 1834.9 1855.6 1880.2 928.8 935.4 944.2 976.2 1051.8 18222.8 19712.5 19843.3 19851.2 20223.5 969.5 990.4 993.6 1000.8 1027.4 10519.3 10916.9 11236.6 11253.6 11308.9 475653.0 480030.0 488259.0 488457.0 498446.0 45391.8 45962.1 46288.5 52049.0 82376.0 1001.5 1013.7 1024.7 1034.1 1039.2 16595.8 16611.8 17357.1 17702.9 17816.5 39343.6 39593.0 39982.6 40889.8 41068.0 51228.5 51688.0 51754.0 52032.7 52110.7 8355.2 8407.2 8435.8 8601.8 8845.2 13243.5 13369.9 13465.8 13602.1 13710.7 11300.2 11372.4 11717.4 11720.9 12296.0 11466.1 11558.2 11739.4 11751.7 12223.4 10688.5 11550.6 11664.8 11973.3 12025.3 7362.4 7688.7 7875.1 8428.3 8936.8 8824.7 10966.3 11314.3 11542.8 11852.9 19:12:32 INFO - PROCESS | 23861 | 19:12:32 INFO - PROCESS | 23861 | [#3] mootools.html Cycles:180 Average:5774.43 Median:2746.07 stddev:3270.15 (119.1%) stddev-sans-first:3262.35 19:12:32 INFO - PROCESS | 23861 | Values: 1341.7 1436.3 1481.6 1592.2 1739.2 7734.6 8473.8 8632.9 8758.3 8997.2 8797.5 8841.6 8868.4 8870.2 8872.6 2339.3 2354.3 2361.4 2365.3 2378.4 2258.8 2280.6 2332.5 2334.7 2342.1 2308.8 2319.8 2337.8 2340.1 2340.9 2257.0 2258.9 2271.4 2280.6 2283.8 2254.3 2256.3 2258.2 2260.8 2263.9 8583.9 8782.2 8793.1 8845.0 8856.5 5236.4 5447.7 5448.2 5461.9 5466.4 3406.2 3432.1 3467.0 3469.9 3485.4 1221.7 1223.3 1264.5 1268.0 2137.0 11741.9 12261.2 12266.4 12277.4 12281.9 4543.5 4667.1 4685.1 4723.5 4753.5 9805.5 9860.6 9872.8 9881.9 9884.3 5289.8 5290.2 5304.8 5308.7 5314.1 4398.8 4540.2 4785.2 4889.4 5001.4 3672.9 3797.4 3838.7 3843.3 3902.3 1188.9 1200.4 1201.8 1218.8 1225.4 906.5 954.3 972.5 980.1 983.1 5907.1 5946.5 6001.2 6001.4 6273.4 456.3 461.7 480.6 489.1 490.7 4200.1 4261.3 4280.7 4305.3 4306.2 10655.3 10897.1 10898.6 10963.3 10980.5 10184.7 10703.6 10910.9 10925.5 10944.7 7990.6 8144.4 8147.6 8154.9 8182.6 6022.5 6045.5 6103.8 6130.0 6270.8 9653.2 9975.8 9993.5 10088.7 10140.9 10336.9 10341.7 10366.4 10378.6 10438.4 7040.3 7155.5 7604.9 7784.8 7906.6 8377.5 8569.8 8571.1 8576.0 8579.5 7516.7 7691.3 7850.1 7902.4 7909.2 7503.4 7505.6 7541.2 7591.5 7607.2 6908.8 6988.5 6988.7 7006.1 7060.7 5064.0 5096.3 5112.2 5120.6 5375.7 5110.9 5166.0 5242.7 5261.1 5387.9 19:12:32 INFO - PROCESS | 23861 | 19:12:32 INFO - PROCESS | 23861 | [#4] prototype.html Cycles:180 Average:4515.87 Median:5146.72 stddev:1328.23 (25.8%) stddev-sans-first:1294.17 19:12:32 INFO - PROCESS | 23861 | Values: 324.5 327.1 334.9 337.9 339.7 5155.8 5165.0 5170.5 5196.4 5202.6 5137.6 5165.9 5166.8 5188.2 5200.8 3841.0 4115.8 4119.9 4127.8 4150.4 4048.1 4087.8 4089.9 4090.4 4095.2 4007.3 4023.7 4037.5 4038.2 4051.9 3815.9 3933.5 3957.1 4032.2 4066.2 3548.0 3549.4 3559.6 3561.2 3565.4 5008.0 5039.5 5039.7 5068.4 5078.4 4319.7 4425.6 4605.6 4636.9 4648.9 5345.3 5363.4 5365.9 5372.8 5391.7 3894.3 3894.6 3903.4 3914.6 3922.8 5539.2 5560.6 5566.4 5582.6 5624.2 3959.2 3965.7 3969.3 3988.0 3988.2 4021.1 4042.9 4044.6 4045.7 4045.8 4554.2 4602.7 4650.4 4658.9 4659.0 5333.4 5377.8 5386.2 5406.4 5432.5 1369.7 1387.6 1389.5 1394.5 1397.0 846.0 899.3 902.5 907.5 913.2 4590.3 4641.0 4645.0 4663.8 4695.1 4886.2 4897.8 4899.6 4925.9 4945.6 4364.8 4654.7 4668.3 4679.5 4680.6 2592.6 2815.9 2864.4 2868.9 2876.0 5588.4 5628.4 5723.6 5761.5 5787.0 5502.1 5592.7 5627.9 5631.9 5663.9 5723.7 5754.3 5782.9 5790.5 5833.8 5508.3 5549.2 5564.4 5567.2 5570.5 5302.0 5411.1 5460.8 5462.8 5501.4 5700.8 5731.2 5752.3 5769.5 5778.8 3983.5 4026.9 4032.9 4038.3 4038.8 5312.4 5570.6 5588.7 5612.2 5628.3 5450.9 5464.6 5468.6 5470.9 5478.7 5236.2 5453.7 5459.9 5474.8 5485.5 5350.6 5356.7 5364.7 5391.3 5400.5 5493.5 5498.0 5502.4 5520.9 5531.6 5237.0 5358.1 5362.9 5374.2 5381.7 19:12:32 INFO - PROCESS | 23861 | 19:12:32 INFO - PROCESS | 23861 | [#5] yui.html Cycles:180 Average:22226.82 Median:3882.23 stddev:32678.55 (841.7%) stddev-sans-first:32730.55 19:12:32 INFO - PROCESS | 23861 | Values: 781.3 784.3 791.4 822.9 836.6 15689.0 16707.8 16714.4 16720.5 16734.7 14907.5 15094.1 15199.7 15252.5 15700.6 798.6 825.0 829.0 829.4 830.0 658.5 679.1 818.6 819.2 819.9 812.4 846.5 851.6 851.6 851.6 845.8 849.3 849.4 850.1 850.6 498.2 511.3 619.2 619.3 619.3 14325.9 14747.6 14764.5 15106.0 15129.2 13534.5 14024.9 14605.4 14877.0 14887.9 11857.9 12144.8 12758.9 12860.9 13032.9 3788.4 3824.9 3856.8 3907.7 4037.1 87678.1 87700.0 88388.6 89402.1 89435.0 11285.1 11316.2 11413.4 11522.7 11618.9 21926.1 21948.1 21995.9 22019.8 22026.8 7258.9 8073.0 8431.8 8464.8 8475.8 4411.7 4617.8 4665.9 4703.4 4857.0 3642.5 3745.8 3747.5 3757.0 3759.4 597.0 600.4 602.3 623.1 624.2 673.4 746.1 756.1 781.6 783.1 9786.5 9998.4 10031.2 10066.4 10166.7 618.4 635.6 707.1 729.4 750.9 4996.3 5044.9 5118.7 5132.1 5149.0 108731.5 118858.0 123151.0 123600.4 127554.0 102139.5 103616.0 108705.0 110226.0 115260.4 90891.0 93965.0 95308.6 95449.3 96258.0 8980.4 9029.9 9126.2 9157.8 9209.8 55306.0 55338.0 55538.0 56080.0 59146.7 76618.0 77222.2 82186.0 84463.6 85144.0 11689.9 11868.5 12986.4 13262.5 13395.6 15168.5 15209.8 15236.2 15559.7 15752.5 13368.4 13675.4 14297.4 14433.0 14509.8 12951.0 13272.0 13585.7 13852.8 14484.6 13091.1 13351.9 13533.9 14122.6 14138.7 11572.3 11789.6 11876.3 11955.8 12136.5 11769.8 11976.2 12082.7 12136.3 12422.3 19:12:32 INFO - PROCESS | 23861 | -------- Summary: end -------- 19:12:32 INFO - PROCESS | 23861 | 19:12:33 INFO - TEST-INFO | 23861: exit 0 19:12:33 INFO - TEST-OK | dromaeo_css | took 1116125ms 19:12:33 INFO - TEST-START | kraken 19:12:33 INFO - operating with platform_type : linux_ 19:12:33 INFO - Initialising browser for kraken test... 19:12:33 INFO - TEST-INFO | started process 24163 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpN2MHas/profile http://localhost:56993/getInfo.html) 19:12:37 INFO - PROCESS | 24163 | __metrics Screen width/height:1600/1200 19:12:37 INFO - PROCESS | 24163 | colorDepth:24 19:12:37 INFO - PROCESS | 24163 | Browser inner width/height: 1024/697 19:12:37 INFO - PROCESS | 24163 | __metrics 19:12:37 INFO - PROCESS | 24163 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 19:12:37 INFO - PROCESS | 24163 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:12:38 INFO - PROCESS | 24163 | console.error: PushService: 19:12:38 INFO - PROCESS | 24163 | stateChangeProcessEnqueue: Error transitioning state 19:12:38 INFO - PROCESS | 24163 | UnknownError 19:12:39 INFO - TEST-INFO | 24163: exit 0 19:12:39 INFO - Browser initialized. 19:12:39 INFO - Running cycle 1/1 for kraken test... 19:12:39 INFO - TEST-INFO | started process 24246 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpN2MHas/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:12:51 INFO - PROCESS | 24246 | RSS: Main: 173301760 19:12:51 INFO - PROCESS | 24246 | 19:12:53 INFO - PROCESS | 24246 | Cycle 1(1): loaded http://localhost:56993/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56993/tests/kraken/driver.html?testName=audio-beat-detection) 19:12:54 INFO - PROCESS | 24246 | RSS: Main: 232538112 19:12:54 INFO - PROCESS | 24246 | 19:12:57 INFO - PROCESS | 24246 | Cycle 1(1): loaded http://localhost:56993/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56993/tests/kraken/driver.html?testName=audio-dft) 19:12:57 INFO - PROCESS | 24246 | RSS: Main: 217202688 19:12:57 INFO - PROCESS | 24246 | 19:13:01 INFO - PROCESS | 24246 | Cycle 1(1): loaded http://localhost:56993/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56993/tests/kraken/driver.html?testName=audio-fft) 19:13:01 INFO - PROCESS | 24246 | RSS: Main: 298786816 19:13:01 INFO - PROCESS | 24246 | 19:13:03 INFO - PROCESS | 24246 | Cycle 1(1): loaded http://localhost:56993/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56993/tests/kraken/driver.html?testName=audio-oscillator) 19:13:04 INFO - PROCESS | 24246 | RSS: Main: 208228352 19:13:04 INFO - PROCESS | 24246 | 19:13:06 INFO - PROCESS | 24246 | Cycle 1(1): loaded http://localhost:56993/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56993/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:13:07 INFO - PROCESS | 24246 | RSS: Main: 212279296 19:13:07 INFO - PROCESS | 24246 | 19:13:10 INFO - PROCESS | 24246 | Cycle 1(1): loaded http://localhost:56993/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56993/tests/kraken/driver.html?testName=imaging-darkroom) 19:13:10 INFO - PROCESS | 24246 | RSS: Main: 248651776 19:13:10 INFO - PROCESS | 24246 | 19:13:14 INFO - PROCESS | 24246 | Cycle 1(1): loaded http://localhost:56993/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56993/tests/kraken/driver.html?testName=imaging-desaturate) 19:13:14 INFO - PROCESS | 24246 | RSS: Main: 248201216 19:13:14 INFO - PROCESS | 24246 | 19:13:17 INFO - PROCESS | 24246 | Cycle 1(1): loaded http://localhost:56993/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56993/tests/kraken/driver.html?testName=json-parse-financial) 19:13:18 INFO - PROCESS | 24246 | RSS: Main: 248086528 19:13:18 INFO - PROCESS | 24246 | 19:13:19 INFO - PROCESS | 24246 | Cycle 1(1): loaded http://localhost:56993/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56993/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:13:20 INFO - PROCESS | 24246 | RSS: Main: 210157568 19:13:20 INFO - PROCESS | 24246 | 19:13:22 INFO - PROCESS | 24246 | Cycle 1(1): loaded http://localhost:56993/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56993/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:13:22 INFO - PROCESS | 24246 | RSS: Main: 217350144 19:13:22 INFO - PROCESS | 24246 | 19:13:24 INFO - PROCESS | 24246 | Cycle 1(1): loaded http://localhost:56993/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56993/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:13:25 INFO - PROCESS | 24246 | RSS: Main: 222703616 19:13:25 INFO - PROCESS | 24246 | 19:13:27 INFO - PROCESS | 24246 | Cycle 1(1): loaded http://localhost:56993/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56993/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:13:28 INFO - PROCESS | 24246 | RSS: Main: 223711232 19:13:28 INFO - PROCESS | 24246 | 19:13:31 INFO - PROCESS | 24246 | Cycle 1(1): loaded http://localhost:56993/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56993/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:13:32 INFO - PROCESS | 24246 | RSS: Main: 220979200 19:13:32 INFO - PROCESS | 24246 | 19:13:33 INFO - PROCESS | 24246 | Cycle 1(1): loaded http://localhost:56993/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56993/tests/kraken/driver.html?testName=ai-astar) 19:13:33 INFO - PROCESS | 24246 | RSS: Main: 272941056 19:13:33 INFO - PROCESS | 24246 | 19:13:33 INFO - PROCESS | 24246 | __start_tp_report 19:13:33 INFO - PROCESS | 24246 | _x_x_mozilla_page_load 19:13:33 INFO - PROCESS | 24246 | _x_x_mozilla_page_load_details 19:13:33 INFO - PROCESS | 24246 | |i|pagename|runs| 19:13:33 INFO - PROCESS | 24246 | |0;ai-astar;110;113;112;112;112;112;112;110;110;111 19:13:33 INFO - PROCESS | 24246 | |1;audio-beat-detection;149;128;124;158;119;117;118;117;148;119 19:13:33 INFO - PROCESS | 24246 | |2;audio-dft;163;161;161;161;163;161;164;162;163;159 19:13:33 INFO - PROCESS | 24246 | |3;audio-fft;75;76;74;112;76;76;77;77;99;75 19:13:33 INFO - PROCESS | 24246 | |4;audio-oscillator;83;82;112;83;82;83;115;83;83;112 19:13:33 INFO - PROCESS | 24246 | |5;imaging-gaussian-blur;116;116;116;117;117;116;117;117;116;116 19:13:33 INFO - PROCESS | 24246 | |6;imaging-darkroom;147;146;145;146;146;146;145;147;146;147 19:13:33 INFO - PROCESS | 24246 | |7;imaging-desaturate;96;95;96;95;96;96;94;95;95;95 19:13:33 INFO - PROCESS | 24246 | |8;json-parse-financial;51;50;50;50;51;51;51;52;50;51 19:13:33 INFO - PROCESS | 24246 | |9;json-stringify-tinderbox;61;61;61;61;61;60;60;62;61;61 19:13:33 INFO - PROCESS | 24246 | |10;stanford-crypto-aes;86;79;80;84;82;86;78;78;79;78 19:13:33 INFO - PROCESS | 24246 | |11;stanford-crypto-ccm;124;124;130;124;133;135;130;123;172;131 19:13:33 INFO - PROCESS | 24246 | |12;stanford-crypto-pbkdf2;172;166;170;167;166;167;171;170;174;168 19:13:33 INFO - PROCESS | 24246 | |13;stanford-crypto-sha256-iterative;65;65;65;65;66;66;66;70;64;63 19:13:33 INFO - PROCESS | 24246 | __end_tp_report 19:13:33 INFO - PROCESS | 24246 | __start_cc_report 19:13:33 INFO - PROCESS | 24246 | _x_x_mozilla_cycle_collect,1665 19:13:33 INFO - PROCESS | 24246 | __end_cc_report 19:13:33 INFO - PROCESS | 24246 | __startTimestamp1474337613927__endTimestamp 19:13:33 INFO - PROCESS | 24246 | 19:13:33 INFO - PROCESS | 24246 | ------- Summary: start ------- 19:13:33 INFO - PROCESS | 24246 | Number of tests: 14 19:13:33 INFO - PROCESS | 24246 | 19:13:33 INFO - PROCESS | 24246 | [#0] ai-astar Cycles:10 Average:111.40 Median:112.00 stddev:1.07 (1.0%) stddev-sans-first:1.01 19:13:33 INFO - PROCESS | 24246 | Values: 110.0 113.0 112.0 112.0 112.0 112.0 112.0 110.0 110.0 111.0 19:13:33 INFO - PROCESS | 24246 | 19:13:33 INFO - PROCESS | 24246 | [#1] audio-beat-detection Cycles:10 Average:129.70 Median:126.00 stddev:15.75 (12.5%) stddev-sans-first:15.08 19:13:33 INFO - PROCESS | 24246 | Values: 149.0 128.0 124.0 158.0 119.0 117.0 118.0 117.0 148.0 119.0 19:13:33 INFO - PROCESS | 24246 | 19:13:33 INFO - PROCESS | 24246 | [#2] audio-dft Cycles:10 Average:161.80 Median:162.50 stddev:1.48 (0.9%) stddev-sans-first:1.50 19:13:33 INFO - PROCESS | 24246 | Values: 163.0 161.0 161.0 161.0 163.0 161.0 164.0 162.0 163.0 159.0 19:13:33 INFO - PROCESS | 24246 | 19:13:33 INFO - PROCESS | 24246 | [#3] audio-fft Cycles:10 Average:81.70 Median:76.00 stddev:12.94 (17.0%) stddev-sans-first:13.50 19:13:33 INFO - PROCESS | 24246 | Values: 75.0 76.0 74.0 112.0 76.0 76.0 77.0 77.0 99.0 75.0 19:13:33 INFO - PROCESS | 24246 | 19:13:33 INFO - PROCESS | 24246 | [#4] audio-oscillator Cycles:10 Average:91.80 Median:83.00 stddev:14.66 (17.7%) stddev-sans-first:15.20 19:13:33 INFO - PROCESS | 24246 | Values: 83.0 82.0 112.0 83.0 82.0 83.0 115.0 83.0 83.0 112.0 19:13:33 INFO - PROCESS | 24246 | 19:13:33 INFO - PROCESS | 24246 | [#5] imaging-gaussian-blur Cycles:10 Average:116.40 Median:116.50 stddev:0.52 (0.4%) stddev-sans-first:0.53 19:13:33 INFO - PROCESS | 24246 | Values: 116.0 116.0 116.0 117.0 117.0 116.0 117.0 117.0 116.0 116.0 19:13:33 INFO - PROCESS | 24246 | 19:13:33 INFO - PROCESS | 24246 | [#6] imaging-darkroom Cycles:10 Average:146.10 Median:146.00 stddev:0.74 (0.5%) stddev-sans-first:0.71 19:13:33 INFO - PROCESS | 24246 | Values: 147.0 146.0 145.0 146.0 146.0 146.0 145.0 147.0 146.0 147.0 19:13:33 INFO - PROCESS | 24246 | 19:13:33 INFO - PROCESS | 24246 | [#7] imaging-desaturate Cycles:10 Average:95.30 Median:95.50 stddev:0.67 (0.7%) stddev-sans-first:0.67 19:13:33 INFO - PROCESS | 24246 | Values: 96.0 95.0 96.0 95.0 96.0 96.0 94.0 95.0 95.0 95.0 19:13:33 INFO - PROCESS | 24246 | 19:13:33 INFO - PROCESS | 24246 | [#8] json-parse-financial Cycles:10 Average:50.70 Median:51.00 stddev:0.67 (1.3%) stddev-sans-first:0.71 19:13:33 INFO - PROCESS | 24246 | Values: 51.0 50.0 50.0 50.0 51.0 51.0 51.0 52.0 50.0 51.0 19:13:33 INFO - PROCESS | 24246 | 19:13:33 INFO - PROCESS | 24246 | [#9] json-stringify-tinderbox Cycles:10 Average:60.90 Median:61.00 stddev:0.57 (0.9%) stddev-sans-first:0.60 19:13:33 INFO - PROCESS | 24246 | Values: 61.0 61.0 61.0 61.0 61.0 60.0 60.0 62.0 61.0 61.0 19:13:33 INFO - PROCESS | 24246 | 19:13:33 INFO - PROCESS | 24246 | [#10] stanford-crypto-aes Cycles:10 Average:81.00 Median:81.00 stddev:3.27 (4.0%) stddev-sans-first:2.92 19:13:33 INFO - PROCESS | 24246 | Values: 86.0 79.0 80.0 84.0 82.0 86.0 78.0 78.0 79.0 78.0 19:13:33 INFO - PROCESS | 24246 | 19:13:33 INFO - PROCESS | 24246 | [#11] stanford-crypto-ccm Cycles:10 Average:132.60 Median:130.50 stddev:14.49 (11.1%) stddev-sans-first:15.03 19:13:33 INFO - PROCESS | 24246 | Values: 124.0 124.0 130.0 124.0 133.0 135.0 130.0 123.0 172.0 131.0 19:13:33 INFO - PROCESS | 24246 | 19:13:33 INFO - PROCESS | 24246 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.10 Median:170.00 stddev:2.73 (1.6%) stddev-sans-first:2.68 19:13:33 INFO - PROCESS | 24246 | Values: 172.0 166.0 170.0 167.0 166.0 167.0 171.0 170.0 174.0 168.0 19:13:33 INFO - PROCESS | 24246 | 19:13:33 INFO - PROCESS | 24246 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.50 Median:65.50 stddev:1.84 (2.8%) stddev-sans-first:1.94 19:13:33 INFO - PROCESS | 24246 | Values: 65.0 65.0 65.0 65.0 66.0 66.0 66.0 70.0 64.0 63.0 19:13:33 INFO - PROCESS | 24246 | -------- Summary: end -------- 19:13:33 INFO - PROCESS | 24246 | 19:13:35 INFO - TEST-INFO | 24246: exit 0 19:13:35 INFO - TEST-OK | kraken | took 61927ms 19:13:35 INFO - SUITE-END | took 1178s 19:13:35 INFO - Completed test suite (00:19:38) 19:13:35 INFO - javascript benchmark 19:13:35 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2949.6607890092637, 3001.9249518762026, 3028.106708520985, 3061.984690076549, 3069.95395069074, 5155.278261043457, 5217.113090774568, 5243.842684719455, 5286.497782710641, 5344.0, 5243.501867322608, 5304.230886521462, 5335.039692855285, 5338.359396872371, 5433.130699088147, 4791.28130780384, 4795.2807078938085, 4834.299026641143, 4850.126977144113, 4855.247436647325, 4765.046990601893, 4858.1983972644475, 4890.853274401774, 4894.608431325485, 4928.482509336504, 4850.684705494132, 4924.803007879698, 4940.827071052514, 4979.7261150636705, 4990.900181996358, 4612.838550650745, 4622.884427889296, 4646.581807637329, 4768.094062128184, 4871.756412179412, 4755.0489902019735, 4769.57073863354, 4779.354787103731, 4806.038792241566, 4857.979824236914, 5259.710715910624, 5299.390570084451, 5299.867503312448, 5301.73491325436, 5328.866778330534, 5444.128939369722, 5498.862528436821, 5632.802851900164, 5638.830835074954, 5640.915386269209, 5623.465770751772, 5660.066089095294, 5663.235463212496, 5673.773049078032, 5734.885302293931, 5284.392294886099, 5287.021900948337, 5289.656172348862, 5292.629515933886, 5292.788288468418, 5491.75287112073, 5786.132080188006, 5786.1785106076195, 5795.304563452412, 5849.707514624337, 5324.653897496728, 5332.9733351333, 5378.650387724785, 5379.67721936685, 5386.569074474032, 5444.646098003617, 5612.0, 5613.438656134354, 5661.830145095653, 5739.827805165852, 5626.774929002876, 5633.042382794934, 5636.605437619327, 5638.6052976292085, 5641.548676105902, 5609.242752228397, 5671.120976248688, 5672.914906276326, 5673.801416950387, 5731.570132240098, 4266.765327906933, 4315.28797748368, 4353.434053573079, 4395.7362558246605, 4407.6914615976575, 3912.39357899526, 3919.9804000980384, 3924.50943632046, 3957.5053118360206, 3973.304671682502, 5369.48989845964, 5382.381026181961, 5386.946130538723, 5387.326584176978, 5449.182622606561, 5410.83767486976, 5431.456854314537, 5443.42844001382, 5545.057340252167, 5628.97185514078, 5391.433899440581, 5391.622586418992, 5392.137258038773, 5430.15832545956, 5457.2359869618285, 4946.431160416526, 4957.752112394366, 4958.157113290749, 4959.628027897922, 5014.323066386064, 5711.742971566289, 5749.741261643152, 5762.769489220468, 5878.412158784173, 5909.231799866076, 5365.275687782099, 5604.383517812956, 5606.0, 5675.77296908119, 5677.858053548694, 5792.0443126885275, 5797.391273916178, 5798.536117110537, 5800.4489573490455, 5800.796972105956, 5372.382176049648, 5467.234587157722, 5507.697076660662, 5509.834704958857, 5516.365617953987, 5137.897242055213, 5603.243562119224, 5659.349174844946, 5708.372079071381, 5769.07694768835, 5551.084071128219, 5566.081596536526, 5566.109422492544, 5576.10782274834, 5578.944210558005, 5387.191921211849, 5537.6954267515675, 5541.667499950016, 5546.057170280981, 5555.777768889361, 5364.30264065669, 5389.973050134724, 5409.107497263065, 5424.891502170013, 5513.61404701667, 5465.508104270635, 5496.285482887199, 5532.087205611029, 5541.445855414427, 5551.417101204315, 5211.166213405837, 5328.893422131613, 5354.3842458117815, 5388.110961691277, 5397.163439666858], "value": 5196.146142033373, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [568.2612603615299, 581.8690794571222, 583.7635757518207, 596.0671549025773, 598.7365559153972, 31372.84313578432, 42156.36765448517, 43837.0, 43866.342004869955, 47189.76405117969, 44389.33415998763, 45041.0, 45060.549394506044, 45077.54922450775, 45089.32366014504, 7974.800629984268, 8862.689805856797, 9007.459552426842, 9036.50299233542, 9036.77408064797, 8004.679812807481, 8243.175682431769, 8250.711225107123, 10589.1528677706, 10868.239223254375, 10801.0, 10872.347659140438, 10898.455077246106, 10899.0, 10900.236983411165, 10597.470126493645, 10681.572737090584, 10699.090577300938, 10706.41114738689, 10772.353658780497, 10524.473776311153, 10595.205359598061, 10601.363918164934, 10611.681649550526, 10614.097801686867, 40944.181116377506, 41039.58960410417, 41250.58749412497, 41333.379999300036, 41337.79331103355, 3368.9663103369144, 3463.930721385583, 3469.774464659789, 3495.598006229265, 3529.858805647771, 31703.84148079268, 32054.35891282161, 32903.6709632903, 33227.50158747621, 33944.15139621504, 4263.850765223202, 4354.259775838115, 4370.978145109286, 4379.032338234269, 4386.495553011413, 332860.0, 333706.0, 334206.0, 334233.0, 334568.0, 57342.71328643413, 57447.0, 57774.0, 57978.42021579815, 58145.70927145337, 59586.0, 59697.0, 60056.0, 60076.69961650164, 60091.39908600946, 16635.41776037833, 16920.15399230058, 16925.830741692425, 16943.915280423764, 16944.49166525006, 12850.357482125857, 13005.024623153302, 13008.284544350152, 13008.674783130498, 13013.47946082165, 15289.235538223043, 15633.296501657453, 16955.745663815054, 17280.481585552454, 17417.129143543025, 4967.627427942846, 6043.305019922677, 6091.451769340781, 6152.661603611844, 6260.0, 930.437085563238, 1017.4048181813574, 1063.5745701719375, 1073.2004656530935, 1114.4761961877905, 30729.69270307313, 31556.0, 31647.367052658818, 31692.683073169435, 32348.514772278435, 1024.5031159887449, 1071.23406764164, 1100.2078503477483, 1132.5130194016651, 1144.8494263265366, 15356.61608459775, 16211.594710132342, 16327.0, 16366.263518141712, 16435.212897561745, 483278.0, 487897.4523057256, 488098.0, 488506.0, 489258.0, 50027.0, 51018.0, 55653.72173139108, 56500.71749641225, 56719.716401418555, 1305.3147097773744, 1327.9468821247042, 1333.4666133546466, 1339.3102552185633, 1354.2622362280179, 29988.621852998247, 32513.512297315086, 32813.67186328106, 32845.50731739074, 33186.17034574155, 51262.0, 53722.0, 55155.72422137864, 55565.72217138889, 61365.0, 456402.0, 459407.0, 460269.0, 460520.0, 461170.0, 28301.43397132087, 28499.572506412005, 28552.42895142044, 28774.424511509234, 28905.277368065967, 46380.30429543627, 47556.0, 48206.93827148099, 48339.75830120827, 49660.25509617286, 40645.39031914583, 41222.38166427566, 41936.790316048224, 42867.785661071495, 43522.12955740931, 40815.38776918408, 41447.90801946622, 41688.58311416847, 41897.87736084812, 42613.57386426096, 40115.79942100271, 40784.79607601943, 41228.58771412368, 41269.793651031556, 41410.58589414188, 34652.82673586616, 36199.63800362068, 36320.0, 36900.096722406866, 37438.43842342422, 27117.322066948484, 27301.726982729917, 27376.863115684293, 27402.862985684944, 27454.313642158308], "value": 20149.72362461758, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [362.7043959173272, 379.49906123916423, 386.80659670164926, 389.95320561532617, 415.61970796721, 14211.502597409093, 17966.281348746066, 18244.17901194446, 18375.44873653789, 19498.317558885443, 18112.90943545284, 19100.235990560395, 19137.52156196096, 19282.0, 19466.221351145938, 5639.7180140993, 5892.558058145635, 5896.587238893279, 5902.144189092579, 5902.645841249518, 5612.07400778871, 5777.1623114648355, 5783.21926539916, 5826.563007774413, 5877.0, 5780.595358324919, 5782.9421705782825, 5783.277090363705, 5783.479486846183, 5788.102844059156, 5590.720463976805, 5700.201971723962, 5778.624389414675, 5779.855503612402, 5783.5951483396175, 5123.2827404163445, 5126.6923984561045, 5131.076406246874, 5141.0, 5141.048905952411, 22615.434614134614, 22626.434339141488, 22655.546889062127, 22736.65895011576, 22928.31215063551, 2303.2514432809235, 2502.311864237331, 3049.6797836227097, 3054.8930787422555, 3221.9677803221903, 14181.78727319091, 14275.14349139055, 14463.27683615815, 14510.709785804225, 14531.709365812625, 1884.5571290746664, 1900.4488698277482, 1955.0, 1965.5380985468403, 2017.505711100775, 80408.79386809202, 80977.59511202465, 81055.0, 82950.0, 83040.0, 9649.276304277208, 9831.360961537477, 9964.850527242097, 10126.291159618904, 10271.897281027244, 13677.24775137357, 13693.589192324247, 13703.45186192561, 13708.24604646734, 13733.107348022346, 7757.534547927143, 7985.760427187193, 8085.272325490734, 8234.752957411167, 8266.0, 7837.843243135219, 7845.7253996111, 7996.760097197093, 8143.389245806588, 8171.713990010321, 6074.54440916933, 6162.845928851814, 6248.656323902138, 6322.146510221153, 6424.196975378078, 1816.1191821966336, 1824.744535764994, 1834.8990805505825, 1855.6103218324304, 1880.2009146112848, 928.8420968435382, 935.4340623922565, 944.2351695126969, 976.1653786012998, 1051.75283808305, 18222.817771822378, 19712.507187320145, 19843.30548430798, 19851.20595176206, 20223.4944126398, 969.4667932637021, 990.4354517924858, 993.6373223773415, 1000.8174006658894, 1027.3938376357985, 10519.31624444424, 10916.945415272872, 11236.628711609903, 11253.606123785628, 11308.86911308879, 475653.0, 480030.0, 488259.0, 488457.0, 498446.0, 45391.773041134584, 45962.08075838465, 46288.53711462842, 52049.0, 82376.0, 1001.5142655811925, 1013.6654903881702, 1024.7233247023412, 1034.1003327105316, 1039.2050081687514, 16595.751063733813, 16611.750823737413, 17357.13214339303, 17702.91148544249, 17816.46550603484, 39343.60656393514, 39593.0, 39982.60017399905, 40889.795551022056, 41068.0, 51228.48771512386, 51688.0, 51754.0, 52032.73983630209, 52110.73944630253, 8355.206255405727, 8407.2433480987, 8435.789105272417, 8601.82796344082, 8845.159709827556, 13243.536476223286, 13369.866301336862, 13465.798013029616, 13602.115862468909, 13710.725785484434, 11300.152488563555, 11372.374519401512, 11717.414129293671, 11720.88279117198, 12296.0, 11466.140039496904, 11558.248713833575, 11739.413029348669, 11751.706207344885, 12223.44994475233, 10688.465576721288, 11550.59572914944, 11664.76670466569, 11973.341466219093, 12025.278483291031, 7362.3741981932135, 7688.730894418669, 7875.094364147967, 8428.283595894172, 8936.821263574822, 8824.720010794888, 10966.287191332538, 11314.321140731583, 11542.769144617228, 11852.881471185523], "value": 9119.124463756309, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1341.7182391697743, 1436.3033928544658, 1481.6221863424828, 1592.2198122919772, 1739.2083912322385, 7734.574598397093, 8473.78815529612, 8632.913670863281, 8758.343124265673, 8997.190252877239, 8797.472151670889, 8841.5579221039, 8868.423552469085, 8870.246029087535, 8872.556372181398, 2339.3215967369442, 2354.3290162303747, 2361.421451744325, 2365.31405892291, 2378.369732021016, 2258.762829902861, 2280.5894938910988, 2332.5334933013464, 2334.708161479815, 2342.051469154995, 2308.849924754894, 2319.82601304902, 2337.7662233776655, 2340.1341503643675, 2340.871252081135, 2257.0294773247497, 2258.8644681319092, 2271.44349634339, 2280.6464997925264, 2283.794458498735, 2254.334971183505, 2256.323103069089, 2258.2209137847417, 2260.7626199249007, 2263.8868056597103, 8583.871241931376, 8782.209601135928, 8793.120687931156, 8844.955775221148, 8856.51289179095, 5236.423993360765, 5447.727613619343, 5448.182772584105, 5461.863453413696, 5466.398696143461, 3406.2165701888703, 3432.1076520104702, 3466.9826650866835, 3469.8612055517747, 3485.3900567400606, 1221.688469440287, 1223.3027174510532, 1264.500522293698, 1267.993660031694, 2136.9786302136936, 11741.941290293495, 12261.203021803554, 12266.448009839578, 12277.38613069352, 12281.938590307082, 4543.477500087466, 4667.066586682677, 4685.1332503486965, 4723.527647235249, 4753.453352864396, 9805.509724513746, 9860.556274967643, 9872.753181170385, 9881.901180988241, 9884.30809843304, 5289.814856480005, 5290.1800220965815, 5304.814331498379, 5308.654937429054, 5314.0700377433795, 4398.8020539075815, 4540.205464043779, 4785.186518291899, 4889.437714662859, 5001.44984051754, 3672.889813305605, 3797.3924172132324, 3838.7312888097563, 3843.3466310727244, 3902.3366027775346, 1188.8989435897872, 1200.4177973682756, 1201.8377519034818, 1218.79889818179, 1225.3811825028304, 906.4651855405342, 954.3319676226669, 972.5380444288907, 980.0689345122163, 983.0562659846628, 5907.1139329100115, 5946.464818166386, 6001.219841420588, 6001.399860014051, 6273.404026617464, 456.2881904229413, 461.73681001829306, 480.6299149654816, 489.11714355587793, 490.69576862345576, 4200.075983283673, 4261.27558315081, 4280.72175308604, 4305.332673435622, 4306.246406878847, 10655.254132210826, 10897.073748731287, 10898.564057437772, 10963.342199467897, 10980.450977451255, 10184.745381365525, 10703.571857125784, 10910.945445272722, 10925.453727313603, 10944.726381840517, 7990.64042118106, 8144.429889907649, 8147.633356498972, 8154.918450815535, 8182.590870456454, 6022.518198544193, 6045.546584006305, 6103.847403814762, 6129.969350153221, 6270.843228919313, 9653.203426038783, 9975.750606234902, 9993.500324983866, 10088.747781305527, 10140.949295253477, 10336.948315258376, 10341.689749307532, 10366.378017318984, 10378.636747713794, 10438.425886576008, 7040.258350834222, 7155.534890232118, 7604.923950760643, 7784.805379865548, 7906.5651389174145, 8377.45546539473, 8569.82860342802, 8571.057183709912, 8576.0, 8579.485230886166, 7516.73040152961, 7691.30778229962, 7850.057993040871, 7902.407319450949, 7909.209079092045, 7503.362214211853, 7505.624718763931, 7541.245875412635, 7591.506552074097, 7607.23927607257, 6908.758193463204, 6988.510804243654, 6988.720451182099, 7006.124234470692, 7060.71757129709, 5064.0, 5096.337476128079, 5112.207607820794, 5120.641555091108, 5375.704336261387, 5110.872228194325, 5166.0, 5242.711650859087, 5261.079311120615, 5387.92241551699], "value": 4567.920835288459, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [324.4711120872977, 327.08579520240926, 334.92464195555993, 337.9307242015384, 339.7248228934563, 5155.845324640262, 5164.9741751291285, 5170.457102004292, 5196.350456192976, 5202.609804264681, 5137.58899288057, 5165.922511162326, 5166.844994650157, 5188.195829646411, 5200.765965531551, 3840.961590384088, 4115.773632450214, 4119.8970025749295, 4127.834886604533, 4150.377443383486, 4048.149888523399, 4087.816048277835, 4089.9386509202386, 4090.4477895484174, 4095.201435720036, 4007.298722723526, 4023.6982226333, 4037.535683396403, 4038.2125485530332, 4051.898702532431, 3815.9046023849346, 3933.547642021147, 3957.1096503286817, 4032.1976226522434, 4066.207089617526, 3547.9822600886832, 3549.396602577568, 3559.555055618048, 3561.1631266652316, 3565.449029672101, 5008.0, 5039.546440820307, 5039.72281524516, 5068.3917929848285, 5078.365204349457, 4319.704088773356, 4425.557444255581, 4605.562471565229, 4636.884077898046, 4648.930266046012, 5345.251664766935, 5363.383210930715, 5365.919511207335, 5372.811951581676, 5391.730413479349, 3894.279558281727, 3894.5910679378826, 3903.355946268862, 3914.608539146091, 3922.8038598069984, 5539.224508568804, 5560.555155587584, 5566.387697353288, 5582.581306402036, 5624.21261023457, 3959.1685745993664, 3965.7025723070883, 3969.325214713506, 3988.0, 3988.222296652125, 4021.1354558770004, 4042.939355909664, 4044.5550989391145, 4045.6561192298986, 4045.7774822384463, 4554.248548989446, 4602.700824446467, 4650.372199753057, 4658.883527911829, 4658.976705116453, 5333.359996800331, 5377.7580008899695, 5386.165144402624, 5406.4321346809775, 5432.456754324615, 1369.7123604043263, 1387.6045327081733, 1389.4650559534653, 1394.4730520080495, 1397.0, 845.9746207613781, 899.3030401438937, 902.5126431726925, 907.5008745190119, 913.2191975860676, 4590.334401511762, 4640.9535904640525, 4644.95621926358, 4663.836765713184, 4695.131400690883, 4886.193778026657, 4897.804087836446, 4899.58353539946, 4925.901481970341, 4945.604351651859, 4364.847230346986, 4654.743989080633, 4668.276417155346, 4679.485256621769, 4680.578747912636, 2592.611108333765, 2815.943681126366, 2864.4271145770513, 2868.922008045747, 2875.9856200718864, 5588.357338905997, 5628.352739434936, 5723.5707321950185, 5761.452661997104, 5786.971065144731, 5502.119660854244, 5592.748326325325, 5627.859303517363, 5631.859203519863, 5663.876046482569, 5723.656580605094, 5754.309482862, 5782.942170578241, 5790.536757059509, 5833.82498525045, 5508.256385388082, 5549.195366671891, 5564.38791732901, 5567.218490083134, 5570.470805273492, 5302.030862716891, 5411.132099987013, 5460.781568737206, 5462.8361149165585, 5501.386841250374, 5700.828975130753, 5731.226284451713, 5752.252207213036, 5769.451902069297, 5778.768849245983, 3983.50206224222, 4026.8590599330055, 4032.858849940238, 4038.273110840077, 4038.757674539537, 5312.389075256395, 5570.637908535932, 5588.720563971867, 5612.186232996111, 5628.324601047901, 5450.863728406822, 5464.617476776588, 5468.578169675992, 5470.890582188414, 5478.698671573102, 5236.162214045735, 5453.672779633234, 5459.863503412446, 5474.835754927359, 5485.533729632866, 5350.625456218183, 5356.705381204071, 5364.746806341424, 5391.272178255886, 5400.513953744182, 5493.450654934634, 5498.0, 5502.449755024466, 5520.917186242129, 5531.557475402039, 5237.0, 5358.089124848707, 5362.892742145214, 5374.220737992891, 5381.67709937405], "value": 4095.1861093990265, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [781.2968328504342, 784.3489903380193, 791.3866753266218, 822.8847961285418, 836.5858899844574, 15689.0, 16707.83292167078, 16714.41499547516, 16720.498385048468, 16734.66530669389, 14907.478238261663, 15094.09435433872, 15199.696006079872, 15252.466163684274, 15700.607484812885, 798.6006996501749, 824.9546274954876, 829.0217543298935, 829.4359835312016, 830.024720952881, 658.5357323087236, 679.1239301301315, 818.5702506184241, 819.1685439279137, 819.9180081991813, 812.4069429316603, 846.4540371460404, 851.5657014922373, 851.6210286422545, 851.6465666748323, 845.8392905347964, 849.2865992566274, 849.371465115816, 850.090403268503, 850.6129710981488, 498.16308601549383, 511.3327108123897, 619.2321521313539, 619.2661695890384, 619.2785405003166, 14325.85674143266, 14747.55757327282, 14764.483243086548, 15106.0, 15129.243537823175, 13534.458621655122, 14024.929875350557, 14605.415783368653, 14877.0, 14887.925560372129, 11857.881421185764, 12144.757104857941, 12758.872411275861, 12860.913380788637, 13032.934835325857, 3788.4317352397084, 3824.9235015299537, 3856.8071596420345, 3907.651860108258, 4037.1118353962083, 87678.123218767, 87700.0, 88388.55805720994, 89402.10597894003, 89435.0, 11285.097192224766, 11316.151288653384, 11413.372264525366, 11522.654320370402, 11618.88381116195, 21926.122955081937, 21948.122075116815, 21995.890020549796, 22019.779802201774, 22026.779732202795, 7258.854822903512, 8073.0, 8431.831363372698, 8464.830703385898, 8475.83048339042, 4411.713238639478, 4617.7691115444095, 4665.930011049837, 4703.388559487314, 4856.975715121402, 3642.4718415829557, 3745.831437585315, 3747.512823333004, 3756.966834120639, 3759.4360845872793, 596.9820905372845, 600.4055984575326, 602.3073465514693, 623.1058431151313, 624.2290770897937, 673.4074014866886, 746.119578896896, 756.1417790807474, 781.6209138567904, 783.115079959642, 9786.461744604116, 9998.350107243006, 10031.247656425798, 10066.44634545107, 10166.746641074933, 618.3538202578317, 635.6345101566618, 707.1090426063197, 729.4492658043249, 750.8610906982226, 4996.250562415594, 5044.873878153002, 5118.744062796845, 5132.10188217068, 5149.0, 108731.45634271778, 118858.0, 123151.0, 123600.38199808943, 127554.0, 102139.48930255302, 103616.0, 108705.0, 110226.0, 115260.42369788098, 90891.0, 93965.0, 95308.57037144448, 95449.318547701, 96258.0, 8980.416272942239, 9029.909700902906, 9126.17864392208, 9157.816843662957, 9209.815803683754, 55306.0, 55338.0, 55538.0, 56080.0, 59146.704266478395, 76618.0, 77222.22777772375, 82186.0, 84463.57768211365, 85144.0, 11689.941550292195, 11868.465919033491, 12986.350682466027, 13262.53581124656, 13395.598132056053, 15168.469103581763, 15209.847901520843, 15236.162011089496, 15559.688806224038, 15752.527424177293, 13368.398422070835, 13675.384607692478, 14297.35661895238, 14433.0, 14509.782353264498, 12951.0, 13272.0, 13585.728285434434, 13852.792208117087, 14484.637884052983, 13091.149075310075, 13351.933240333736, 13533.932330338286, 14122.646933826736, 14138.717225655224, 11572.305661660328, 11789.587364442203, 11876.346800926032, 11955.76088478243, 12136.453859576164, 11769.823452648388, 11976.221545599508, 12082.697932551757, 12136.33250171249, 12422.316772577595], "value": 7745.043299773236, "unit": "score"}], "name": "dromaeo_css", "value": 7191.638983158749, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 113.0, 112.0, 112.0, 112.0, 112.0, 112.0, 110.0, 110.0, 111.0], "value": 111.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 128.0, 124.0, 158.0, 119.0, 117.0, 118.0, 117.0, 148.0, 119.0], "value": 129.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 161.0, 161.0, 161.0, 163.0, 161.0, 164.0, 162.0, 163.0, 159.0], "value": 161.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 74.0, 112.0, 76.0, 76.0, 77.0, 77.0, 99.0, 75.0], "value": 81.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 112.0, 83.0, 82.0, 83.0, 115.0, 83.0, 83.0, 112.0], "value": 91.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 116.0, 116.0, 117.0, 117.0, 116.0, 117.0, 117.0, 116.0, 116.0], "value": 116.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 145.0, 146.0, 146.0, 146.0, 145.0, 147.0, 146.0, 147.0], "value": 146.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 96.0, 95.0, 96.0, 96.0, 94.0, 95.0, 95.0, 95.0], "value": 95.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 50.0, 50.0, 50.0, 51.0, 51.0, 51.0, 52.0, 50.0, 51.0], "value": 50.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 61.0, 61.0, 61.0, 61.0, 60.0, 60.0, 62.0, 61.0, 61.0], "value": 60.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 79.0, 80.0, 84.0, 82.0, 86.0, 78.0, 78.0, 79.0, 78.0], "value": 81.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [124.0, 124.0, 130.0, 124.0, 133.0, 135.0, 130.0, 123.0, 172.0, 131.0], "value": 132.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 166.0, 170.0, 167.0, 166.0, 167.0, 171.0, 170.0, 174.0, 168.0], "value": 169.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 65.0, 65.0, 66.0, 66.0, 66.0, 70.0, 64.0, 63.0], "value": 65.5, "unit": "score"}], "name": "kraken", "value": 1493.9999999999998, "alertThreshold": 2.0}]} 19:13:35 INFO - Return code: 0 19:13:35 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:13:35 INFO - # TBPL SUCCESS # 19:13:35 INFO - Running post-action listener: _resource_record_post_action 19:13:35 INFO - [mozharness: 2016-09-20 02:13:35.731142Z] Finished run-tests step (success) 19:13:35 INFO - Running post-run listener: _resource_record_post_run 19:13:35 INFO - Total resource usage - Wall time: 1196s; CPU: 13.0%; Read bytes: 8192; Write bytes: 364597248; Read time: 100; Write time: 280544 19:13:35 INFO - TinderboxPrint: CPU usage
13.1% 19:13:35 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 100 19:13:35 INFO - TinderboxPrint: I/O write bytes / time
364,597,248 / 280,544 19:13:35 INFO - TinderboxPrint: CPU idle
8,291.6 (86.9%) 19:13:35 INFO - TinderboxPrint: CPU user
1,198.0 (12.6%) 19:13:35 INFO - TinderboxPrint: Swap in / out
0 / 0 19:13:35 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 73924608; Read time: 0; Write time: 51108 19:13:35 INFO - run-tests - Wall time: 1179s; CPU: 13.0%; Read bytes: 4096; Write bytes: 290181120; Read time: 80; Write time: 229408 19:13:36 INFO - Running post-run listener: _upload_blobber_files 19:13:36 INFO - Blob upload gear active. 19:13:36 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:13:36 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 19:13:36 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'] 19:13:36 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 19:13:36 INFO - (blobuploader) - INFO - Open directory for files ... 19:13:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 19:13:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:13:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:13:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 19:13:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:13:37 INFO - (blobuploader) - INFO - Done attempting. 19:13:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 19:13:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:13:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:13:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 19:13:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:13:38 INFO - (blobuploader) - INFO - Done attempting. 19:13:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:13:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:13:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:13:42 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:13:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:13:42 INFO - (blobuploader) - INFO - Done attempting. 19:13:42 INFO - (blobuploader) - INFO - Iteration through files over. 19:13:42 INFO - Return code: 0 19:13:42 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:13:42 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:13:42 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/fc1d1bf245ea78ab9802c4c931361d6bdaab63f3fd1fc6052bd751e15a0940c5009acc6da4f0a92ff54c163634bb4c74e254d071133f0bf8e091d42c5a185f1d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/de5bee947ddd65dd89316d365ec297cba611f921229a1355fb03e582a5c5664e192f967c000d69b71f39c13e61db58642c18040d075c71776496430c719523a0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/b3ebdc2445bb523b7b45fb4cc353d4df128c43efa9f86562cbea091c9adc0fc06105125ec38abafc7ed38a03e1c0e2cec6e72ad6b1c60cc9676b214dd58cc61b"} 19:13:42 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:13:42 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:13:42 INFO - Contents: 19:13:42 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/fc1d1bf245ea78ab9802c4c931361d6bdaab63f3fd1fc6052bd751e15a0940c5009acc6da4f0a92ff54c163634bb4c74e254d071133f0bf8e091d42c5a185f1d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/de5bee947ddd65dd89316d365ec297cba611f921229a1355fb03e582a5c5664e192f967c000d69b71f39c13e61db58642c18040d075c71776496430c719523a0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/b3ebdc2445bb523b7b45fb4cc353d4df128c43efa9f86562cbea091c9adc0fc06105125ec38abafc7ed38a03e1c0e2cec6e72ad6b1c60cc9676b214dd58cc61b"} 19:13:42 INFO - Running post-run listener: copy_logs_to_upload_dir 19:13:42 INFO - Copying logs to upload dir... 19:13:42 INFO - mkdir: /builds/slave/test/build/upload/logs 19:13:42 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1252.960303 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2016-09-19 19:13:42.092508) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-19 19:13:42.096507) ========= 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=652eb03db935cfc1c15ebda0000001ad-1474336365.109353-1011090097 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/fc1d1bf245ea78ab9802c4c931361d6bdaab63f3fd1fc6052bd751e15a0940c5009acc6da4f0a92ff54c163634bb4c74e254d071133f0bf8e091d42c5a185f1d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/de5bee947ddd65dd89316d365ec297cba611f921229a1355fb03e582a5c5664e192f967c000d69b71f39c13e61db58642c18040d075c71776496430c719523a0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/b3ebdc2445bb523b7b45fb4cc353d4df128c43efa9f86562cbea091c9adc0fc06105125ec38abafc7ed38a03e1c0e2cec6e72ad6b1c60cc9676b214dd58cc61b"} build_url:https://queue.taskcluster.net/v1/task/aRfrZtnETwOiO50MVN1ZcA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011316 build_url: 'https://queue.taskcluster.net/v1/task/aRfrZtnETwOiO50MVN1ZcA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/fc1d1bf245ea78ab9802c4c931361d6bdaab63f3fd1fc6052bd751e15a0940c5009acc6da4f0a92ff54c163634bb4c74e254d071133f0bf8e091d42c5a185f1d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/de5bee947ddd65dd89316d365ec297cba611f921229a1355fb03e582a5c5664e192f967c000d69b71f39c13e61db58642c18040d075c71776496430c719523a0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/b3ebdc2445bb523b7b45fb4cc353d4df128c43efa9f86562cbea091c9adc0fc06105125ec38abafc7ed38a03e1c0e2cec6e72ad6b1c60cc9676b214dd58cc61b"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-19 19:13:42.122838) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 19:13:42.123327) ========= 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=652eb03db935cfc1c15ebda0000001ad-1474336365.109353-1011090097 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004477 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 19:13:42.206771) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-19 19:13:42.207216) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-19 19:13:42.207647) ========= ========= Total master_lag: 0.20 =========