builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-053 starttime: 1474555749.99 results: success (0) buildid: 20160920235207 builduid: 8b8ccdf06c314d089c82f9ce769d6bf3 revision: 6ad72777c10d5bd2c3107eafe922ccf2e130deff ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-22 07:49:09.988456) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-22 07:49:09.989260) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-22 07:49:09.989844) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-22 07:49:10.006285) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-22 07:49:10.006691) ========= 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=920470bf432669ad7e3a5387000001ca-1474554887.763818-1325601685 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-22 07:49:10-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.4M=0s 2016-09-22 07:49:11 (46.4 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.285959 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-22 07:49:10.308107) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-22 07:49:10.308446) ========= 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=920470bf432669ad7e3a5387000001ca-1474554887.763818-1325601685 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.023476 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-22 07:49:10.358597) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-22 07:49:10.359302) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 6ad72777c10d5bd2c3107eafe922ccf2e130deff --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 6ad72777c10d5bd2c3107eafe922ccf2e130deff --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=920470bf432669ad7e3a5387000001ca-1474554887.763818-1325601685 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-22 07:49:11,313 truncating revision to first 12 chars 2016-09-22 07:49:11,313 Setting DEBUG logging. 2016-09-22 07:49:11,314 attempt 1/10 2016-09-22 07:49:11,314 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/6ad72777c10d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-22 07:49:12,416 unpacking tar archive at: mozilla-aurora-6ad72777c10d/testing/mozharness/ program finished with exit code 0 elapsedTime=1.427836 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-22 07:49:11.800968) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-22 07:49:11.801284) ========= script_repo_revision: 6ad72777c10d5bd2c3107eafe922ccf2e130deff ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-22 07:49:11.801728) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-22 07:49:11.802032) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-22 07:49:11.813839) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 35 secs) (at 2016-09-22 07:49:11.814158) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 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-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] 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=920470bf432669ad7e3a5387000001ca-1474554887.763818-1325601685 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 07:49:12 INFO - MultiFileLogger online at 20160922 07:49:12 in /builds/slave/test 07:49:12 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 07:49:12 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:49:12 INFO - {'append_to_log': False, 07:49:12 INFO - 'base_work_dir': '/builds/slave/test', 07:49:12 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 07:49:12 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:49:12 INFO - 'branch': 'Mozilla-Aurora', 07:49:12 INFO - 'buildbot_json_path': 'buildprops.json', 07:49:12 INFO - 'config_files': ('talos/linux_config.py',), 07:49:12 INFO - 'default_actions': ('clobber', 07:49:12 INFO - 'read-buildbot-config', 07:49:12 INFO - 'download-and-extract', 07:49:12 INFO - 'populate-webroot', 07:49:12 INFO - 'create-virtualenv', 07:49:12 INFO - 'install', 07:49:12 INFO - 'run-tests'), 07:49:12 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:49:12 INFO - 'download_minidump_stackwalk': True, 07:49:12 INFO - 'download_symbols': 'ondemand', 07:49:12 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:49:12 INFO - 'tooltool.py': '/tools/tooltool.py', 07:49:12 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:49:12 INFO - '/tools/misc-python/virtualenv.py')}, 07:49:12 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:49:12 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:49:12 INFO - 'installer_path': 'installer.exe', 07:49:12 INFO - 'log_level': 'info', 07:49:12 INFO - 'log_name': 'talos', 07:49:12 INFO - 'log_to_console': True, 07:49:12 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 07:49:12 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 07:49:12 INFO - 'opt_config_files': (), 07:49:12 INFO - 'pip_index': False, 07:49:12 INFO - 'sps_profile': False, 07:49:12 INFO - 'sps_profile_interval': 0, 07:49:12 INFO - 'suite': 'dromaeojs', 07:49:12 INFO - 'system_bits': '32', 07:49:12 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:49:12 INFO - 'title': 'talos-linux64-ix-053', 07:49:12 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:49:12 INFO - 'use_talos_json': True, 07:49:12 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:49:12 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:49:12 INFO - 'work_dir': 'build'} 07:49:12 INFO - [mozharness: 2016-09-22 14:49:12.865919Z] Running clobber step. 07:49:12 INFO - Running pre-action listener: _resource_record_pre_action 07:49:12 INFO - Running main action method: clobber 07:49:12 INFO - rmtree: /builds/slave/test/build 07:49:12 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:49:13 INFO - Running post-action listener: _resource_record_post_action 07:49:13 INFO - [mozharness: 2016-09-22 14:49:13.333819Z] Finished clobber step (success) 07:49:13 INFO - [mozharness: 2016-09-22 14:49:13.333919Z] Running read-buildbot-config step. 07:49:13 INFO - Running pre-action listener: _resource_record_pre_action 07:49:13 INFO - Running main action method: read_buildbot_config 07:49:13 INFO - Using buildbot properties: 07:49:13 INFO - { 07:49:13 INFO - "project": "", 07:49:13 INFO - "product": "firefox", 07:49:13 INFO - "script_repo_revision": "production", 07:49:13 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 07:49:13 INFO - "repository": "", 07:49:13 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 07:49:13 INFO - "buildid": "20160920235207", 07:49:13 INFO - "pgo_build": "True", 07:49:13 INFO - "basedir": "/builds/slave/test", 07:49:13 INFO - "buildnumber": 779, 07:49:13 INFO - "slavename": "talos-linux64-ix-053", 07:49:13 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 07:49:13 INFO - "platform": "ubuntu64_hw", 07:49:13 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 07:49:13 INFO - "branch": "mozilla-aurora", 07:49:13 INFO - "repo_path": "releases/mozilla-aurora", 07:49:13 INFO - "revision": "6ad72777c10d5bd2c3107eafe922ccf2e130deff", 07:49:13 INFO - "stage_platform": "linux64-pgo", 07:49:13 INFO - "builduid": "8b8ccdf06c314d089c82f9ce769d6bf3", 07:49:13 INFO - "slavebuilddir": "test" 07:49:13 INFO - } 07:49:13 INFO - Found installer url https://queue.taskcluster.net/v1/task/Szx1hifsTvmxevhQo86Mlw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2. 07:49:13 INFO - Running post-action listener: _resource_record_post_action 07:49:13 INFO - [mozharness: 2016-09-22 14:49:13.336352Z] Finished read-buildbot-config step (success) 07:49:13 INFO - [mozharness: 2016-09-22 14:49:13.336441Z] Running download-and-extract step. 07:49:13 INFO - Running pre-action listener: _resource_record_pre_action 07:49:13 INFO - Running main action method: download_and_extract 07:49:13 INFO - mkdir: /builds/slave/test/build/tests 07:49:13 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')]} 07:49:13 INFO - trying https://queue.taskcluster.net/v1/task/Szx1hifsTvmxevhQo86Mlw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 07:49:13 INFO - Downloading https://queue.taskcluster.net/v1/task/Szx1hifsTvmxevhQo86Mlw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 07:49:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Szx1hifsTvmxevhQo86Mlw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 07:49:13 INFO - Downloaded 1361 bytes. 07:49:13 INFO - Reading from file /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 07:49:13 INFO - Using the following test package requirements: 07:49:13 INFO - {u'common': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 07:49:13 INFO - u'cppunittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 07:49:13 INFO - u'firefox-51.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 07:49:13 INFO - u'gtest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 07:49:13 INFO - u'firefox-51.0a2.en-US.linux-x86_64.gtest.tests.zip'], 07:49:13 INFO - u'jittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 07:49:13 INFO - u'jsshell-linux-x86_64.zip'], 07:49:13 INFO - u'mochitest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 07:49:13 INFO - u'firefox-51.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 07:49:13 INFO - u'mozbase': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 07:49:13 INFO - u'reftest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 07:49:13 INFO - u'firefox-51.0a2.en-US.linux-x86_64.reftest.tests.zip'], 07:49:13 INFO - u'talos': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 07:49:13 INFO - u'firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'], 07:49:13 INFO - u'web-platform': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 07:49:13 INFO - u'firefox-51.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 07:49:13 INFO - u'xpcshell': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 07:49:13 INFO - u'firefox-51.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 07:49:13 INFO - Downloading packages: [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 07:49:13 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Szx1hifsTvmxevhQo86Mlw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 07:49:13 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Szx1hifsTvmxevhQo86Mlw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 07:49:13 INFO - Fetch https://queue.taskcluster.net/v1/task/Szx1hifsTvmxevhQo86Mlw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 07:49:14 INFO - Expected file size: 21893574 07:49:18 INFO - Downloading packages: [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 07:49:18 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Szx1hifsTvmxevhQo86Mlw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 07:49:18 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Szx1hifsTvmxevhQo86Mlw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 07:49:18 INFO - Fetch https://queue.taskcluster.net/v1/task/Szx1hifsTvmxevhQo86Mlw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 07:49:19 INFO - Expected file size: 21893574 07:49:24 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Szx1hifsTvmxevhQo86Mlw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip 07:49:24 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Szx1hifsTvmxevhQo86Mlw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 07:49:24 INFO - Fetch https://queue.taskcluster.net/v1/task/Szx1hifsTvmxevhQo86Mlw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip into memory 07:49:24 INFO - Expected file size: 14414059 07:49: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')]} 07:49:25 INFO - trying https://queue.taskcluster.net/v1/task/Szx1hifsTvmxevhQo86Mlw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 07:49:25 INFO - Downloading https://queue.taskcluster.net/v1/task/Szx1hifsTvmxevhQo86Mlw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 07:49:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Szx1hifsTvmxevhQo86Mlw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 07:49:27 INFO - Downloaded 63960240 bytes. 07:49:27 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Szx1hifsTvmxevhQo86Mlw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 07:49:27 INFO - mkdir: /builds/slave/test/properties 07:49:27 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:49:27 INFO - Writing to file /builds/slave/test/properties/build_url 07:49:27 INFO - Contents: 07:49:27 INFO - build_url:https://queue.taskcluster.net/v1/task/Szx1hifsTvmxevhQo86Mlw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 07:49:27 INFO - Running post-action listener: _resource_record_post_action 07:49:27 INFO - Running post-action listener: set_extra_try_arguments 07:49:27 INFO - [mozharness: 2016-09-22 14:49:27.785885Z] Finished download-and-extract step (success) 07:49:27 INFO - [mozharness: 2016-09-22 14:49:27.786113Z] Running populate-webroot step. 07:49:27 INFO - Running pre-action listener: _resource_record_pre_action 07:49:27 INFO - Running main action method: populate_webroot 07:49:27 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:49:27 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:49:27 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:49:27 INFO - '--tppagecycles', 07:49:27 INFO - '7'], 07:49:27 INFO - 'tests': ['tsvgm']}}, 07:49:27 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:49:27 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:49:27 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:49:27 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:49:27 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:49:27 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:49:27 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:49:27 INFO - 'talos_options': ['--disable-e10s'], 07:49:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:49:27 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:49:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:49:27 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:49:27 INFO - 'talos_options': ['--disable-e10s'], 07:49:27 INFO - 'tests': ['damp', 'tps']}, 07:49:27 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:49:27 INFO - 'tests': ['damp', 'tps']}, 07:49:27 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:49:27 INFO - 'tests': ['dromaeo_dom']}, 07:49:27 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:49:27 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:49:27 INFO - 'tests': ['basic_compositor_video']}, 07:49:27 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:49:27 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:49:27 INFO - 'tests': ['a11yr', 07:49:27 INFO - 'ts_paint', 07:49:27 INFO - 'tpaint', 07:49:27 INFO - 'sessionrestore', 07:49:27 INFO - 'sessionrestore_no_auto_restore', 07:49:27 INFO - 'tabpaint']}, 07:49:27 INFO - 'other-e10s': {'tests': ['a11yr', 07:49:27 INFO - 'ts_paint', 07:49:27 INFO - 'tpaint', 07:49:27 INFO - 'sessionrestore', 07:49:27 INFO - 'sessionrestore_no_auto_restore', 07:49:27 INFO - 'tabpaint']}, 07:49:27 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:49:27 INFO - 'ts_paint', 07:49:27 INFO - 'tpaint', 07:49:27 INFO - 'sessionrestore', 07:49:27 INFO - 'sessionrestore_no_auto_restore', 07:49:27 INFO - 'tabpaint']}, 07:49:27 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:49:27 INFO - 'ts_paint', 07:49:27 INFO - 'tpaint', 07:49:27 INFO - 'sessionrestore', 07:49:27 INFO - 'sessionrestore_no_auto_restore', 07:49:27 INFO - 'tabpaint']}, 07:49:27 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:49:27 INFO - 'tests': ['a11yr', 07:49:27 INFO - 'ts_paint', 07:49:27 INFO - 'tpaint', 07:49:27 INFO - 'sessionrestore', 07:49:27 INFO - 'sessionrestore_no_auto_restore', 07:49:27 INFO - 'tabpaint']}, 07:49:27 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:49:27 INFO - 'tests': ['a11yr', 07:49:27 INFO - 'ts_paint', 07:49:27 INFO - 'tpaint', 07:49:27 INFO - 'sessionrestore', 07:49:27 INFO - 'sessionrestore_no_auto_restore', 07:49:27 INFO - 'tabpaint']}, 07:49:27 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:49:27 INFO - 'tests': ['tsvgx', 07:49:27 INFO - 'tsvgr_opacity', 07:49:27 INFO - 'tart', 07:49:27 INFO - 'tscrollx', 07:49:27 INFO - 'cart']}, 07:49:27 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:49:27 INFO - 'tsvgr_opacity', 07:49:27 INFO - 'tart', 07:49:27 INFO - 'tscrollx', 07:49:27 INFO - 'cart']}, 07:49:27 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:49:27 INFO - 'talos_options': ['--disable-e10s'], 07:49:27 INFO - 'tests': ['tp5o']}, 07:49:27 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:49:27 INFO - 'tests': ['tp5o']}, 07:49:27 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:49:27 INFO - 'talos_options': ['--disable-e10s', 07:49:27 INFO - '--xperf_path', 07:49:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:49:27 INFO - 'tests': ['tp5n']}, 07:49:27 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:49:27 INFO - 'talos_options': ['--xperf_path', 07:49:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:49:27 INFO - 'tests': ['tp5n']}}, 07:49:27 INFO - 'talos.zip': {'path': '', 07:49:27 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:49:27 INFO - Running post-action listener: _resource_record_post_action 07:49:27 INFO - [mozharness: 2016-09-22 14:49:27.803914Z] Finished populate-webroot step (success) 07:49:27 INFO - [mozharness: 2016-09-22 14:49:27.804003Z] Running create-virtualenv step. 07:49:27 INFO - Running pre-action listener: _resource_record_pre_action 07:49:27 INFO - Running main action method: create_virtualenv 07:49:27 INFO - Creating virtualenv /builds/slave/test/build/venv 07:49:27 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 07:49:27 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:49:27 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:49:27 INFO - Using real prefix '/usr' 07:49:27 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:49:28 INFO - Installing distribute.............................................................................................................................................................................................done. 07:49:30 INFO - Installing pip.................done. 07:49:30 INFO - Return code: 0 07:49:30 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:49:30 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')]} 07:49:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:49:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:49:30 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')]} 07:49:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:49:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:49:30 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 0x7ff9a1c13f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16998a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1766ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1763f60>, '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 0x1764d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17651d0>, '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': '920470bf432669ad7e3a5387000001ca-1474554887.763818-1325601685', '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 07:49:30 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 07:49:30 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 07:49:30 INFO - Using env: {'DISPLAY': ':0', 07:49:30 INFO - 'HOME': '/home/cltbld', 07:49:30 INFO - 'LANG': 'en_US.UTF-8', 07:49:30 INFO - 'LANGUAGE': 'en_US:en', 07:49:30 INFO - 'LOGNAME': 'cltbld', 07:49:30 INFO - 'MAIL': '/var/mail/cltbld', 07:49:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:49:30 INFO - 'MOZ_NO_REMOTE': '1', 07:49:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:49:30 INFO - 'NO_EM_RESTART': '1', 07:49:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:49:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:49:30 INFO - 'PWD': '/builds/slave/test', 07:49:30 INFO - 'SHELL': '/bin/bash', 07:49:30 INFO - 'SHLVL': '1', 07:49:30 INFO - 'TERM': 'linux', 07:49:30 INFO - 'TMOUT': '86400', 07:49:30 INFO - 'USER': 'cltbld', 07:49:30 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1474554887.763818-1325601685', 07:49:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:49:30 INFO - '_': '/tools/buildbot/bin/python'} 07:49:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:49:30 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 07:49:30 INFO - Cleaning up... 07:49:30 INFO - Return code: 0 07:49:30 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:49:30 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')]} 07:49:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:49:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:49:30 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')]} 07:49:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:49:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:49:30 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 0x7ff9a1c13f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16998a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1766ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1763f60>, '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 0x1764d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17651d0>, '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': '920470bf432669ad7e3a5387000001ca-1474554887.763818-1325601685', '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 07:49:30 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 07:49:30 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 07:49:30 INFO - Using env: {'DISPLAY': ':0', 07:49:30 INFO - 'HOME': '/home/cltbld', 07:49:30 INFO - 'LANG': 'en_US.UTF-8', 07:49:30 INFO - 'LANGUAGE': 'en_US:en', 07:49:30 INFO - 'LOGNAME': 'cltbld', 07:49:30 INFO - 'MAIL': '/var/mail/cltbld', 07:49:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:49:30 INFO - 'MOZ_NO_REMOTE': '1', 07:49:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:49:30 INFO - 'NO_EM_RESTART': '1', 07:49:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:49:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:49:30 INFO - 'PWD': '/builds/slave/test', 07:49:30 INFO - 'SHELL': '/bin/bash', 07:49:30 INFO - 'SHLVL': '1', 07:49:30 INFO - 'TERM': 'linux', 07:49:30 INFO - 'TMOUT': '86400', 07:49:30 INFO - 'USER': 'cltbld', 07:49:30 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1474554887.763818-1325601685', 07:49:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:49:30 INFO - '_': '/tools/buildbot/bin/python'} 07:49:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:49:30 INFO - Downloading/unpacking psutil>=3.1.1 07:49: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 07:49: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 07:49: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 07:49: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 07:49: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 07:49: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 07:49:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:49:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:49:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:49:32 INFO - Installing collected packages: psutil 07:49:32 INFO - Running setup.py install for psutil 07:49:32 INFO - building 'psutil._psutil_linux' extension 07:49:32 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 07:49:33 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 07:49:33 INFO - building 'psutil._psutil_posix' extension 07:49:33 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 07:49:33 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 07:49:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:49:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:49:33 INFO - Successfully installed psutil 07:49:33 INFO - Cleaning up... 07:49:33 INFO - Return code: 0 07:49:33 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:49:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:49:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:49:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:49:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:49:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:49:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:49:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff9a1c13f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16998a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1766ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1763f60>, '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 0x1764d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17651d0>, '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': '920470bf432669ad7e3a5387000001ca-1474554887.763818-1325601685', '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 07:49:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 07:49:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 07:49:33 INFO - Using env: {'DISPLAY': ':0', 07:49:33 INFO - 'HOME': '/home/cltbld', 07:49:33 INFO - 'LANG': 'en_US.UTF-8', 07:49:33 INFO - 'LANGUAGE': 'en_US:en', 07:49:33 INFO - 'LOGNAME': 'cltbld', 07:49:33 INFO - 'MAIL': '/var/mail/cltbld', 07:49:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:49:33 INFO - 'MOZ_NO_REMOTE': '1', 07:49:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:49:33 INFO - 'NO_EM_RESTART': '1', 07:49:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:49:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:49:33 INFO - 'PWD': '/builds/slave/test', 07:49:33 INFO - 'SHELL': '/bin/bash', 07:49:33 INFO - 'SHLVL': '1', 07:49:33 INFO - 'TERM': 'linux', 07:49:33 INFO - 'TMOUT': '86400', 07:49:33 INFO - 'USER': 'cltbld', 07:49:33 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1474554887.763818-1325601685', 07:49:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:49:33 INFO - '_': '/tools/buildbot/bin/python'} 07:49:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:49:33 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:49:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:49:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:49:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:49:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:49:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:49:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:49:35 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:49:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:49:35 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:49:35 INFO - Installing collected packages: mozsystemmonitor 07:49:35 INFO - Running setup.py install for mozsystemmonitor 07:49:35 INFO - Successfully installed mozsystemmonitor 07:49:35 INFO - Cleaning up... 07:49:35 INFO - Return code: 0 07:49:35 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:49:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:49:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:49:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:49:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:49:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:49:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:49:35 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 0x7ff9a1c13f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16998a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1766ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1763f60>, '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 0x1764d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17651d0>, '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': '920470bf432669ad7e3a5387000001ca-1474554887.763818-1325601685', '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 07:49:35 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 07:49:35 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 07:49:35 INFO - Using env: {'DISPLAY': ':0', 07:49:35 INFO - 'HOME': '/home/cltbld', 07:49:35 INFO - 'LANG': 'en_US.UTF-8', 07:49:35 INFO - 'LANGUAGE': 'en_US:en', 07:49:35 INFO - 'LOGNAME': 'cltbld', 07:49:35 INFO - 'MAIL': '/var/mail/cltbld', 07:49:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:49:35 INFO - 'MOZ_NO_REMOTE': '1', 07:49:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:49:35 INFO - 'NO_EM_RESTART': '1', 07:49:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:49:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:49:35 INFO - 'PWD': '/builds/slave/test', 07:49:35 INFO - 'SHELL': '/bin/bash', 07:49:35 INFO - 'SHLVL': '1', 07:49:35 INFO - 'TERM': 'linux', 07:49:35 INFO - 'TMOUT': '86400', 07:49:35 INFO - 'USER': 'cltbld', 07:49:35 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1474554887.763818-1325601685', 07:49:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:49:35 INFO - '_': '/tools/buildbot/bin/python'} 07:49:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:49:35 INFO - Downloading/unpacking jsonschema==2.5.1 07:49:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:49:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:49:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:49:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:49:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:49:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:49:37 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:49:37 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:49:37 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 07:49:37 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 07:49:37 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 07:49:37 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 07:49:37 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 07:49:37 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 07:49:37 INFO - Downloading functools32-3.2.3-2.tar.gz 07:49:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:49:38 INFO - warning: no files found matching '*.txt' 07:49:38 INFO - no previously-included directories found matching 'build' 07:49:38 INFO - no previously-included directories found matching 'dist' 07:49:38 INFO - no previously-included directories found matching '.git*' 07:49:38 INFO - Installing collected packages: jsonschema, functools32 07:49:38 INFO - Running setup.py install for functools32 07:49:38 INFO - warning: no files found matching '*.txt' 07:49:38 INFO - no previously-included directories found matching 'build' 07:49:38 INFO - no previously-included directories found matching 'dist' 07:49:38 INFO - no previously-included directories found matching '.git*' 07:49:38 INFO - Successfully installed jsonschema functools32 07:49:38 INFO - Cleaning up... 07:49:38 INFO - Return code: 0 07:49:38 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:49:38 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')]} 07:49:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:49:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:49:38 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')]} 07:49:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:49:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:49:38 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 0x7ff9a1c13f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16998a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1766ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1763f60>, '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 0x1764d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17651d0>, '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': '920470bf432669ad7e3a5387000001ca-1474554887.763818-1325601685', '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 07:49:38 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 07:49:38 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 07:49:38 INFO - Using env: {'DISPLAY': ':0', 07:49:38 INFO - 'HOME': '/home/cltbld', 07:49:38 INFO - 'LANG': 'en_US.UTF-8', 07:49:38 INFO - 'LANGUAGE': 'en_US:en', 07:49:38 INFO - 'LOGNAME': 'cltbld', 07:49:38 INFO - 'MAIL': '/var/mail/cltbld', 07:49:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:49:38 INFO - 'MOZ_NO_REMOTE': '1', 07:49:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:49:38 INFO - 'NO_EM_RESTART': '1', 07:49:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:49:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:49:38 INFO - 'PWD': '/builds/slave/test', 07:49:38 INFO - 'SHELL': '/bin/bash', 07:49:38 INFO - 'SHLVL': '1', 07:49:38 INFO - 'TERM': 'linux', 07:49:38 INFO - 'TMOUT': '86400', 07:49:38 INFO - 'USER': 'cltbld', 07:49:38 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1474554887.763818-1325601685', 07:49:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:49:38 INFO - '_': '/tools/buildbot/bin/python'} 07:49:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:49:38 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:49:38 INFO - Cleaning up... 07:49:38 INFO - Return code: 0 07:49:38 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:49:38 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')]} 07:49:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:49:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:49:38 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')]} 07:49:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:49:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:49:38 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 0x7ff9a1c13f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16998a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1766ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1763f60>, '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 0x1764d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17651d0>, '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': '920470bf432669ad7e3a5387000001ca-1474554887.763818-1325601685', '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 07:49:38 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 07:49:38 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 07:49:38 INFO - Using env: {'DISPLAY': ':0', 07:49:38 INFO - 'HOME': '/home/cltbld', 07:49:38 INFO - 'LANG': 'en_US.UTF-8', 07:49:38 INFO - 'LANGUAGE': 'en_US:en', 07:49:38 INFO - 'LOGNAME': 'cltbld', 07:49:38 INFO - 'MAIL': '/var/mail/cltbld', 07:49:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:49:38 INFO - 'MOZ_NO_REMOTE': '1', 07:49:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:49:38 INFO - 'NO_EM_RESTART': '1', 07:49:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:49:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:49:38 INFO - 'PWD': '/builds/slave/test', 07:49:38 INFO - 'SHELL': '/bin/bash', 07:49:38 INFO - 'SHLVL': '1', 07:49:38 INFO - 'TERM': 'linux', 07:49:38 INFO - 'TMOUT': '86400', 07:49:38 INFO - 'USER': 'cltbld', 07:49:38 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1474554887.763818-1325601685', 07:49:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:49:38 INFO - '_': '/tools/buildbot/bin/python'} 07:49:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:49:38 INFO - Downloading/unpacking blobuploader==1.2.4 07:49:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:49:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:49:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:49:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:49:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:49:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:49:40 INFO - Downloading blobuploader-1.2.4.tar.gz 07:49:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:49:40 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:49:40 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 07:49:40 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 07:49:40 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 07:49:40 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 07:49:40 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 07:49:40 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 07:49:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:49:41 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:49:41 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 07:49:41 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 07:49:41 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 07:49:41 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 07:49:41 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 07:49:41 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 07:49:41 INFO - Downloading docopt-0.6.1.tar.gz 07:49:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:49:41 INFO - Installing collected packages: blobuploader, requests, docopt 07:49:41 INFO - Running setup.py install for blobuploader 07:49:41 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:49:41 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:49:41 INFO - Running setup.py install for requests 07:49:41 INFO - Running setup.py install for docopt 07:49:41 INFO - Successfully installed blobuploader requests docopt 07:49:41 INFO - Cleaning up... 07:49:41 INFO - Return code: 0 07:49:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:49:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:49:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:49:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:49:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:49:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:49:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:49:41 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 0x7ff9a1c13f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16998a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1766ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1763f60>, '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 0x1764d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17651d0>, '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': '920470bf432669ad7e3a5387000001ca-1474554887.763818-1325601685', '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 07:49:41 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 07:49:41 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 07:49:41 INFO - Using env: {'DISPLAY': ':0', 07:49:41 INFO - 'HOME': '/home/cltbld', 07:49:41 INFO - 'LANG': 'en_US.UTF-8', 07:49:41 INFO - 'LANGUAGE': 'en_US:en', 07:49:41 INFO - 'LOGNAME': 'cltbld', 07:49:41 INFO - 'MAIL': '/var/mail/cltbld', 07:49:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:49:41 INFO - 'MOZ_NO_REMOTE': '1', 07:49:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:49:41 INFO - 'NO_EM_RESTART': '1', 07:49:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:49:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:49:41 INFO - 'PWD': '/builds/slave/test', 07:49:41 INFO - 'SHELL': '/bin/bash', 07:49:41 INFO - 'SHLVL': '1', 07:49:41 INFO - 'TERM': 'linux', 07:49:41 INFO - 'TMOUT': '86400', 07:49:41 INFO - 'USER': 'cltbld', 07:49:41 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1474554887.763818-1325601685', 07:49:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:49:41 INFO - '_': '/tools/buildbot/bin/python'} 07:49:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:49:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:49:42 INFO - Running setup.py (path:/tmp/pip-awE6Hs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:49:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:49:42 INFO - Running setup.py (path:/tmp/pip-fh4sIt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:49:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:49:42 INFO - Running setup.py (path:/tmp/pip-CSJIlc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:49:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:49:42 INFO - Running setup.py (path:/tmp/pip-9EnxIv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:49:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:49:42 INFO - Running setup.py (path:/tmp/pip-h1hNud-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:49:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:49:42 INFO - Running setup.py (path:/tmp/pip-Qu88RL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:49:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:49:42 INFO - Running setup.py (path:/tmp/pip-TFpfcQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:49:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:49:42 INFO - Running setup.py (path:/tmp/pip-fddUgJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:49:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:49:42 INFO - Running setup.py (path:/tmp/pip-GrJC4K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:49:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:49:42 INFO - Running setup.py (path:/tmp/pip-jmynCw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:49:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:49:42 INFO - Running setup.py (path:/tmp/pip-Xur1rK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:49:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:49:42 INFO - Running setup.py (path:/tmp/pip-tMcJX8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:49:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:49:43 INFO - Running setup.py (path:/tmp/pip-ypIaQb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:49:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:49:43 INFO - Running setup.py (path:/tmp/pip-nUgiKA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:49:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:49:43 INFO - Running setup.py (path:/tmp/pip-L6AfkT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:49:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:49:43 INFO - Running setup.py (path:/tmp/pip-sJD_Pq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:49:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:49:43 INFO - Running setup.py (path:/tmp/pip-T1Mfr6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:49:43 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:49:43 INFO - Running setup.py install for manifestparser 07:49:43 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:49:43 INFO - Running setup.py install for mozcrash 07:49:43 INFO - Running setup.py install for mozdebug 07:49:43 INFO - Running setup.py install for mozdevice 07:49:43 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:49:43 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:49:43 INFO - Running setup.py install for mozfile 07:49:43 INFO - Running setup.py install for mozhttpd 07:49:43 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:49:43 INFO - Running setup.py install for mozinfo 07:49:44 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:49:44 INFO - Running setup.py install for mozInstall 07:49:44 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:49:44 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:49:44 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:49:44 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:49:44 INFO - Running setup.py install for mozleak 07:49:44 INFO - Running setup.py install for mozlog 07:49:44 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:49:44 INFO - Running setup.py install for moznetwork 07:49:44 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:49:44 INFO - Running setup.py install for mozprocess 07:49:44 INFO - Running setup.py install for mozprofile 07:49:44 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:49:44 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:49:44 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:49:44 INFO - Running setup.py install for mozrunner 07:49:44 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:49:44 INFO - Running setup.py install for mozscreenshot 07:49:44 INFO - Running setup.py install for moztest 07:49:45 INFO - Running setup.py install for mozversion 07:49:45 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:49:45 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:49:45 INFO - Cleaning up... 07:49:45 INFO - Return code: 0 07:49:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:49:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:49:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:49:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:49:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:49:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:49:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:49:45 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 0x7ff9a1c13f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16998a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1766ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1763f60>, '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 0x1764d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17651d0>, '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': '920470bf432669ad7e3a5387000001ca-1474554887.763818-1325601685', '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 07:49:45 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 07:49:45 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 07:49:45 INFO - Using env: {'DISPLAY': ':0', 07:49:45 INFO - 'HOME': '/home/cltbld', 07:49:45 INFO - 'LANG': 'en_US.UTF-8', 07:49:45 INFO - 'LANGUAGE': 'en_US:en', 07:49:45 INFO - 'LOGNAME': 'cltbld', 07:49:45 INFO - 'MAIL': '/var/mail/cltbld', 07:49:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:49:45 INFO - 'MOZ_NO_REMOTE': '1', 07:49:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:49:45 INFO - 'NO_EM_RESTART': '1', 07:49:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:49:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:49:45 INFO - 'PWD': '/builds/slave/test', 07:49:45 INFO - 'SHELL': '/bin/bash', 07:49:45 INFO - 'SHLVL': '1', 07:49:45 INFO - 'TERM': 'linux', 07:49:45 INFO - 'TMOUT': '86400', 07:49:45 INFO - 'USER': 'cltbld', 07:49:45 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1474554887.763818-1325601685', 07:49:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:49:45 INFO - '_': '/tools/buildbot/bin/python'} 07:49:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:49:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:49:45 INFO - Running setup.py (path:/tmp/pip-iZKjla-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:49:45 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)) 07:49:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:49:45 INFO - Running setup.py (path:/tmp/pip-7EAa70-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:49:45 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)) 07:49:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:49:45 INFO - Running setup.py (path:/tmp/pip-gHwKz3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:49:45 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)) 07:49:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:49:45 INFO - Running setup.py (path:/tmp/pip-YTgcga-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:49:45 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)) 07:49:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:49:45 INFO - Running setup.py (path:/tmp/pip-J1k2Ur-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:49:45 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)) 07:49:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:49:45 INFO - Running setup.py (path:/tmp/pip-jLo4X8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:49:45 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)) 07:49:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:49:45 INFO - Running setup.py (path:/tmp/pip-Ryh9uw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:49:45 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)) 07:49:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:49:45 INFO - Running setup.py (path:/tmp/pip-iq2ebK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:49:45 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)) 07:49:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:49:45 INFO - Running setup.py (path:/tmp/pip-OaOArP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:49:46 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)) 07:49:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:49:46 INFO - Running setup.py (path:/tmp/pip-GL2R1t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:49:46 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)) 07:49:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:49:46 INFO - Running setup.py (path:/tmp/pip-ZLKnGz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:49:46 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)) 07:49:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:49:46 INFO - Running setup.py (path:/tmp/pip-aNdmh6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:49:46 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)) 07:49:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:49:46 INFO - Running setup.py (path:/tmp/pip-5cUp4K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:49:46 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)) 07:49:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:49:46 INFO - Running setup.py (path:/tmp/pip-dE4fe3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:49:46 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)) 07:49:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:49:46 INFO - Running setup.py (path:/tmp/pip-B_QKGp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:49:46 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)) 07:49:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:49:46 INFO - Running setup.py (path:/tmp/pip-wNt2sd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:49:46 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)) 07:49:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:49:46 INFO - Running setup.py (path:/tmp/pip-i7gp1_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:49:46 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)) 07:49:46 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)) 07:49:46 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)) 07:49:46 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)) 07:49:46 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)) 07:49:46 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)) 07:49:46 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:49:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:49:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:49:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:49:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:49:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:49:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:49:48 INFO - Downloading blessings-1.6.tar.gz 07:49:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:49:48 INFO - Installing collected packages: blessings 07:49:48 INFO - Running setup.py install for blessings 07:49:48 INFO - Successfully installed blessings 07:49:48 INFO - Cleaning up... 07:49:48 INFO - Return code: 0 07:49:48 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:49:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:49:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:49:48 INFO - Reading from file tmpfile_stdout 07:49:48 INFO - Current package versions: 07:49:48 INFO - argparse == 1.2.1 07:49:48 INFO - blessings == 1.6 07:49:48 INFO - blobuploader == 1.2.4 07:49:48 INFO - docopt == 0.6.1 07:49:48 INFO - functools32 == 3.2.3-2 07:49:48 INFO - jsonschema == 2.5.1 07:49:48 INFO - manifestparser == 1.1 07:49:48 INFO - mozInstall == 1.12 07:49:48 INFO - mozcrash == 0.17 07:49:48 INFO - mozdebug == 0.1 07:49:48 INFO - mozdevice == 0.48 07:49:48 INFO - mozfile == 1.2 07:49:48 INFO - mozhttpd == 0.7 07:49:48 INFO - mozinfo == 0.9 07:49:48 INFO - mozleak == 0.1 07:49:48 INFO - mozlog == 3.4 07:49:48 INFO - moznetwork == 0.27 07:49:48 INFO - mozprocess == 0.23 07:49:48 INFO - mozprofile == 0.28 07:49:48 INFO - mozrunner == 6.12 07:49:48 INFO - mozscreenshot == 0.1 07:49:48 INFO - mozsystemmonitor == 0.3 07:49:48 INFO - moztest == 0.7 07:49:48 INFO - mozversion == 1.4 07:49:48 INFO - psutil == 3.1.1 07:49:48 INFO - requests == 1.2.3 07:49:48 INFO - wsgiref == 0.1.2 07:49:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:49:48 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')]} 07:49:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:49:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:49:48 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')]} 07:49:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:49:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:49:48 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 0x7ff9a1c13f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16998a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1766ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1763f60>, '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 0x1764d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17651d0>, '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': '920470bf432669ad7e3a5387000001ca-1474554887.763818-1325601685', '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 07:49:48 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 07:49:48 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 07:49:48 INFO - Using env: {'DISPLAY': ':0', 07:49:48 INFO - 'HOME': '/home/cltbld', 07:49:48 INFO - 'LANG': 'en_US.UTF-8', 07:49:48 INFO - 'LANGUAGE': 'en_US:en', 07:49:48 INFO - 'LOGNAME': 'cltbld', 07:49:48 INFO - 'MAIL': '/var/mail/cltbld', 07:49:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:49:48 INFO - 'MOZ_NO_REMOTE': '1', 07:49:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:49:48 INFO - 'NO_EM_RESTART': '1', 07:49:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:49:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:49:48 INFO - 'PWD': '/builds/slave/test', 07:49:48 INFO - 'SHELL': '/bin/bash', 07:49:48 INFO - 'SHLVL': '1', 07:49:48 INFO - 'TERM': 'linux', 07:49:48 INFO - 'TMOUT': '86400', 07:49:48 INFO - 'USER': 'cltbld', 07:49:48 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1474554887.763818-1325601685', 07:49:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:49:48 INFO - '_': '/tools/buildbot/bin/python'} 07:49:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:49:49 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)) 07:49:49 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)) 07:49:49 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)) 07:49:49 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)) 07:49:49 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)) 07:49:49 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)) 07:49:49 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)) 07:49:49 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)) 07:49:49 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)) 07:49:49 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)) 07:49:49 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)) 07:49:49 INFO - Cleaning up... 07:49:49 INFO - Return code: 0 07:49:49 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:49:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:49:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:49:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:49:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:49:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:49:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:49:49 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 0x7ff9a1c13f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16998a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1766ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1763f60>, '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 0x1764d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17651d0>, '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': '920470bf432669ad7e3a5387000001ca-1474554887.763818-1325601685', '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 07:49:49 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 07:49:49 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 07:49:49 INFO - Using env: {'DISPLAY': ':0', 07:49:49 INFO - 'HOME': '/home/cltbld', 07:49:49 INFO - 'LANG': 'en_US.UTF-8', 07:49:49 INFO - 'LANGUAGE': 'en_US:en', 07:49:49 INFO - 'LOGNAME': 'cltbld', 07:49:49 INFO - 'MAIL': '/var/mail/cltbld', 07:49:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:49:49 INFO - 'MOZ_NO_REMOTE': '1', 07:49:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:49:49 INFO - 'NO_EM_RESTART': '1', 07:49:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:49:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:49:49 INFO - 'PWD': '/builds/slave/test', 07:49:49 INFO - 'SHELL': '/bin/bash', 07:49:49 INFO - 'SHLVL': '1', 07:49:49 INFO - 'TERM': 'linux', 07:49:49 INFO - 'TMOUT': '86400', 07:49:49 INFO - 'USER': 'cltbld', 07:49:49 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1474554887.763818-1325601685', 07:49:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:49:49 INFO - '_': '/tools/buildbot/bin/python'} 07:49:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:49:49 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:49:49 INFO - Cleaning up... 07:49:49 INFO - Return code: 0 07:49:49 INFO - Running post-action listener: _resource_record_post_action 07:49:49 INFO - Running post-action listener: _start_resource_monitoring 07:49:49 INFO - Starting resource monitoring. 07:49:49 INFO - [mozharness: 2016-09-22 14:49:49.459784Z] Finished create-virtualenv step (success) 07:49:49 INFO - [mozharness: 2016-09-22 14:49:49.460280Z] Running install step. 07:49:49 INFO - Running pre-action listener: _resource_record_pre_action 07:49:49 INFO - Running main action method: install 07:49:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:49:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:49:49 INFO - Reading from file tmpfile_stdout 07:49:49 INFO - Detecting whether we're running mozinstall >=1.0... 07:49:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:49:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:49:49 INFO - Reading from file tmpfile_stdout 07:49:49 INFO - Output received: 07:49:49 INFO - Usage: mozinstall [options] installer 07:49:49 INFO - Options: 07:49:49 INFO - -h, --help show this help message and exit 07:49:49 INFO - -d DEST, --destination=DEST 07:49:49 INFO - Directory to install application into. [default: 07:49:49 INFO - "/builds/slave/test"] 07:49:49 INFO - --app=APP Application being installed. [default: firefox] 07:49:49 INFO - mkdir: /builds/slave/test/build/application 07:49:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 07:49:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 07:50:05 INFO - Reading from file tmpfile_stdout 07:50:05 INFO - Output received: 07:50:05 INFO - /builds/slave/test/build/application/firefox/firefox 07:50:05 INFO - Running post-action listener: _resource_record_post_action 07:50:05 INFO - [mozharness: 2016-09-22 14:50:05.787852Z] Finished install step (success) 07:50:05 INFO - [mozharness: 2016-09-22 14:50:05.788186Z] Running run-tests step. 07:50:05 INFO - Running pre-action listener: _resource_record_pre_action 07:50:05 INFO - Running main action method: run_tests 07:50:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:50:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:50:05 INFO - Python 2.7.3 07:50:05 INFO - Return code: 0 07:50:05 INFO - grabbing minidump binary from tooltool 07:50:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:50:05 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 0x1763f60>, '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 0x1764d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17651d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:50:05 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 07:50:05 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 07:50:05 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 07:50:05 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:50:05 INFO - Return code: 0 07:50:05 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 07:50:05 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:50:05 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:50:05 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 07:50:05 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:50:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Szx1hifsTvmxevhQo86Mlw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-053', '--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 07:50:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Szx1hifsTvmxevhQo86Mlw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-053 --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 07:50:05 INFO - Using env: {'DISPLAY': ':0', 07:50:05 INFO - 'HOME': '/home/cltbld', 07:50:05 INFO - 'LANG': 'en_US.UTF-8', 07:50:05 INFO - 'LANGUAGE': 'en_US:en', 07:50:05 INFO - 'LOGNAME': 'cltbld', 07:50:05 INFO - 'MAIL': '/var/mail/cltbld', 07:50:05 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:50:05 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 07:50:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:50:05 INFO - 'MOZ_NO_REMOTE': '1', 07:50:05 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:50:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:50:05 INFO - 'NO_EM_RESTART': '1', 07:50:05 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:50:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:50:05 INFO - 'PWD': '/builds/slave/test', 07:50:05 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:50:05 INFO - 'SHELL': '/bin/bash', 07:50:05 INFO - 'SHLVL': '1', 07:50:05 INFO - 'TERM': 'linux', 07:50:05 INFO - 'TMOUT': '86400', 07:50:05 INFO - 'USER': 'cltbld', 07:50:05 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1474554887.763818-1325601685', 07:50:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:50:05 INFO - '_': '/tools/buildbot/bin/python'} 07:50:05 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Szx1hifsTvmxevhQo86Mlw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-053', '--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 07:50:06 INFO - mozversion application_buildid: 20160920235207 07:50:06 INFO - mozversion application_changeset: 6ad72777c10d5bd2c3107eafe922ccf2e130deff 07:50:06 INFO - mozversion application_display_name: FirefoxDeveloperEdition 07:50:06 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:50:06 INFO - mozversion application_name: Firefox 07:50:06 INFO - mozversion application_remotingname: firefox-dev 07:50:06 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 07:50:06 INFO - mozversion application_vendor: Mozilla 07:50:06 INFO - mozversion application_version: 51.0a2 07:50:06 INFO - mozversion platform_buildid: 20160920235207 07:50:06 INFO - mozversion platform_changeset: 6ad72777c10d5bd2c3107eafe922ccf2e130deff 07:50:06 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 07:50:06 INFO - mozversion platform_version: 51.0a2 07:50:06 INFO - using testdate: 1474555806 07:50:06 INFO - actual date: 1474555806 07:50:06 INFO - starting webserver on 'localhost:46022' 07:50:06 INFO - SUITE-START | Running 2 tests 07:50:06 INFO - TEST-START | dromaeo_css 07:50:06 INFO - operating with platform_type : linux_ 07:50:06 INFO - Initialising browser for dromaeo_css test... 07:50:06 INFO - TEST-INFO | started process 20167 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpJJ2s8s/profile http://localhost:46022/getInfo.html) 07:50:10 INFO - PROCESS | 20167 | __metrics Screen width/height:1600/1200 07:50:10 INFO - PROCESS | 20167 | colorDepth:24 07:50:10 INFO - PROCESS | 20167 | Browser inner width/height: 1024/697 07:50:10 INFO - PROCESS | 20167 | __metrics 07:50:10 INFO - PROCESS | 20167 | 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" 07:50:10 INFO - PROCESS | 20167 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:50:11 INFO - PROCESS | 20167 | console.error: PushService: 07:50:11 INFO - PROCESS | 20167 | stateChangeProcessEnqueue: Error transitioning state 07:50:11 INFO - PROCESS | 20167 | UnknownError 07:50:12 INFO - TEST-INFO | 20167: exit 0 07:50:12 INFO - Browser initialized. 07:50:12 INFO - Running cycle 1/1 for dromaeo_css test... 07:50:12 INFO - TEST-INFO | started process 20256 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpJJ2s8s/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:50:23 INFO - PROCESS | 20256 | RSS: Main: 144445440 07:50:23 INFO - PROCESS | 20256 | 07:50:34 INFO - PROCESS | 20256 | [GFX1-]: Refresh driver waiting for the compositor for 1.00074 seconds. 07:50:36 INFO - PROCESS | 20256 | [GFX1-]: Refresh driver waiting for the compositor for 1.00053 seconds. 07:50:44 INFO - PROCESS | 20256 | [GFX1-]: Refresh driver waiting for the compositor for 1.00037 seconds. 07:50:57 INFO - PROCESS | 20256 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 07:51:14 INFO - PROCESS | 20256 | 1474555874974 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] 07:51:17 INFO - PROCESS | 20256 | [GFX1-]: Refresh driver waiting for the compositor for 1.00036 seconds. 07:51:27 INFO - PROCESS | 20256 | [GFX1-]: Refresh driver waiting for the compositor for 1.03284 seconds. 07:51:34 INFO - PROCESS | 20256 | [GFX1-]: Refresh driver waiting for the compositor for 1.01598 seconds. 07:51:37 INFO - PROCESS | 20256 | [GFX1-]: Refresh driver waiting for the compositor for 1.0334 seconds. 07:51:40 INFO - PROCESS | 20256 | [GFX1-]: Refresh driver waiting for the compositor for 1.05051 seconds. 07:51:51 INFO - PROCESS | 20256 | [GFX1-]: Refresh driver waiting for the compositor for 1.03397 seconds. 07:51:54 INFO - PROCESS | 20256 | [GFX1-]: Refresh driver waiting for the compositor for 1.01674 seconds. 07:51:59 INFO - PROCESS | 20256 | [GFX1-]: Refresh driver waiting for the compositor for 1.14964 seconds. 07:52:02 INFO - PROCESS | 20256 | [GFX1-]: Refresh driver waiting for the compositor for 1.03327 seconds. 07:52:08 INFO - PROCESS | 20256 | [GFX1-]: Refresh driver waiting for the compositor for 1.06708 seconds. 07:52:16 INFO - PROCESS | 20256 | [GFX1-]: Refresh driver waiting for the compositor for 1.16683 seconds. 07:52:48 INFO - PROCESS | 20256 | [GFX1-]: Refresh driver waiting for the compositor for 1.01679 seconds. 07:53:00 INFO - PROCESS | 20256 | [GFX1-]: Refresh driver waiting for the compositor for 1.03298 seconds. 07:53:11 INFO - PROCESS | 20256 | [GFX1-]: Refresh driver waiting for the compositor for 1.01699 seconds. 07:53:22 INFO - PROCESS | 20256 | [GFX1-]: Refresh driver waiting for the compositor for 1.00034 seconds. 07:53:24 INFO - PROCESS | 20256 | Cycle 1(1): loaded http://localhost:46022/tests/dromaeo/cssquery-dojo.html (next: http://localhost:46022/tests/dromaeo/cssquery-ext.html) 07:53:25 INFO - PROCESS | 20256 | RSS: Main: 235925504 07:53:25 INFO - PROCESS | 20256 | 07:53:28 INFO - PROCESS | 20256 | [GFX1-]: Refresh driver waiting for the compositor for 1.01647 seconds. 07:53:45 INFO - PROCESS | 20256 | [GFX1-]: Refresh driver waiting for the compositor for 1.0005 seconds. 07:53:49 INFO - PROCESS | 20256 | [GFX1-]: Refresh driver waiting for the compositor for 1.00063 seconds. 07:55:10 INFO - PROCESS | 20256 | [GFX1-]: Refresh driver waiting for the compositor for 1.00083 seconds. 07:55:29 INFO - PROCESS | 20256 | [GFX1-]: Refresh driver waiting for the compositor for 1.00057 seconds. 07:55:30 INFO - PROCESS | 20256 | [GFX1-]: Refresh driver waiting for the compositor for 1.00068 seconds. 07:55:32 INFO - PROCESS | 20256 | [GFX1-]: Refresh driver waiting for the compositor for 1.00026 seconds. 07:55:38 INFO - PROCESS | 20256 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 07:55:49 INFO - PROCESS | 20256 | [GFX1-]: Refresh driver waiting for the compositor for 1.01675 seconds. 07:55:52 INFO - PROCESS | 20256 | [GFX1-]: Refresh driver waiting for the compositor for 1.00045 seconds. 07:55:53 INFO - PROCESS | 20256 | [GFX1-]: Refresh driver waiting for the compositor for 1.01676 seconds. 07:56:03 INFO - PROCESS | 20256 | [GFX1-]: Refresh driver waiting for the compositor for 1.00078 seconds. 07:56:27 INFO - PROCESS | 20256 | [GFX1-]: Refresh driver waiting for the compositor for 1.01647 seconds. 07:56:27 INFO - PROCESS | 20256 | Cycle 1(1): loaded http://localhost:46022/tests/dromaeo/cssquery-ext.html (next: http://localhost:46022/tests/dromaeo/cssquery-jquery.html) 07:56:27 INFO - PROCESS | 20256 | RSS: Main: 230072320 07:56:27 INFO - PROCESS | 20256 | 07:56:31 INFO - PROCESS | 20256 | [GFX1-]: Refresh driver waiting for the compositor for 1.05017 seconds. 07:56:33 INFO - PROCESS | 20256 | [GFX1-]: Refresh driver waiting for the compositor for 1.03291 seconds. 07:56:44 INFO - PROCESS | 20256 | [GFX1-]: Refresh driver waiting for the compositor for 1.00041 seconds. 07:56:50 INFO - PROCESS | 20256 | [GFX1-]: Refresh driver waiting for the compositor for 1.0001 seconds. 07:57:04 INFO - PROCESS | 20256 | [GFX1-]: Refresh driver waiting for the compositor for 1.00017 seconds. 07:57:22 INFO - PROCESS | 20256 | [GFX1-]: Refresh driver waiting for the compositor for 1.01664 seconds. 07:57:34 INFO - PROCESS | 20256 | [GFX1-]: Refresh driver waiting for the compositor for 1.00048 seconds. 07:57:38 INFO - PROCESS | 20256 | [GFX1-]: Refresh driver waiting for the compositor for 1.01666 seconds. 07:57:59 INFO - PROCESS | 20256 | [GFX1-]: Refresh driver waiting for the compositor for 1.00017 seconds. 07:58:03 INFO - PROCESS | 20256 | [GFX1-]: Refresh driver waiting for the compositor for 1.00047 seconds. 07:58:29 INFO - PROCESS | 20256 | [GFX1-]: Refresh driver waiting for the compositor for 1.00016 seconds. 07:58:45 INFO - PROCESS | 20256 | [GFX1-]: Refresh driver waiting for the compositor for 1.00024 seconds. 07:58:53 INFO - PROCESS | 20256 | [GFX1-]: Refresh driver waiting for the compositor for 1.0001 seconds. 07:58:56 INFO - PROCESS | 20256 | [GFX1-]: Refresh driver waiting for the compositor for 1.00061 seconds. 07:59:29 INFO - PROCESS | 20256 | Cycle 1(1): loaded http://localhost:46022/tests/dromaeo/cssquery-jquery.html (next: http://localhost:46022/tests/dromaeo/cssquery-mootools.html) 07:59:30 INFO - PROCESS | 20256 | RSS: Main: 231165952 07:59:30 INFO - PROCESS | 20256 | 07:59:33 INFO - PROCESS | 20256 | [GFX1-]: Refresh driver waiting for the compositor for 1.0162 seconds. 07:59:50 INFO - PROCESS | 20256 | [GFX1-]: Refresh driver waiting for the compositor for 1.00036 seconds. 08:00:03 INFO - PROCESS | 20256 | [GFX1-]: Refresh driver waiting for the compositor for 1.00007 seconds. 08:00:16 INFO - PROCESS | 20256 | [GFX1-]: Refresh driver waiting for the compositor for 1.03294 seconds. 08:00:18 INFO - PROCESS | 20256 | [GFX1-]: Refresh driver waiting for the compositor for 2.01609 seconds. 08:00:18 INFO - PROCESS | 20256 | [GFX1-]: Refresh driver waiting for the compositor for 2.01609 seconds. 08:00:41 INFO - PROCESS | 20256 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 08:01:01 INFO - PROCESS | 20256 | [GFX1-]: Refresh driver waiting for the compositor for 1.00043 seconds. 08:01:03 INFO - PROCESS | 20256 | [GFX1-]: Refresh driver waiting for the compositor for 1.00043 seconds. 08:01:09 INFO - PROCESS | 20256 | [GFX1-]: Refresh driver waiting for the compositor for 1.00022 seconds. 08:02:08 INFO - PROCESS | 20256 | [GFX1-]: Refresh driver waiting for the compositor for 1.00076 seconds. 08:02:32 INFO - PROCESS | 20256 | Cycle 1(1): loaded http://localhost:46022/tests/dromaeo/cssquery-mootools.html (next: http://localhost:46022/tests/dromaeo/cssquery-prototype.html) 08:02:32 INFO - PROCESS | 20256 | RSS: Main: 175185920 08:02:32 INFO - PROCESS | 20256 | 08:02:36 INFO - PROCESS | 20256 | [GFX1-]: Refresh driver waiting for the compositor for 1.03317 seconds. 08:02:49 INFO - PROCESS | 20256 | [GFX1-]: Refresh driver waiting for the compositor for 1.01662 seconds. 08:02:57 INFO - PROCESS | 20256 | [GFX1-]: Refresh driver waiting for the compositor for 1.00051 seconds. 08:03:10 INFO - PROCESS | 20256 | [GFX1-]: Refresh driver waiting for the compositor for 1.0168 seconds. 08:03:21 INFO - PROCESS | 20256 | [GFX1-]: Refresh driver waiting for the compositor for 1.00058 seconds. 08:03:24 INFO - PROCESS | 20256 | [GFX1-]: Refresh driver waiting for the compositor for 1.05031 seconds. 08:03:44 INFO - PROCESS | 20256 | [GFX1-]: Refresh driver waiting for the compositor for 1.00041 seconds. 08:04:24 INFO - PROCESS | 20256 | [GFX1-]: Refresh driver waiting for the compositor for 1.00007 seconds. 08:04:49 INFO - PROCESS | 20256 | [GFX1-]: Refresh driver waiting for the compositor for 1.00038 seconds. 08:05:03 INFO - PROCESS | 20256 | [GFX1-]: Refresh driver waiting for the compositor for 1.00061 seconds. 08:05:21 INFO - PROCESS | 20256 | [GFX1-]: Refresh driver waiting for the compositor for 1.01689 seconds. 08:05:35 INFO - PROCESS | 20256 | Cycle 1(1): loaded http://localhost:46022/tests/dromaeo/cssquery-prototype.html (next: http://localhost:46022/tests/dromaeo/cssquery-yui.html) 08:05:36 INFO - PROCESS | 20256 | RSS: Main: 174796800 08:05:36 INFO - PROCESS | 20256 | 08:05:39 INFO - PROCESS | 20256 | [GFX1-]: Refresh driver waiting for the compositor for 1.01684 seconds. 08:05:41 INFO - PROCESS | 20256 | [GFX1-]: Refresh driver waiting for the compositor for 2.05006 seconds. 08:05:41 INFO - PROCESS | 20256 | [GFX1-]: Refresh driver waiting for the compositor for 2.05006 seconds. 08:05:44 INFO - PROCESS | 20256 | [GFX1-]: Refresh driver waiting for the compositor for 1.03301 seconds. 08:06:02 INFO - PROCESS | 20256 | [GFX1-]: Refresh driver waiting for the compositor for 1.00032 seconds. 08:06:45 INFO - PROCESS | 20256 | [GFX1-]: Refresh driver waiting for the compositor for 1.03283 seconds. 08:06:47 INFO - PROCESS | 20256 | [GFX1-]: Refresh driver waiting for the compositor for 1.01583 seconds. 08:07:13 INFO - PROCESS | 20256 | [GFX1-]: Refresh driver waiting for the compositor for 1.01663 seconds. 08:07:47 INFO - PROCESS | 20256 | [GFX1-]: Refresh driver waiting for the compositor for 1.01689 seconds. 08:08:10 INFO - PROCESS | 20256 | [GFX1-]: Refresh driver waiting for the compositor for 1.00079 seconds. 08:08:38 INFO - PROCESS | 20256 | Cycle 1(1): loaded http://localhost:46022/tests/dromaeo/cssquery-yui.html (next: http://localhost:46022/tests/dromaeo/cssquery-dojo.html) 08:08:38 INFO - PROCESS | 20256 | RSS: Main: 258252800 08:08:38 INFO - PROCESS | 20256 | 08:08:38 INFO - PROCESS | 20256 | __start_tp_report 08:08:38 INFO - PROCESS | 20256 | _x_x_mozilla_page_load 08:08:38 INFO - PROCESS | 20256 | _x_x_mozilla_page_load_details 08:08:38 INFO - PROCESS | 20256 | |i|pagename|runs| 08:08:38 INFO - PROCESS | 20256 | |0;dojo.html;3088.2896933705247;3132.4831402818536;3142.4657808172624;3161.509965955278;3176.3012137329792;5599.524040456561;5663.150527420889;5666.263385759856;5670.064439367499;5691.08942569189;5732.656040637565;5787.392323805992;5796.768129274824;5802.216700745398;5829.941700582993;5192.091384007803;5209.921851172236;5226.268322434862;5265.65773224741;5300.01949639316;5064.215046667772;5297.17893726473;5299.5230429261355;5307.73461326934;5344.331958505187;5037.143685573461;5079.136546787036;5122.3084883540805;5155.304033955405;5161.483851614846;5113.667611605252;5137.691738495665;5137.972405518911;5250.264962905197;5251.396089449724;5088.618353623456;5134.871628209325;5204.76578553966;5209.374875014984;5249.685018898878;5512.944870551323;5558.388577256539;5632.3804381518;5644.491995720364;5650.773969041234;6069.726862291208;6072.878542429126;6168.1056246843955;6169.629822210682;6173.32093469718;6164.075388691687;6173.228346456693;6190.473809726179;6190.969045154746;6193.411625895533;5489.58828087891;5541.33503979525;5660.405657405954;5687.914681279784;5704.172894930211;6306.936930630727;6308.526860485482;6311.526635502356;6317.4946004319545;6330.272018717815;6070.453659170696;6081.665508396992;6083.695815209222;6085.026395776655;6085.361037091042;5890.823275301748;5974.2830860297045;6004.159417681528;6068.33248342682;6171.814845554641;6025;6085.330613632497;6093.451589356979;6114.174586430864;6130.816075517741;6023.156758053876;6080.14877917092;6081.300650425258;6099.024156134997;6105.267367915879;4566.109608626286;4590.793414296365;4677.859664210079;4688.367070445446;4691.695039822401;4039.2325458162854;4077.857274995361;4084.0606660467665;4088.652464540488;4133.173365326887;5687.459691329317;5704.030314846486;5704.942950570524;5710.857228569319;5730.856728581819;5932;5968.1942937703725;5977.222961015127;5984.551158663117;5998.940010599838;5676.432356764373;5796.478316951495;5815.82552523425;5820.912686309709;5939.732712027969;5322.840314790563;5352.759125839347;5361.490658387447;5364.6512976656395;5366.7048312343195;6107.480864126599;6315.652639104894;6348.873022539616;6368.29948705642;6371.076193951851;5988.251468566429;6068.666223357581;6102.0541816018585;6113.141862489121;6168.845778855564;6279.089532017923;6303.49572034227;6306.905396419149;6307.842303942438;6311.96844015777;6085.148079268817;6091.847703807441;6095.299040610386;6099.725512352044;6101.725422356093;5917.85205369852;6047.516198704181;6057.242844644419;6073.149759033827;6185.876282474415;5959.0763431668165;5992.101184822137;5992.940070599413;5998.790042348497;6032.969835150796;5956.731947062306;5973.044312909914;5975.820725378072;5975.970120149372;5978.103284507185;5692.971535142297;5732.742026608729;5797.565182611237;5857.560682948711;5911.172435859069;5884.588078834612;6012.368701286302;6013.128096426081;6052.878942421215;6053.4551890330085;5839.708014599338;5841.18223448709;5842.795502157404;5848.766049357978;5862.530997520274 08:08:38 INFO - PROCESS | 20256 | |1;ext.html;590.4744777148338;606.7572970811675;623.8346838087905;639.3510586754442;643.1221382812461;35769.46345804815;36467;37480.06299842502;38023.04942376435;38148.61851381485;44524;45036.0992780145;45055.549444505545;45056.09887802242;45074.32388514175;9156.267498600113;9306.395084319498;9309.53452327381;9311.76720581984;9317.021712720169;8618.56907154643;8833.160849719266;10909.890901091007;11079.058280046205;11087.778244435107;11178;11268.098552115853;11277.32336059835;11277.436128193598;11279.097672186246;11012.889871101306;11070.169737269736;11101.7224569386;11104.500297486633;11158.553657853676;10928.617498387519;10980.450977451095;10996;10997.945010274978;11002.284851484628;43939.34090988638;44022.77988610036;44181;44302.33546496805;44339.77830110829;3557.2174121693447;3686.3733165361687;3796.9810150949343;3807.619238076198;3956.109875278068;32676.6732332676;32732.181695457566;32846.507302390484;32923.67076329254;33132.3373532528;4717.127331443694;4738;4801.2798080287885;4801.4958429365115;4886.657933944625;240297;240803;240895;242291;242720.78639606867;58197;58426;58507.70746146242;58521.70739146362;58528;39758.20483590312;40610.18779624391;40716;43052;62466;17076.658466830595;17196.31214751421;17220.225089870986;17224.138793060298;17228.741568876478;10896.78206435867;12788;12788.36058197105;12820.551280705131;12821.23072615646;17365.7395139073;19243;19636.509087272934;19890.90054549718;20622.27822026222;7258;7309.415246780245;7488.625568721651;7594.164641889388;7693.884591731128;1005.6228914157191;1039.4335087377397;1088.7060493666825;1098.6649072033106;1106.2686895009285;33284;33459;33504.832475837466;33581.16047098842;33614.49578256328;1096.5997410944947;1165.2542372881362;1166.1370585766642;1170.4849866058908;1190.4999900042003;15121.319540620698;15913.363465461483;17150.656986860195;17155.859610338495;17688.646227075387;608280;615427;615477;615513;615754;58200.417995820346;58720.70639646774;58738.70630646819;58740.70629646824;58901;1255.6484184428375;1319.399673148715;1363.5023216525897;1378.8207533020643;1408.6900881805987;30220;34153.48769768505;34418.48372274468;34458.82770586231;34556.65443345635;49259;49589;49807.750961244965;49966.50033499764;50032.249516258016;415579;416561;416661;416782;417074;29532.261693456974;30317.242068948453;30776.538351925188;32611.18472038218;32636.347273054882;48259;48598.27102593535;48777.53667389984;48937.75531122321;49412.75293623509;41208.1758364825;41250;41665.58334416617;42691.57308426876;42940.78529607332;42560.5743942569;42602.78698606487;42949.57050429581;43552;44097.11805763803;42108.368374473794;42843.57156428396;43101.78449107734;43133.352999705654;43201;35368.46947295741;37165;37425;37614.24771504609;38041;28436.43127137487;28649;29119.708802912548;29139.7086029137;29290.853545732134 08:08:39 INFO - PROCESS | 20256 | |2;jquery.html;392.7270546969857;429.429458473978;432.3601070415785;432.5695932547115;448.4394506866417;16425.671486570278;19415.902920485394;21038.474038149016;21218;21326.146954121836;17826.75212735109;18756.343527976525;19278.51803704908;19287.61424771504;19783.5054123647;5829.679367634779;6057.424544668272;6062;6062.060380641008;6068.757249710006;5687.800926967557;5918.437748413894;5919.023361145406;5953.910691339634;5963.194968679216;5749.453801888814;5945.227120474332;5948.643081415107;5952.4047595240345;5953.077273022688;5848.561357898154;5960.523158147338;5963.224780778514;5978.760849566012;5992.970035149818;5343.812966546152;5363.678179309254;5386.3536375634785;5391.946080539184;5392.568594512469;20115.497112572157;20193.697094543593;20382.898085509627;20488.18047278107;20535.794642053686;2582.2769624505167;2623.4753049390197;2673.7459941305738;2876.4966130927005;2945.6023436835967;15300.08199508033;15332.15673137977;15511.922440387725;15512.379504819794;15557.455489057942;1954.1890115602007;1974.3780709076595;2020.8282296004857;2049.7437820272467;2081;84363.99999999939;85968;86054;86562.56718716428;88321.55839220763;9990.450525221037;11075.612353567587;11228.775424491465;11370.772584548262;11431.657050288504;14628.853711462962;15470.381184752709;15473.071615703095;15542.300596473186;15568.299426525833;7856.803579910434;7966.760997170094;8213.137620549876;8260.8347833043;8280.171982801672;7544.547327160388;7625.809354766176;7654.96172519145;7768.378529717609;7866.960665196638;6842.726290948406;6918;6962.6422847545255;7007.229204787469;7114.750983715545;2059.5160137367707;2076.1591555420077;2080.2927004818434;2081.906314215864;2089.0703636881744;943.3113826906259;1025.9127974122284;1036.8030074285862;1091.7052395853234;1121.3496172220093;20502.17991280332;20689.58620827575;20762.48093797667;21114.78885211159;21149.27233275569;1028.2442404832443;1035.295998720877;1047.879493858201;1053.3627155570923;1109.2290857853714;9633.32566720323;11911.225770324902;11975.341356225317;11998.940005299917;12012.339321337411;487825;493693;497724;502430;506618;87830;90257.54871225602;90965;90994.54502727443;91014;1016.044917777287;1029.9742506437399;1054.488573042074;1055.9894401056044;1060.4379678770265;16789;18520.12937994613;18643.813561864208;18701;18814.24743010264;42911.356329655704;43025.56974430341;43029.56970430381;43263.13473730571;44519.33221001752;56293;57714.42285577091;57724.422755771906;58415.4158458427;58918;12299.69250768738;12380.690482738004;12560.623181304576;13080.672983175496;13242.668933276746;13558.457661693421;14324.427022919383;14696.265186740835;14725.779113313094;15232.847671523143;12916.418761155583;12979.675508111995;13061.151025183333;13140.34298285101;13504.919606431658;12487.750244995232;12606.306653133799;12648.936755316165;12862.292573908151;13131.080824342205;12917.186932578497;12995.415206315549;12995.935020324838;13042.934785326392;13066.346682666019;13140;13146.868531314565;13152.276624785729;13338.13302135358;13409;12634.305113218494;12867.678308042;13006.414711337824;13136.737265254833;13190 08:08:39 INFO - PROCESS | 20256 | |3;mootools.html;1417.3763544040628;1518.6962607478504;1558.2909776051895;1698.7027270227707;1846.4552956877712;8315.542645154514;9077.954610226949;9406.90593094069;9478.431294122358;9498.382605130679;9230.538473076353;9324.813503729938;9337.206337461324;9341.532923353821;9359.48522831244;2610.4257063446057;2633.749793769589;2644.034927251549;2644.2728249731285;2651.2841532786138;2558.60341647045;2621.6198651195564;2627.829191102572;2632.0787724296533;2641.0492222799776;2588.8317259378173;2591.520568694788;2622.7770639495666;2626.973730262702;2629.7238789926973;2551.6045013023013;2578.252306831026;2578.871056447189;2594.1179998800403;2595.0917178987393;2532.8100392470637;2542.427953710419;2547.681539807524;2581.38046868752;2588.6634737484196;9005.234555062827;9174;9528.094830991045;9617.663381781675;9639.132478077006;5710.714464276811;5937.495312898409;5940.2871655401195;5944.078667806497;5949.762009519614;3688.5942546319884;3713.405855063204;3729.664330210267;3751.718621103401;3784.4890939723336;1257.6227131860496;1266.5693664154232;1269.4033804112053;1299.0906365544156;1313.2973858985492;13301.201927884358;13410;13445;13467.730645387135;13467.932660336734;4905.803767849317;5099.77051032697;5101.617378696613;5118.10433174193;5230.92153617696;10490.265681402381;10535.051845333957;10544.52549635253;10546.314489558154;10554.47227638615;5627.8593035174445;5658.519025882763;5664.915026274609;5670.092785154356;5677.120046392815;4545.2954792007295;4775.2598347256235;4856.805727770849;4865.854024379274;5310.946890531122;4195.328747400401;4235.322348424237;4354.738715677069;4359.978200108979;4372.475302963665;1247.328579703184;1263.8041103628952;1276.1959965221852;1277.1506947879557;1282.7498637765545;842.3135144856922;948.7675519497767;1013.2552573858355;1015.1016350529686;1054.146141625286;5969.194158788508;6056.121862329937;6343.1436756038265;6374;6376.394242547043;1014.06705830636;1035.5650626736638;1055.1400608504046;1057.360297020307;1061.5753698520653;4130.586941305845;4269.338252570857;4305.97847010763;4354.542773008852;4465.866024019285;11585.131115166396;12037.819432708517;12051.939740301243;12060.879391206152;12072.128820032909;11929.164958452999;11936.701582460508;11941.582044628396;11944.16390852632;11984.52061917531;8366.83266334682;8636.395452318277;8655.6970506032;8828.117188281249;8853.601587928559;6210.596311239755;6316.778912738032;6369.14016607752;6583.96708016476;6714.328567143247;11007.569016027865;11107.44462776874;11167.218294719292;11264.042556382472;11274.718132046764;10101.343412678152;10477.52851121686;10983;11237.157213208879;11352.60265890723;8165.918340816515;8563.058063613122;8566.700165494178;8616.052234254352;8628.956855215683;9189.908100919172;9206.677766278415;9262.16640502358;9262.907370926474;9264.258859291105;8184.2224988625985;8377;8461.153884611736;8468.11084836108;8608.91391086081;8117.959410202911;8143.918560814316;8212.425130240827;8298.336133109216;8347.332213422791;7610.12483564375;7638.847223055619;7724.691012359667;7725.845483090419;7754.418418618513;5301.893962120659;5369.27514785499;5427.457254274541;5428.782848686008;5477.616566840283;5356.464353564613;5562.276904002454;5629.155626656035;5653.773849045992;5743.798967036134 08:08:39 INFO - PROCESS | 20256 | |4;prototype.html;358.2226568346687;361.8516408272608;362.07851019156243;362.80952499937547;365.648976982097;5523.309586301712;5524.419935906727;5530.170474428838;5535.446455354468;5583.190437386576;5484.341878974518;5485.6982865942355;5507.284053073095;5508.283923090003;5511.200875872996;4093.8157782899693;4288.485381754193;4332.761698106603;4341.782910854445;4342.630876375511;4307.978460107695;4310.719803212797;4310.762908040057;4327.459067616548;4336.436263285768;4293.8067786949505;4305.729809706144;4319.632831209351;4326.286162783153;4333.198358303724;4171.311733563958;4260.38224457455;4296.656267498593;4304.612584867377;4337.1976184405985;3793;3796.9810150949343;3803.7527560708463;3810.8285127169343;3814.2180852925085;5333.893322133575;5346.358436987547;5398.973005134949;5404.243405923174;5416.431274716177;4764.190087685067;4844.522420661697;4881.875599616248;4936.111499930011;4944.9258261126115;5634.859128521779;5666.490015898589;5684.516816070598;5688;5701.543876489871;4185.4977402711565;4195;4195.992961689203;4208.569086510571;4212.473440819897;5628.874225154986;5880.647161170301;5919.437653422961;5989.790357337472;6019.277686677583;4235.152969406131;4254.851080212177;4259.382389553497;4264.424302719093;4279.272523670921;4331;4345.891352716208;4361.716488428242;4363.410939523187;4365.345198220292;4827.275908613666;4874;4898.21628539432;4974.104661160954;4978.253262010728;5704.857378565569;5739.684317362502;5742.253507044057;5746.454086861741;5747.48272655463;1480.4744315767962;1502.7295086884467;1506.246876561719;1506.7739839024234;1506.8322050411018;921.1214953271028;962.5908988679784;964.9723044957099;968.8837339519162;985.3989066669326;4919.975400122977;4922.532359425849;4937.432195297515;4941.925871111936;4953.356063711766;5157.1176520569725;5184.844454666366;5187.714675692798;5240.895182096337;5284.207368894619;4656.020147105855;4912.606991440676;4978.32792572998;4992.327570263929;5024.698518088926;2718.497078040569;2741.218208164709;2742.766864816473;2747.6702795664646;2758.3655759175504;5286;5305.814296499681;5306.946930530722;5310.840674779763;5315.867103322448;5023.271625614266;5180.844574662766;5259.684418934876;5278.4721527846905;5712.885742285131;5838;5842.474177324061;5969.283685957713;5986.161937328778;5998.190244317048;5798.59409841307;5852.3269823969085;5854.531637469077;5856.824295271149;5867.735951882261;5584.944150558443;5763.884722305615;5764.636499035375;5780.826575202751;5795.623284486495;5933.626549380282;5947.762089516371;5956.868195042928;5962.25471816023;5969.522438204846;4251.277282861859;4251.532331443601;4265.552117027792;4281.850135245251;4299.376590394313;5577.247071645276;5855.3559108498885;5872.119182122716;5883.646981181143;5908.05471124618;5782.826515204551;5792.189093526994;5796.739146738491;5829.921464529076;5848.795292164754;5588.944110558842;5730.598858080061;5817.738201780845;5823.301203855395;5823.970880145572;5725.570582206435;5742.971285143548;5744.339400968942;5745.1956726059225;5751.194832723339;5779.306483222041;5822.7670893163795;5829.15477255804;5832.396091074988;5858.47273745365;5368.248445217595;5621.5221706155435;5654.208410822571;5668.8095499945475;5669.64709645509 08:08:39 INFO - PROCESS | 20256 | |5;yui.html;782.6634547144729;795.3994733975849;819.863323027142;831.1646794971049;933.7292185266273;15897.920510397445;16987.320507179713;17612.64774704505;17772;17776.733348999776;16294.83705162948;16450.177491125454;16482.17589120545;16484.75272870905;16872.66254674906;734.1373885684326;751.7481643649384;853.2491407561369;871.7776966873438;877.666486735043;880.2254016465502;889.5418859287472;889.5818965086399;890.692711014699;891.3582220801045;939.7509659940122;943.504660053474;943.9480828554426;944.2681921510815;944.2870632672323;764.2739397572328;774.3766268154134;896.959636816348;918.9448633082036;921.9124183202586;678.0575000748963;678.2911857109333;678.3555622158968;678.5080816408114;679.1476696745602;16538.669226615522;16547.255373508222;16578;16616.169191540495;16623.750643740354;14553.272336383245;14663;14777.113373197642;14845.777313340308;15177.317020734094;12909.289989050598;13340.466381344735;13363.799543006862;13459.528916487974;13877.722445551033;4682.882927926795;4793.281007848851;4838.032393521275;4867;4909.631777616693;60339.09491357633;86179;87211.69182462331;87674.12325876787;89359;11966.58116965902;12105.87894121065;12371.938140309241;12648.936755316348;12680.429380677891;24676.876615617166;25025.749742502707;25195.87402062978;25272.115475958253;25349.36626584313;9684.806303873884;9799.706008819747;9887.555060022316;10138.847917281246;10213.693589192337;5023.824166154167;5242.213667949838;5252.422233554306;5268.473152684701;5270.8155214567305;4064.7764372959773;4197.097624010852;4207.957920420818;4210.200061988213;4215.367694845798;638.7476946606157;719.6329871765437;721.6463932673057;731.3125661877924;732.1763016606318;619.8853212155766;730.5762657658545;745.4371949178336;783.5220515485549;801.078759426664;10652.786944261072;11154.10767138627;11304.830427543593;11511.19421640477;12015;669.0466085827676;749.2282948562989;752.6349720385608;765.7664412354285;842.7471758472434;5478.534324582455;5754.74103665336;5796.21751063601;6068.4538391544975;6080.118382834553;77622.6118869402;97523.51238243764;125873;132722;135874.3206283982;109561.45219273852;111234.44382778034;112101;114407.42796285966;116612;97417.5129124364;98237.50881245692;98862;99008;99290.50354748324;10254.333468324536;10835.945820270848;10875.347479151276;11245.493952771982;11312.20814542974;50180.74909625429;50489.24266136084;52658.736706316224;52995;53916;73840.63079684567;74364.62817685877;78992.21007790079;80251;83817;12962.157459765462;13394.196348219139;13586.93206533961;13715.314234288046;14129.858701412853;15815.13016784088;15965.361385544913;16503.587410314838;16934.237959291615;16961.151942403078;15326.950418071043;15719.764203536728;16048.518544443685;16510;16573.922695024783;15483.458078967182;15765.921170394075;16404.179791010163;17174.055426951138;17200.225989830236;13925.442982280596;14668.70662586721;14877.47928822486;15894.8410515898;15941.282642280894;10579.995513571508;11100.722481937693;11467.713307167387;11506.712332191428;11735.178537502294;11863.762724745284;11938.58214962472;12210.511579536718;12227.816582751087;12257.632271031884 08:08:39 INFO - PROCESS | 20256 | __end_tp_report 08:08:39 INFO - PROCESS | 20256 | __start_cc_report 08:08:39 INFO - PROCESS | 20256 | _x_x_mozilla_cycle_collect,830 08:08:39 INFO - PROCESS | 20256 | __end_cc_report 08:08:39 INFO - PROCESS | 20256 | __startTimestamp1474556918991__endTimestamp 08:08:39 INFO - PROCESS | 20256 | 08:08:39 INFO - PROCESS | 20256 | ------- Summary: start ------- 08:08:39 INFO - PROCESS | 20256 | Number of tests: 6 08:08:39 INFO - PROCESS | 20256 | 08:08:39 INFO - PROCESS | 20256 | [#0] dojo.html Cycles:165 Average:5657.62 Median:5862.53 stddev:667.70 (11.4%) stddev-sans-first:638.60 08:08:39 INFO - PROCESS | 20256 | Values: 3088.3 3132.5 3142.5 3161.5 3176.3 5599.5 5663.2 5666.3 5670.1 5691.1 5732.7 5787.4 5796.8 5802.2 5829.9 5192.1 5209.9 5226.3 5265.7 5300.0 5064.2 5297.2 5299.5 5307.7 5344.3 5037.1 5079.1 5122.3 5155.3 5161.5 5113.7 5137.7 5138.0 5250.3 5251.4 5088.6 5134.9 5204.8 5209.4 5249.7 5512.9 5558.4 5632.4 5644.5 5650.8 6069.7 6072.9 6168.1 6169.6 6173.3 6164.1 6173.2 6190.5 6191.0 6193.4 5489.6 5541.3 5660.4 5687.9 5704.2 6306.9 6308.5 6311.5 6317.5 6330.3 6070.5 6081.7 6083.7 6085.0 6085.4 5890.8 5974.3 6004.2 6068.3 6171.8 6025.0 6085.3 6093.5 6114.2 6130.8 6023.2 6080.1 6081.3 6099.0 6105.3 4566.1 4590.8 4677.9 4688.4 4691.7 4039.2 4077.9 4084.1 4088.7 4133.2 5687.5 5704.0 5704.9 5710.9 5730.9 5932.0 5968.2 5977.2 5984.6 5998.9 5676.4 5796.5 5815.8 5820.9 5939.7 5322.8 5352.8 5361.5 5364.7 5366.7 6107.5 6315.7 6348.9 6368.3 6371.1 5988.3 6068.7 6102.1 6113.1 6168.8 6279.1 6303.5 6306.9 6307.8 6312.0 6085.1 6091.8 6095.3 6099.7 6101.7 5917.9 6047.5 6057.2 6073.1 6185.9 5959.1 5992.1 5992.9 5998.8 6033.0 5956.7 5973.0 5975.8 5976.0 5978.1 5693.0 5732.7 5797.6 5857.6 5911.2 5884.6 6012.4 6013.1 6052.9 6053.5 5839.7 5841.2 5842.8 5848.8 5862.5 08:08:39 INFO - PROCESS | 20256 | 08:08:39 INFO - PROCESS | 20256 | [#1] ext.html Cycles:180 Average:58968.40 Median:37547.16 stddev:120130.47 (319.9%) stddev-sans-first:120387.50 08:08:39 INFO - PROCESS | 20256 | Values: 590.5 606.8 623.8 639.4 643.1 35769.5 36467.0 37480.1 38023.0 38148.6 44524.0 45036.1 45055.5 45056.1 45074.3 9156.3 9306.4 9309.5 9311.8 9317.0 8618.6 8833.2 10909.9 11079.1 11087.8 11178.0 11268.1 11277.3 11277.4 11279.1 11012.9 11070.2 11101.7 11104.5 11158.6 10928.6 10980.5 10996.0 10997.9 11002.3 43939.3 44022.8 44181.0 44302.3 44339.8 3557.2 3686.4 3797.0 3807.6 3956.1 32676.7 32732.2 32846.5 32923.7 33132.3 4717.1 4738.0 4801.3 4801.5 4886.7 240297.0 240803.0 240895.0 242291.0 242720.8 58197.0 58426.0 58507.7 58521.7 58528.0 39758.2 40610.2 40716.0 43052.0 62466.0 17076.7 17196.3 17220.2 17224.1 17228.7 10896.8 12788.0 12788.4 12820.6 12821.2 17365.7 19243.0 19636.5 19890.9 20622.3 7258.0 7309.4 7488.6 7594.2 7693.9 1005.6 1039.4 1088.7 1098.7 1106.3 33284.0 33459.0 33504.8 33581.2 33614.5 1096.6 1165.3 1166.1 1170.5 1190.5 15121.3 15913.4 17150.7 17155.9 17688.6 608280.0 615427.0 615477.0 615513.0 615754.0 58200.4 58720.7 58738.7 58740.7 58901.0 1255.6 1319.4 1363.5 1378.8 1408.7 30220.0 34153.5 34418.5 34458.8 34556.7 49259.0 49589.0 49807.8 49966.5 50032.2 415579.0 416561.0 416661.0 416782.0 417074.0 29532.3 30317.2 30776.5 32611.2 32636.3 48259.0 48598.3 48777.5 48937.8 49412.8 41208.2 41250.0 41665.6 42691.6 42940.8 42560.6 42602.8 42949.6 43552.0 44097.1 42108.4 42843.6 43101.8 43133.4 43201.0 35368.5 37165.0 37425.0 37614.2 38041.0 28436.4 28649.0 29119.7 29139.7 29290.9 08:08:39 INFO - PROCESS | 20256 | 08:08:39 INFO - PROCESS | 20256 | [#2] jquery.html Cycles:180 Average:30043.29 Median:11382.87 stddev:81923.80 (719.7%) stddev-sans-first:82123.36 08:08:39 INFO - PROCESS | 20256 | Values: 392.7 429.4 432.4 432.6 448.4 16425.7 19415.9 21038.5 21218.0 21326.1 17826.8 18756.3 19278.5 19287.6 19783.5 5829.7 6057.4 6062.0 6062.1 6068.8 5687.8 5918.4 5919.0 5953.9 5963.2 5749.5 5945.2 5948.6 5952.4 5953.1 5848.6 5960.5 5963.2 5978.8 5993.0 5343.8 5363.7 5386.4 5391.9 5392.6 20115.5 20193.7 20382.9 20488.2 20535.8 2582.3 2623.5 2673.7 2876.5 2945.6 15300.1 15332.2 15511.9 15512.4 15557.5 1954.2 1974.4 2020.8 2049.7 2081.0 84364.0 85968.0 86054.0 86562.6 88321.6 9990.5 11075.6 11228.8 11370.8 11431.7 14628.9 15470.4 15473.1 15542.3 15568.3 7856.8 7966.8 8213.1 8260.8 8280.2 7544.5 7625.8 7655.0 7768.4 7867.0 6842.7 6918.0 6962.6 7007.2 7114.8 2059.5 2076.2 2080.3 2081.9 2089.1 943.3 1025.9 1036.8 1091.7 1121.3 20502.2 20689.6 20762.5 21114.8 21149.3 1028.2 1035.3 1047.9 1053.4 1109.2 9633.3 11911.2 11975.3 11998.9 12012.3 487825.0 493693.0 497724.0 502430.0 506618.0 87830.0 90257.5 90965.0 90994.5 91014.0 1016.0 1030.0 1054.5 1056.0 1060.4 16789.0 18520.1 18643.8 18701.0 18814.2 42911.4 43025.6 43029.6 43263.1 44519.3 56293.0 57714.4 57724.4 58415.4 58918.0 12299.7 12380.7 12560.6 13080.7 13242.7 13558.5 14324.4 14696.3 14725.8 15232.8 12916.4 12979.7 13061.2 13140.3 13504.9 12487.8 12606.3 12648.9 12862.3 13131.1 12917.2 12995.4 12995.9 13042.9 13066.3 13140.0 13146.9 13152.3 13338.1 13409.0 12634.3 12867.7 13006.4 13136.7 13190.0 08:08:39 INFO - PROCESS | 20256 | 08:08:39 INFO - PROCESS | 20256 | [#3] mootools.html Cycles:180 Average:6254.60 Median:4366.23 stddev:3547.20 (81.2%) stddev-sans-first:3538.52 08:08:39 INFO - PROCESS | 20256 | Values: 1417.4 1518.7 1558.3 1698.7 1846.5 8315.5 9078.0 9406.9 9478.4 9498.4 9230.5 9324.8 9337.2 9341.5 9359.5 2610.4 2633.7 2644.0 2644.3 2651.3 2558.6 2621.6 2627.8 2632.1 2641.0 2588.8 2591.5 2622.8 2627.0 2629.7 2551.6 2578.3 2578.9 2594.1 2595.1 2532.8 2542.4 2547.7 2581.4 2588.7 9005.2 9174.0 9528.1 9617.7 9639.1 5710.7 5937.5 5940.3 5944.1 5949.8 3688.6 3713.4 3729.7 3751.7 3784.5 1257.6 1266.6 1269.4 1299.1 1313.3 13301.2 13410.0 13445.0 13467.7 13467.9 4905.8 5099.8 5101.6 5118.1 5230.9 10490.3 10535.1 10544.5 10546.3 10554.5 5627.9 5658.5 5664.9 5670.1 5677.1 4545.3 4775.3 4856.8 4865.9 5310.9 4195.3 4235.3 4354.7 4360.0 4372.5 1247.3 1263.8 1276.2 1277.2 1282.7 842.3 948.8 1013.3 1015.1 1054.1 5969.2 6056.1 6343.1 6374.0 6376.4 1014.1 1035.6 1055.1 1057.4 1061.6 4130.6 4269.3 4306.0 4354.5 4465.9 11585.1 12037.8 12051.9 12060.9 12072.1 11929.2 11936.7 11941.6 11944.2 11984.5 8366.8 8636.4 8655.7 8828.1 8853.6 6210.6 6316.8 6369.1 6584.0 6714.3 11007.6 11107.4 11167.2 11264.0 11274.7 10101.3 10477.5 10983.0 11237.2 11352.6 8165.9 8563.1 8566.7 8616.1 8629.0 9189.9 9206.7 9262.2 9262.9 9264.3 8184.2 8377.0 8461.2 8468.1 8608.9 8118.0 8143.9 8212.4 8298.3 8347.3 7610.1 7638.8 7724.7 7725.8 7754.4 5301.9 5369.3 5427.5 5428.8 5477.6 5356.5 5562.3 5629.2 5653.8 5743.8 08:08:39 INFO - PROCESS | 20256 | 08:08:39 INFO - PROCESS | 20256 | [#4] prototype.html Cycles:180 Average:4744.71 Median:5306.38 stddev:1376.04 (25.9%) stddev-sans-first:1339.93 08:08:39 INFO - PROCESS | 20256 | Values: 358.2 361.9 362.1 362.8 365.6 5523.3 5524.4 5530.2 5535.4 5583.2 5484.3 5485.7 5507.3 5508.3 5511.2 4093.8 4288.5 4332.8 4341.8 4342.6 4308.0 4310.7 4310.8 4327.5 4336.4 4293.8 4305.7 4319.6 4326.3 4333.2 4171.3 4260.4 4296.7 4304.6 4337.2 3793.0 3797.0 3803.8 3810.8 3814.2 5333.9 5346.4 5399.0 5404.2 5416.4 4764.2 4844.5 4881.9 4936.1 4944.9 5634.9 5666.5 5684.5 5688.0 5701.5 4185.5 4195.0 4196.0 4208.6 4212.5 5628.9 5880.6 5919.4 5989.8 6019.3 4235.2 4254.9 4259.4 4264.4 4279.3 4331.0 4345.9 4361.7 4363.4 4365.3 4827.3 4874.0 4898.2 4974.1 4978.3 5704.9 5739.7 5742.3 5746.5 5747.5 1480.5 1502.7 1506.2 1506.8 1506.8 921.1 962.6 965.0 968.9 985.4 4920.0 4922.5 4937.4 4941.9 4953.4 5157.1 5184.8 5187.7 5240.9 5284.2 4656.0 4912.6 4978.3 4992.3 5024.7 2718.5 2741.2 2742.8 2747.7 2758.4 5286.0 5305.8 5306.9 5310.8 5315.9 5023.3 5180.8 5259.7 5278.5 5712.9 5838.0 5842.5 5969.3 5986.2 5998.2 5798.6 5852.3 5854.5 5856.8 5867.7 5584.9 5763.9 5764.6 5780.8 5795.6 5933.6 5947.8 5956.9 5962.3 5969.5 4251.3 4251.5 4265.6 4281.9 4299.4 5577.2 5855.4 5872.1 5883.6 5908.1 5782.8 5792.2 5796.7 5829.9 5848.8 5588.9 5730.6 5817.7 5823.3 5824.0 5725.6 5743.0 5744.3 5745.2 5751.2 5779.3 5822.8 5829.2 5832.4 5858.5 5368.2 5621.5 5654.2 5668.8 5669.6 08:08:39 INFO - PROCESS | 20256 | 08:08:39 INFO - PROCESS | 20256 | [#5] yui.html Cycles:180 Average:22610.95 Median:4130.94 stddev:32238.72 (780.4%) stddev-sans-first:32287.49 08:08:39 INFO - PROCESS | 20256 | Values: 782.7 795.4 819.9 831.2 933.7 15897.9 16987.3 17612.6 17772.0 17776.7 16294.8 16450.2 16482.2 16484.8 16872.7 734.1 751.7 853.2 871.8 877.7 880.2 889.5 889.6 890.7 891.4 939.8 943.5 943.9 944.3 944.3 764.3 774.4 897.0 918.9 921.9 678.1 678.3 678.4 678.5 679.1 16538.7 16547.3 16578.0 16616.2 16623.8 14553.3 14663.0 14777.1 14845.8 15177.3 12909.3 13340.5 13363.8 13459.5 13877.7 4682.9 4793.3 4838.0 4867.0 4909.6 60339.1 86179.0 87211.7 87674.1 89359.0 11966.6 12105.9 12371.9 12648.9 12680.4 24676.9 25025.7 25195.9 25272.1 25349.4 9684.8 9799.7 9887.6 10138.8 10213.7 5023.8 5242.2 5252.4 5268.5 5270.8 4064.8 4197.1 4208.0 4210.2 4215.4 638.7 719.6 721.6 731.3 732.2 619.9 730.6 745.4 783.5 801.1 10652.8 11154.1 11304.8 11511.2 12015.0 669.0 749.2 752.6 765.8 842.7 5478.5 5754.7 5796.2 6068.5 6080.1 77622.6 97523.5 125873.0 132722.0 135874.3 109561.5 111234.4 112101.0 114407.4 116612.0 97417.5 98237.5 98862.0 99008.0 99290.5 10254.3 10835.9 10875.3 11245.5 11312.2 50180.7 50489.2 52658.7 52995.0 53916.0 73840.6 74364.6 78992.2 80251.0 83817.0 12962.2 13394.2 13586.9 13715.3 14129.9 15815.1 15965.4 16503.6 16934.2 16961.2 15327.0 15719.8 16048.5 16510.0 16573.9 15483.5 15765.9 16404.2 17174.1 17200.2 13925.4 14668.7 14877.5 15894.8 15941.3 10580.0 11100.7 11467.7 11506.7 11735.2 11863.8 11938.6 12210.5 12227.8 12257.6 08:08:39 INFO - PROCESS | 20256 | -------- Summary: end -------- 08:08:39 INFO - PROCESS | 20256 | 08:08:39 INFO - TEST-INFO | 20256: exit 0 08:08:39 INFO - TEST-OK | dromaeo_css | took 1113745ms 08:08:39 INFO - TEST-START | kraken 08:08:39 INFO - operating with platform_type : linux_ 08:08:39 INFO - Initialising browser for kraken test... 08:08:39 INFO - TEST-INFO | started process 20577 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp8LoepR/profile http://localhost:46022/getInfo.html) 08:08:44 INFO - PROCESS | 20577 | __metrics Screen width/height:1600/1200 08:08:44 INFO - PROCESS | 20577 | colorDepth:24 08:08:44 INFO - PROCESS | 20577 | Browser inner width/height: 1024/697 08:08:44 INFO - PROCESS | 20577 | __metrics 08:08:44 INFO - PROCESS | 20577 | 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" 08:08:44 INFO - PROCESS | 20577 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:08:45 INFO - PROCESS | 20577 | console.error: PushService: 08:08:45 INFO - PROCESS | 20577 | stateChangeProcessEnqueue: Error transitioning state 08:08:45 INFO - PROCESS | 20577 | UnknownError 08:08:45 INFO - TEST-INFO | 20577: exit 0 08:08:45 INFO - Browser initialized. 08:08:45 INFO - Running cycle 1/1 for kraken test... 08:08:45 INFO - TEST-INFO | started process 20669 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp8LoepR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:08:57 INFO - PROCESS | 20669 | RSS: Main: 144867328 08:08:57 INFO - PROCESS | 20669 | 08:09:00 INFO - PROCESS | 20669 | Cycle 1(1): loaded http://localhost:46022/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:46022/tests/kraken/driver.html?testName=audio-beat-detection) 08:09:01 INFO - PROCESS | 20669 | RSS: Main: 185974784 08:09:01 INFO - PROCESS | 20669 | 08:09:03 INFO - PROCESS | 20669 | Cycle 1(1): loaded http://localhost:46022/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:46022/tests/kraken/driver.html?testName=audio-dft) 08:09:04 INFO - PROCESS | 20669 | RSS: Main: 175906816 08:09:04 INFO - PROCESS | 20669 | 08:09:07 INFO - PROCESS | 20669 | Cycle 1(1): loaded http://localhost:46022/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:46022/tests/kraken/driver.html?testName=audio-fft) 08:09:07 INFO - PROCESS | 20669 | RSS: Main: 266887168 08:09:07 INFO - PROCESS | 20669 | 08:09:09 INFO - PROCESS | 20669 | Cycle 1(1): loaded http://localhost:46022/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:46022/tests/kraken/driver.html?testName=audio-oscillator) 08:09:10 INFO - PROCESS | 20669 | RSS: Main: 172687360 08:09:10 INFO - PROCESS | 20669 | 08:09:12 INFO - PROCESS | 20669 | Cycle 1(1): loaded http://localhost:46022/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:46022/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:09:12 INFO - PROCESS | 20669 | RSS: Main: 174252032 08:09:12 INFO - PROCESS | 20669 | 08:09:15 INFO - PROCESS | 20669 | Cycle 1(1): loaded http://localhost:46022/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:46022/tests/kraken/driver.html?testName=imaging-darkroom) 08:09:16 INFO - PROCESS | 20669 | RSS: Main: 209793024 08:09:16 INFO - PROCESS | 20669 | 08:09:19 INFO - PROCESS | 20669 | Cycle 1(1): loaded http://localhost:46022/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:46022/tests/kraken/driver.html?testName=imaging-desaturate) 08:09:20 INFO - PROCESS | 20669 | RSS: Main: 209494016 08:09:20 INFO - PROCESS | 20669 | 08:09:23 INFO - PROCESS | 20669 | Cycle 1(1): loaded http://localhost:46022/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:46022/tests/kraken/driver.html?testName=json-parse-financial) 08:09:23 INFO - PROCESS | 20669 | RSS: Main: 209235968 08:09:23 INFO - PROCESS | 20669 | 08:09:25 INFO - PROCESS | 20669 | Cycle 1(1): loaded http://localhost:46022/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:46022/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:09:25 INFO - PROCESS | 20669 | RSS: Main: 171118592 08:09:25 INFO - PROCESS | 20669 | 08:09:27 INFO - PROCESS | 20669 | Cycle 1(1): loaded http://localhost:46022/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:46022/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:09:28 INFO - PROCESS | 20669 | RSS: Main: 177860608 08:09:28 INFO - PROCESS | 20669 | 08:09:30 INFO - PROCESS | 20669 | Cycle 1(1): loaded http://localhost:46022/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:46022/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:09:30 INFO - PROCESS | 20669 | RSS: Main: 183611392 08:09:30 INFO - PROCESS | 20669 | 08:09:33 INFO - PROCESS | 20669 | Cycle 1(1): loaded http://localhost:46022/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:46022/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:09:33 INFO - PROCESS | 20669 | RSS: Main: 184393728 08:09:33 INFO - PROCESS | 20669 | 08:09:36 INFO - PROCESS | 20669 | Cycle 1(1): loaded http://localhost:46022/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:46022/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:09:37 INFO - PROCESS | 20669 | RSS: Main: 182685696 08:09:37 INFO - PROCESS | 20669 | 08:09:38 INFO - PROCESS | 20669 | Cycle 1(1): loaded http://localhost:46022/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:46022/tests/kraken/driver.html?testName=ai-astar) 08:09:39 INFO - PROCESS | 20669 | RSS: Main: 233566208 08:09:39 INFO - PROCESS | 20669 | 08:09:39 INFO - PROCESS | 20669 | __start_tp_report 08:09:39 INFO - PROCESS | 20669 | _x_x_mozilla_page_load 08:09:39 INFO - PROCESS | 20669 | _x_x_mozilla_page_load_details 08:09:39 INFO - PROCESS | 20669 | |i|pagename|runs| 08:09:39 INFO - PROCESS | 20669 | |0;ai-astar;108;111;111;108;111;110;111;110;111;109 08:09:39 INFO - PROCESS | 20669 | |1;audio-beat-detection;141;115;117;158;117;118;117;115;151;118 08:09:39 INFO - PROCESS | 20669 | |2;audio-dft;159;160;161;161;164;162;162;162;162;161 08:09:39 INFO - PROCESS | 20669 | |3;audio-fft;75;75;76;112;75;74;75;74;102;76 08:09:39 INFO - PROCESS | 20669 | |4;audio-oscillator;82;82;115;84;82;82;105;82;82;106 08:09:39 INFO - PROCESS | 20669 | |5;imaging-gaussian-blur;117;117;117;117;116;117;116;116;117;117 08:09:39 INFO - PROCESS | 20669 | |6;imaging-darkroom;146;145;145;146;146;145;145;146;145;146 08:09:39 INFO - PROCESS | 20669 | |7;imaging-desaturate;95;97;95;96;95;96;96;94;94;94 08:09:39 INFO - PROCESS | 20669 | |8;json-parse-financial;49;50;49;49;50;48;53;48;48;48 08:09:39 INFO - PROCESS | 20669 | |9;json-stringify-tinderbox;58;58;58;58;59;58;59;58;59;60 08:09:39 INFO - PROCESS | 20669 | |10;stanford-crypto-aes;73;72;72;77;74;76;73;81;73;72 08:09:39 INFO - PROCESS | 20669 | |11;stanford-crypto-ccm;120;117;128;118;117;129;129;118;167;125 08:09:39 INFO - PROCESS | 20669 | |12;stanford-crypto-pbkdf2;152;152;155;156;154;152;152;155;157;151 08:09:39 INFO - PROCESS | 20669 | |13;stanford-crypto-sha256-iterative;62;62;63;65;63;63;62;66;60;62 08:09:39 INFO - PROCESS | 20669 | __end_tp_report 08:09:39 INFO - PROCESS | 20669 | __start_cc_report 08:09:39 INFO - PROCESS | 20669 | _x_x_mozilla_cycle_collect,1627 08:09:39 INFO - PROCESS | 20669 | __end_cc_report 08:09:39 INFO - PROCESS | 20669 | __startTimestamp1474556979139__endTimestamp 08:09:39 INFO - PROCESS | 20669 | 08:09:39 INFO - PROCESS | 20669 | ------- Summary: start ------- 08:09:39 INFO - PROCESS | 20669 | Number of tests: 14 08:09:39 INFO - PROCESS | 20669 | 08:09:39 INFO - PROCESS | 20669 | [#0] ai-astar Cycles:10 Average:110.00 Median:111.00 stddev:1.25 (1.1%) stddev-sans-first:1.09 08:09:39 INFO - PROCESS | 20669 | Values: 108.0 111.0 111.0 108.0 111.0 110.0 111.0 110.0 111.0 109.0 08:09:39 INFO - PROCESS | 20669 | 08:09:39 INFO - PROCESS | 20669 | [#1] audio-beat-detection Cycles:10 Average:126.70 Median:118.00 stddev:16.61 (14.1%) stddev-sans-first:16.79 08:09:39 INFO - PROCESS | 20669 | Values: 141.0 115.0 117.0 158.0 117.0 118.0 117.0 115.0 151.0 118.0 08:09:39 INFO - PROCESS | 20669 | 08:09:39 INFO - PROCESS | 20669 | [#2] audio-dft Cycles:10 Average:161.40 Median:162.00 stddev:1.35 (0.8%) stddev-sans-first:1.12 08:09:39 INFO - PROCESS | 20669 | Values: 159.0 160.0 161.0 161.0 164.0 162.0 162.0 162.0 162.0 161.0 08:09:39 INFO - PROCESS | 20669 | 08:09:39 INFO - PROCESS | 20669 | [#3] audio-fft Cycles:10 Average:81.40 Median:75.00 stddev:13.71 (18.3%) stddev-sans-first:14.35 08:09:39 INFO - PROCESS | 20669 | Values: 75.0 75.0 76.0 112.0 75.0 74.0 75.0 74.0 102.0 76.0 08:09:39 INFO - PROCESS | 20669 | 08:09:39 INFO - PROCESS | 20669 | [#4] audio-oscillator Cycles:10 Average:90.20 Median:82.00 stddev:13.02 (15.9%) stddev-sans-first:13.47 08:09:39 INFO - PROCESS | 20669 | Values: 82.0 82.0 115.0 84.0 82.0 82.0 105.0 82.0 82.0 106.0 08:09:39 INFO - PROCESS | 20669 | 08:09:39 INFO - PROCESS | 20669 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 08:09:39 INFO - PROCESS | 20669 | Values: 117.0 117.0 117.0 117.0 116.0 117.0 116.0 116.0 117.0 117.0 08:09:39 INFO - PROCESS | 20669 | 08:09:39 INFO - PROCESS | 20669 | [#6] imaging-darkroom Cycles:10 Average:145.50 Median:146.00 stddev:0.53 (0.4%) stddev-sans-first:0.53 08:09:39 INFO - PROCESS | 20669 | Values: 146.0 145.0 145.0 146.0 146.0 145.0 145.0 146.0 145.0 146.0 08:09:39 INFO - PROCESS | 20669 | 08:09:39 INFO - PROCESS | 20669 | [#7] imaging-desaturate Cycles:10 Average:95.20 Median:95.50 stddev:1.03 (1.1%) stddev-sans-first:1.09 08:09:39 INFO - PROCESS | 20669 | Values: 95.0 97.0 95.0 96.0 95.0 96.0 96.0 94.0 94.0 94.0 08:09:39 INFO - PROCESS | 20669 | 08:09:39 INFO - PROCESS | 20669 | [#8] json-parse-financial Cycles:10 Average:49.20 Median:49.00 stddev:1.55 (3.2%) stddev-sans-first:1.64 08:09:39 INFO - PROCESS | 20669 | Values: 49.0 50.0 49.0 49.0 50.0 48.0 53.0 48.0 48.0 48.0 08:09:39 INFO - PROCESS | 20669 | 08:09:39 INFO - PROCESS | 20669 | [#9] json-stringify-tinderbox Cycles:10 Average:58.50 Median:58.50 stddev:0.71 (1.2%) stddev-sans-first:0.73 08:09:39 INFO - PROCESS | 20669 | Values: 58.0 58.0 58.0 58.0 59.0 58.0 59.0 58.0 59.0 60.0 08:09:39 INFO - PROCESS | 20669 | 08:09:39 INFO - PROCESS | 20669 | [#10] stanford-crypto-aes Cycles:10 Average:74.30 Median:73.50 stddev:2.91 (4.0%) stddev-sans-first:3.05 08:09:39 INFO - PROCESS | 20669 | Values: 73.0 72.0 72.0 77.0 74.0 76.0 73.0 81.0 73.0 72.0 08:09:39 INFO - PROCESS | 20669 | 08:09:39 INFO - PROCESS | 20669 | [#11] stanford-crypto-ccm Cycles:10 Average:126.80 Median:126.50 stddev:14.99 (11.9%) stddev-sans-first:15.70 08:09:39 INFO - PROCESS | 20669 | Values: 120.0 117.0 128.0 118.0 117.0 129.0 129.0 118.0 167.0 125.0 08:09:39 INFO - PROCESS | 20669 | 08:09:39 INFO - PROCESS | 20669 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:153.60 Median:154.50 stddev:2.07 (1.3%) stddev-sans-first:2.11 08:09:39 INFO - PROCESS | 20669 | Values: 152.0 152.0 155.0 156.0 154.0 152.0 152.0 155.0 157.0 151.0 08:09:39 INFO - PROCESS | 20669 | 08:09:39 INFO - PROCESS | 20669 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.80 Median:63.00 stddev:1.69 (2.7%) stddev-sans-first:1.76 08:09:39 INFO - PROCESS | 20669 | Values: 62.0 62.0 63.0 65.0 63.0 63.0 62.0 66.0 60.0 62.0 08:09:39 INFO - PROCESS | 20669 | -------- Summary: end -------- 08:09:39 INFO - PROCESS | 20669 | 08:09:40 INFO - TEST-INFO | 20669: exit 0 08:09:40 INFO - TEST-OK | kraken | took 60331ms 08:09:40 INFO - SUITE-END | took 1174s 08:09:40 INFO - Completed test suite (00:19:34) 08:09:40 INFO - javascript benchmark 08:09:40 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3088.2896933705247, 3132.4831402818536, 3142.4657808172624, 3161.509965955278, 3176.3012137329792, 5599.524040456561, 5663.150527420889, 5666.263385759856, 5670.064439367499, 5691.08942569189, 5732.656040637565, 5787.392323805992, 5796.768129274824, 5802.216700745398, 5829.941700582993, 5192.091384007803, 5209.921851172236, 5226.268322434862, 5265.65773224741, 5300.01949639316, 5064.215046667772, 5297.17893726473, 5299.5230429261355, 5307.73461326934, 5344.331958505187, 5037.143685573461, 5079.136546787036, 5122.3084883540805, 5155.304033955405, 5161.483851614846, 5113.667611605252, 5137.691738495665, 5137.972405518911, 5250.264962905197, 5251.396089449724, 5088.618353623456, 5134.871628209325, 5204.76578553966, 5209.374875014984, 5249.685018898878, 5512.944870551323, 5558.388577256539, 5632.3804381518, 5644.491995720364, 5650.773969041234, 6069.726862291208, 6072.878542429126, 6168.1056246843955, 6169.629822210682, 6173.32093469718, 6164.075388691687, 6173.228346456693, 6190.473809726179, 6190.969045154746, 6193.411625895533, 5489.58828087891, 5541.33503979525, 5660.405657405954, 5687.914681279784, 5704.172894930211, 6306.936930630727, 6308.526860485482, 6311.526635502356, 6317.4946004319545, 6330.272018717815, 6070.453659170696, 6081.665508396992, 6083.695815209222, 6085.026395776655, 6085.361037091042, 5890.823275301748, 5974.2830860297045, 6004.159417681528, 6068.33248342682, 6171.814845554641, 6025.0, 6085.330613632497, 6093.451589356979, 6114.174586430864, 6130.816075517741, 6023.156758053876, 6080.14877917092, 6081.300650425258, 6099.024156134997, 6105.267367915879, 4566.109608626286, 4590.793414296365, 4677.859664210079, 4688.367070445446, 4691.695039822401, 4039.2325458162854, 4077.857274995361, 4084.0606660467665, 4088.652464540488, 4133.173365326887, 5687.459691329317, 5704.030314846486, 5704.942950570524, 5710.857228569319, 5730.856728581819, 5932.0, 5968.1942937703725, 5977.222961015127, 5984.551158663117, 5998.940010599838, 5676.432356764373, 5796.478316951495, 5815.82552523425, 5820.912686309709, 5939.732712027969, 5322.840314790563, 5352.759125839347, 5361.490658387447, 5364.6512976656395, 5366.7048312343195, 6107.480864126599, 6315.652639104894, 6348.873022539616, 6368.29948705642, 6371.076193951851, 5988.251468566429, 6068.666223357581, 6102.0541816018585, 6113.141862489121, 6168.845778855564, 6279.089532017923, 6303.49572034227, 6306.905396419149, 6307.842303942438, 6311.96844015777, 6085.148079268817, 6091.847703807441, 6095.299040610386, 6099.725512352044, 6101.725422356093, 5917.85205369852, 6047.516198704181, 6057.242844644419, 6073.149759033827, 6185.876282474415, 5959.0763431668165, 5992.101184822137, 5992.940070599413, 5998.790042348497, 6032.969835150796, 5956.731947062306, 5973.044312909914, 5975.820725378072, 5975.970120149372, 5978.103284507185, 5692.971535142297, 5732.742026608729, 5797.565182611237, 5857.560682948711, 5911.172435859069, 5884.588078834612, 6012.368701286302, 6013.128096426081, 6052.878942421215, 6053.4551890330085, 5839.708014599338, 5841.18223448709, 5842.795502157404, 5848.766049357978, 5862.530997520274], "value": 5609.632754123024, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [590.4744777148338, 606.7572970811675, 623.8346838087905, 639.3510586754442, 643.1221382812461, 35769.46345804815, 36467.0, 37480.06299842502, 38023.04942376435, 38148.61851381485, 44524.0, 45036.0992780145, 45055.549444505545, 45056.09887802242, 45074.32388514175, 9156.267498600113, 9306.395084319498, 9309.53452327381, 9311.76720581984, 9317.021712720169, 8618.56907154643, 8833.160849719266, 10909.890901091007, 11079.058280046205, 11087.778244435107, 11178.0, 11268.098552115853, 11277.32336059835, 11277.436128193598, 11279.097672186246, 11012.889871101306, 11070.169737269736, 11101.7224569386, 11104.500297486633, 11158.553657853676, 10928.617498387519, 10980.450977451095, 10996.0, 10997.945010274978, 11002.284851484628, 43939.34090988638, 44022.77988610036, 44181.0, 44302.33546496805, 44339.77830110829, 3557.2174121693447, 3686.3733165361687, 3796.9810150949343, 3807.619238076198, 3956.109875278068, 32676.6732332676, 32732.181695457566, 32846.507302390484, 32923.67076329254, 33132.3373532528, 4717.127331443694, 4738.0, 4801.2798080287885, 4801.4958429365115, 4886.657933944625, 240297.0, 240803.0, 240895.0, 242291.0, 242720.78639606867, 58197.0, 58426.0, 58507.70746146242, 58521.70739146362, 58528.0, 39758.20483590312, 40610.18779624391, 40716.0, 43052.0, 62466.0, 17076.658466830595, 17196.31214751421, 17220.225089870986, 17224.138793060298, 17228.741568876478, 10896.78206435867, 12788.0, 12788.36058197105, 12820.551280705131, 12821.23072615646, 17365.7395139073, 19243.0, 19636.509087272934, 19890.90054549718, 20622.27822026222, 7258.0, 7309.415246780245, 7488.625568721651, 7594.164641889388, 7693.884591731128, 1005.6228914157191, 1039.4335087377397, 1088.7060493666825, 1098.6649072033106, 1106.2686895009285, 33284.0, 33459.0, 33504.832475837466, 33581.16047098842, 33614.49578256328, 1096.5997410944947, 1165.2542372881362, 1166.1370585766642, 1170.4849866058908, 1190.4999900042003, 15121.319540620698, 15913.363465461483, 17150.656986860195, 17155.859610338495, 17688.646227075387, 608280.0, 615427.0, 615477.0, 615513.0, 615754.0, 58200.417995820346, 58720.70639646774, 58738.70630646819, 58740.70629646824, 58901.0, 1255.6484184428375, 1319.399673148715, 1363.5023216525897, 1378.8207533020643, 1408.6900881805987, 30220.0, 34153.48769768505, 34418.48372274468, 34458.82770586231, 34556.65443345635, 49259.0, 49589.0, 49807.750961244965, 49966.50033499764, 50032.249516258016, 415579.0, 416561.0, 416661.0, 416782.0, 417074.0, 29532.261693456974, 30317.242068948453, 30776.538351925188, 32611.18472038218, 32636.347273054882, 48259.0, 48598.27102593535, 48777.53667389984, 48937.75531122321, 49412.75293623509, 41208.1758364825, 41250.0, 41665.58334416617, 42691.57308426876, 42940.78529607332, 42560.5743942569, 42602.78698606487, 42949.57050429581, 43552.0, 44097.11805763803, 42108.368374473794, 42843.57156428396, 43101.78449107734, 43133.352999705654, 43201.0, 35368.46947295741, 37165.0, 37425.0, 37614.24771504609, 38041.0, 28436.43127137487, 28649.0, 29119.708802912548, 29139.7086029137, 29290.853545732134], "value": 20610.283576072274, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [392.7270546969857, 429.429458473978, 432.3601070415785, 432.5695932547115, 448.4394506866417, 16425.671486570278, 19415.902920485394, 21038.474038149016, 21218.0, 21326.146954121836, 17826.75212735109, 18756.343527976525, 19278.51803704908, 19287.61424771504, 19783.5054123647, 5829.679367634779, 6057.424544668272, 6062.0, 6062.060380641008, 6068.757249710006, 5687.800926967557, 5918.437748413894, 5919.023361145406, 5953.910691339634, 5963.194968679216, 5749.453801888814, 5945.227120474332, 5948.643081415107, 5952.4047595240345, 5953.077273022688, 5848.561357898154, 5960.523158147338, 5963.224780778514, 5978.760849566012, 5992.970035149818, 5343.812966546152, 5363.678179309254, 5386.3536375634785, 5391.946080539184, 5392.568594512469, 20115.497112572157, 20193.697094543593, 20382.898085509627, 20488.18047278107, 20535.794642053686, 2582.2769624505167, 2623.4753049390197, 2673.7459941305738, 2876.4966130927005, 2945.6023436835967, 15300.08199508033, 15332.15673137977, 15511.922440387725, 15512.379504819794, 15557.455489057942, 1954.1890115602007, 1974.3780709076595, 2020.8282296004857, 2049.7437820272467, 2081.0, 84363.99999999939, 85968.0, 86054.0, 86562.56718716428, 88321.55839220763, 9990.450525221037, 11075.612353567587, 11228.775424491465, 11370.772584548262, 11431.657050288504, 14628.853711462962, 15470.381184752709, 15473.071615703095, 15542.300596473186, 15568.299426525833, 7856.803579910434, 7966.760997170094, 8213.137620549876, 8260.8347833043, 8280.171982801672, 7544.547327160388, 7625.809354766176, 7654.96172519145, 7768.378529717609, 7866.960665196638, 6842.726290948406, 6918.0, 6962.6422847545255, 7007.229204787469, 7114.750983715545, 2059.5160137367707, 2076.1591555420077, 2080.2927004818434, 2081.906314215864, 2089.0703636881744, 943.3113826906259, 1025.9127974122284, 1036.8030074285862, 1091.7052395853234, 1121.3496172220093, 20502.17991280332, 20689.58620827575, 20762.48093797667, 21114.78885211159, 21149.27233275569, 1028.2442404832443, 1035.295998720877, 1047.879493858201, 1053.3627155570923, 1109.2290857853714, 9633.32566720323, 11911.225770324902, 11975.341356225317, 11998.940005299917, 12012.339321337411, 487825.0, 493693.0, 497724.0, 502430.0, 506618.0, 87830.0, 90257.54871225602, 90965.0, 90994.54502727443, 91014.0, 1016.044917777287, 1029.9742506437399, 1054.488573042074, 1055.9894401056044, 1060.4379678770265, 16789.0, 18520.12937994613, 18643.813561864208, 18701.0, 18814.24743010264, 42911.356329655704, 43025.56974430341, 43029.56970430381, 43263.13473730571, 44519.33221001752, 56293.0, 57714.42285577091, 57724.422755771906, 58415.4158458427, 58918.0, 12299.69250768738, 12380.690482738004, 12560.623181304576, 13080.672983175496, 13242.668933276746, 13558.457661693421, 14324.427022919383, 14696.265186740835, 14725.779113313094, 15232.847671523143, 12916.418761155583, 12979.675508111995, 13061.151025183333, 13140.34298285101, 13504.919606431658, 12487.750244995232, 12606.306653133799, 12648.936755316165, 12862.292573908151, 13131.080824342205, 12917.186932578497, 12995.415206315549, 12995.935020324838, 13042.934785326392, 13066.346682666019, 13140.0, 13146.868531314565, 13152.276624785729, 13338.13302135358, 13409.0, 12634.305113218494, 12867.678308042, 13006.414711337824, 13136.737265254833, 13190.0], "value": 9991.242921025945, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1417.3763544040628, 1518.6962607478504, 1558.2909776051895, 1698.7027270227707, 1846.4552956877712, 8315.542645154514, 9077.954610226949, 9406.90593094069, 9478.431294122358, 9498.382605130679, 9230.538473076353, 9324.813503729938, 9337.206337461324, 9341.532923353821, 9359.48522831244, 2610.4257063446057, 2633.749793769589, 2644.034927251549, 2644.2728249731285, 2651.2841532786138, 2558.60341647045, 2621.6198651195564, 2627.829191102572, 2632.0787724296533, 2641.0492222799776, 2588.8317259378173, 2591.520568694788, 2622.7770639495666, 2626.973730262702, 2629.7238789926973, 2551.6045013023013, 2578.252306831026, 2578.871056447189, 2594.1179998800403, 2595.0917178987393, 2532.8100392470637, 2542.427953710419, 2547.681539807524, 2581.38046868752, 2588.6634737484196, 9005.234555062827, 9174.0, 9528.094830991045, 9617.663381781675, 9639.132478077006, 5710.714464276811, 5937.495312898409, 5940.2871655401195, 5944.078667806497, 5949.762009519614, 3688.5942546319884, 3713.405855063204, 3729.664330210267, 3751.718621103401, 3784.4890939723336, 1257.6227131860496, 1266.5693664154232, 1269.4033804112053, 1299.0906365544156, 1313.2973858985492, 13301.201927884358, 13410.0, 13445.0, 13467.730645387135, 13467.932660336734, 4905.803767849317, 5099.77051032697, 5101.617378696613, 5118.10433174193, 5230.92153617696, 10490.265681402381, 10535.051845333957, 10544.52549635253, 10546.314489558154, 10554.47227638615, 5627.8593035174445, 5658.519025882763, 5664.915026274609, 5670.092785154356, 5677.120046392815, 4545.2954792007295, 4775.2598347256235, 4856.805727770849, 4865.854024379274, 5310.946890531122, 4195.328747400401, 4235.322348424237, 4354.738715677069, 4359.978200108979, 4372.475302963665, 1247.328579703184, 1263.8041103628952, 1276.1959965221852, 1277.1506947879557, 1282.7498637765545, 842.3135144856922, 948.7675519497767, 1013.2552573858355, 1015.1016350529686, 1054.146141625286, 5969.194158788508, 6056.121862329937, 6343.1436756038265, 6374.0, 6376.394242547043, 1014.06705830636, 1035.5650626736638, 1055.1400608504046, 1057.360297020307, 1061.5753698520653, 4130.586941305845, 4269.338252570857, 4305.97847010763, 4354.542773008852, 4465.866024019285, 11585.131115166396, 12037.819432708517, 12051.939740301243, 12060.879391206152, 12072.128820032909, 11929.164958452999, 11936.701582460508, 11941.582044628396, 11944.16390852632, 11984.52061917531, 8366.83266334682, 8636.395452318277, 8655.6970506032, 8828.117188281249, 8853.601587928559, 6210.596311239755, 6316.778912738032, 6369.14016607752, 6583.96708016476, 6714.328567143247, 11007.569016027865, 11107.44462776874, 11167.218294719292, 11264.042556382472, 11274.718132046764, 10101.343412678152, 10477.52851121686, 10983.0, 11237.157213208879, 11352.60265890723, 8165.918340816515, 8563.058063613122, 8566.700165494178, 8616.052234254352, 8628.956855215683, 9189.908100919172, 9206.677766278415, 9262.16640502358, 9262.907370926474, 9264.258859291105, 8184.2224988625985, 8377.0, 8461.153884611736, 8468.11084836108, 8608.91391086081, 8117.959410202911, 8143.918560814316, 8212.425130240827, 8298.336133109216, 8347.332213422791, 7610.12483564375, 7638.847223055619, 7724.691012359667, 7725.845483090419, 7754.418418618513, 5301.893962120659, 5369.27514785499, 5427.457254274541, 5428.782848686008, 5477.616566840283, 5356.464353564613, 5562.276904002454, 5629.155626656035, 5653.773849045992, 5743.798967036134], "value": 5009.80396540941, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [358.2226568346687, 361.8516408272608, 362.07851019156243, 362.80952499937547, 365.648976982097, 5523.309586301712, 5524.419935906727, 5530.170474428838, 5535.446455354468, 5583.190437386576, 5484.341878974518, 5485.6982865942355, 5507.284053073095, 5508.283923090003, 5511.200875872996, 4093.8157782899693, 4288.485381754193, 4332.761698106603, 4341.782910854445, 4342.630876375511, 4307.978460107695, 4310.719803212797, 4310.762908040057, 4327.459067616548, 4336.436263285768, 4293.8067786949505, 4305.729809706144, 4319.632831209351, 4326.286162783153, 4333.198358303724, 4171.311733563958, 4260.38224457455, 4296.656267498593, 4304.612584867377, 4337.1976184405985, 3793.0, 3796.9810150949343, 3803.7527560708463, 3810.8285127169343, 3814.2180852925085, 5333.893322133575, 5346.358436987547, 5398.973005134949, 5404.243405923174, 5416.431274716177, 4764.190087685067, 4844.522420661697, 4881.875599616248, 4936.111499930011, 4944.9258261126115, 5634.859128521779, 5666.490015898589, 5684.516816070598, 5688.0, 5701.543876489871, 4185.4977402711565, 4195.0, 4195.992961689203, 4208.569086510571, 4212.473440819897, 5628.874225154986, 5880.647161170301, 5919.437653422961, 5989.790357337472, 6019.277686677583, 4235.152969406131, 4254.851080212177, 4259.382389553497, 4264.424302719093, 4279.272523670921, 4331.0, 4345.891352716208, 4361.716488428242, 4363.410939523187, 4365.345198220292, 4827.275908613666, 4874.0, 4898.21628539432, 4974.104661160954, 4978.253262010728, 5704.857378565569, 5739.684317362502, 5742.253507044057, 5746.454086861741, 5747.48272655463, 1480.4744315767962, 1502.7295086884467, 1506.246876561719, 1506.7739839024234, 1506.8322050411018, 921.1214953271028, 962.5908988679784, 964.9723044957099, 968.8837339519162, 985.3989066669326, 4919.975400122977, 4922.532359425849, 4937.432195297515, 4941.925871111936, 4953.356063711766, 5157.1176520569725, 5184.844454666366, 5187.714675692798, 5240.895182096337, 5284.207368894619, 4656.020147105855, 4912.606991440676, 4978.32792572998, 4992.327570263929, 5024.698518088926, 2718.497078040569, 2741.218208164709, 2742.766864816473, 2747.6702795664646, 2758.3655759175504, 5286.0, 5305.814296499681, 5306.946930530722, 5310.840674779763, 5315.867103322448, 5023.271625614266, 5180.844574662766, 5259.684418934876, 5278.4721527846905, 5712.885742285131, 5838.0, 5842.474177324061, 5969.283685957713, 5986.161937328778, 5998.190244317048, 5798.59409841307, 5852.3269823969085, 5854.531637469077, 5856.824295271149, 5867.735951882261, 5584.944150558443, 5763.884722305615, 5764.636499035375, 5780.826575202751, 5795.623284486495, 5933.626549380282, 5947.762089516371, 5956.868195042928, 5962.25471816023, 5969.522438204846, 4251.277282861859, 4251.532331443601, 4265.552117027792, 4281.850135245251, 4299.376590394313, 5577.247071645276, 5855.3559108498885, 5872.119182122716, 5883.646981181143, 5908.05471124618, 5782.826515204551, 5792.189093526994, 5796.739146738491, 5829.921464529076, 5848.795292164754, 5588.944110558842, 5730.598858080061, 5817.738201780845, 5823.301203855395, 5823.970880145572, 5725.570582206435, 5742.971285143548, 5744.339400968942, 5745.1956726059225, 5751.194832723339, 5779.306483222041, 5822.7670893163795, 5829.15477255804, 5832.396091074988, 5858.47273745365, 5368.248445217595, 5621.5221706155435, 5654.208410822571, 5668.8095499945475, 5669.64709645509], "value": 4313.009955394227, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [782.6634547144729, 795.3994733975849, 819.863323027142, 831.1646794971049, 933.7292185266273, 15897.920510397445, 16987.320507179713, 17612.64774704505, 17772.0, 17776.733348999776, 16294.83705162948, 16450.177491125454, 16482.17589120545, 16484.75272870905, 16872.66254674906, 734.1373885684326, 751.7481643649384, 853.2491407561369, 871.7776966873438, 877.666486735043, 880.2254016465502, 889.5418859287472, 889.5818965086399, 890.692711014699, 891.3582220801045, 939.7509659940122, 943.504660053474, 943.9480828554426, 944.2681921510815, 944.2870632672323, 764.2739397572328, 774.3766268154134, 896.959636816348, 918.9448633082036, 921.9124183202586, 678.0575000748963, 678.2911857109333, 678.3555622158968, 678.5080816408114, 679.1476696745602, 16538.669226615522, 16547.255373508222, 16578.0, 16616.169191540495, 16623.750643740354, 14553.272336383245, 14663.0, 14777.113373197642, 14845.777313340308, 15177.317020734094, 12909.289989050598, 13340.466381344735, 13363.799543006862, 13459.528916487974, 13877.722445551033, 4682.882927926795, 4793.281007848851, 4838.032393521275, 4867.0, 4909.631777616693, 60339.09491357633, 86179.0, 87211.69182462331, 87674.12325876787, 89359.0, 11966.58116965902, 12105.87894121065, 12371.938140309241, 12648.936755316348, 12680.429380677891, 24676.876615617166, 25025.749742502707, 25195.87402062978, 25272.115475958253, 25349.36626584313, 9684.806303873884, 9799.706008819747, 9887.555060022316, 10138.847917281246, 10213.693589192337, 5023.824166154167, 5242.213667949838, 5252.422233554306, 5268.473152684701, 5270.8155214567305, 4064.7764372959773, 4197.097624010852, 4207.957920420818, 4210.200061988213, 4215.367694845798, 638.7476946606157, 719.6329871765437, 721.6463932673057, 731.3125661877924, 732.1763016606318, 619.8853212155766, 730.5762657658545, 745.4371949178336, 783.5220515485549, 801.078759426664, 10652.786944261072, 11154.10767138627, 11304.830427543593, 11511.19421640477, 12015.0, 669.0466085827676, 749.2282948562989, 752.6349720385608, 765.7664412354285, 842.7471758472434, 5478.534324582455, 5754.74103665336, 5796.21751063601, 6068.4538391544975, 6080.118382834553, 77622.6118869402, 97523.51238243764, 125873.0, 132722.0, 135874.3206283982, 109561.45219273852, 111234.44382778034, 112101.0, 114407.42796285966, 116612.0, 97417.5129124364, 98237.50881245692, 98862.0, 99008.0, 99290.50354748324, 10254.333468324536, 10835.945820270848, 10875.347479151276, 11245.493952771982, 11312.20814542974, 50180.74909625429, 50489.24266136084, 52658.736706316224, 52995.0, 53916.0, 73840.63079684567, 74364.62817685877, 78992.21007790079, 80251.0, 83817.0, 12962.157459765462, 13394.196348219139, 13586.93206533961, 13715.314234288046, 14129.858701412853, 15815.13016784088, 15965.361385544913, 16503.587410314838, 16934.237959291615, 16961.151942403078, 15326.950418071043, 15719.764203536728, 16048.518544443685, 16510.0, 16573.922695024783, 15483.458078967182, 15765.921170394075, 16404.179791010163, 17174.055426951138, 17200.225989830236, 13925.442982280596, 14668.70662586721, 14877.47928822486, 15894.8410515898, 15941.282642280894, 10579.995513571508, 11100.722481937693, 11467.713307167387, 11506.712332191428, 11735.178537502294, 11863.762724745284, 11938.58214962472, 12210.511579536718, 12227.816582751087, 12257.632271031884], "value": 8342.166894088006, "unit": "score"}], "name": "dromaeo_css", "value": 7698.867028007087, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 111.0, 111.0, 108.0, 111.0, 110.0, 111.0, 110.0, 111.0, 109.0], "value": 110.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 115.0, 117.0, 158.0, 117.0, 118.0, 117.0, 115.0, 151.0, 118.0], "value": 126.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 160.0, 161.0, 161.0, 164.0, 162.0, 162.0, 162.0, 162.0, 161.0], "value": 161.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 76.0, 112.0, 75.0, 74.0, 75.0, 74.0, 102.0, 76.0], "value": 81.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 115.0, 84.0, 82.0, 82.0, 105.0, 82.0, 82.0, 106.0], "value": 90.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 116.0, 117.0, 116.0, 116.0, 117.0, 117.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 145.0, 145.0, 146.0, 146.0, 145.0, 145.0, 146.0, 145.0, 146.0], "value": 145.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 97.0, 95.0, 96.0, 95.0, 96.0, 96.0, 94.0, 94.0, 94.0], "value": 95.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [49.0, 50.0, 49.0, 49.0, 50.0, 48.0, 53.0, 48.0, 48.0, 48.0], "value": 49.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 58.0, 58.0, 58.0, 59.0, 58.0, 59.0, 58.0, 59.0, 60.0], "value": 58.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 72.0, 72.0, 77.0, 74.0, 76.0, 73.0, 81.0, 73.0, 72.0], "value": 74.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [120.0, 117.0, 128.0, 118.0, 117.0, 129.0, 129.0, 118.0, 167.0, 125.0], "value": 126.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 152.0, 155.0, 156.0, 154.0, 152.0, 152.0, 155.0, 157.0, 151.0], "value": 153.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 62.0, 63.0, 65.0, 63.0, 63.0, 62.0, 66.0, 60.0, 62.0], "value": 62.8, "unit": "score"}], "name": "kraken", "value": 1452.3, "alertThreshold": 2.0}]} 08:09:40 INFO - Return code: 0 08:09:40 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:09:40 INFO - # TBPL SUCCESS # 08:09:40 INFO - Running post-action listener: _resource_record_post_action 08:09:40 INFO - [mozharness: 2016-09-22 15:09:40.470277Z] Finished run-tests step (success) 08:09:40 INFO - Running post-run listener: _resource_record_post_run 08:09:40 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 262144; Write bytes: 367210496; Read time: 12; Write time: 197332 08:09:40 INFO - TinderboxPrint: CPU usage
13.0% 08:09:40 INFO - TinderboxPrint: I/O read bytes / time
262,144 / 12 08:09:40 INFO - TinderboxPrint: I/O write bytes / time
367,210,496 / 197,332 08:09:40 INFO - TinderboxPrint: CPU idle
8,262.7 (87.0%) 08:09:40 INFO - TinderboxPrint: CPU user
1,187.8 (12.5%) 08:09:40 INFO - TinderboxPrint: Swap in / out
0 / 0 08:09:40 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 118689792; Read time: 0; Write time: 62972 08:09:40 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 262144; Write bytes: 247545856; Read time: 12; Write time: 134332 08:09:41 INFO - Running post-run listener: _upload_blobber_files 08:09:41 INFO - Blob upload gear active. 08:09:41 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:09:41 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 08:09:41 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-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 08:09:41 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-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 08:09:41 INFO - (blobuploader) - INFO - Open directory for files ... 08:09:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:09:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:09:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:09:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:09:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:09:42 INFO - (blobuploader) - INFO - Done attempting. 08:09:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:09:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:09:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:09:46 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:09:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:09:46 INFO - (blobuploader) - INFO - Done attempting. 08:09:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:09:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:09:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:09:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:09:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:09:47 INFO - (blobuploader) - INFO - Done attempting. 08:09:47 INFO - (blobuploader) - INFO - Iteration through files over. 08:09:47 INFO - Return code: 0 08:09:47 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:09:47 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:09:47 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/009743efbb9ce5b81c4255c765369e04e74f8dc9836e34fabfc6a7124c6c2cd3c4f2390ba5ff6c1b396e04420800f95e024a71d64547393853918276a7c457ab", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/690812c489144ad08af0b27491cab089b0240655d674a08501cd9b86466064c9cb69a27bfcb7762e1972404c2776b9f9fc27f73adfb38e52660f52a0aab360cd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/227af3ca4a548399d130cc607acd3aff65be569864e3c86626fa4d6f4a3196f4ee454e0da025d7326b0be55030a1acc4e6862da24520873d5d851c4f54603ddc"} 08:09:47 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:09:47 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:09:47 INFO - Contents: 08:09:47 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/009743efbb9ce5b81c4255c765369e04e74f8dc9836e34fabfc6a7124c6c2cd3c4f2390ba5ff6c1b396e04420800f95e024a71d64547393853918276a7c457ab", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/690812c489144ad08af0b27491cab089b0240655d674a08501cd9b86466064c9cb69a27bfcb7762e1972404c2776b9f9fc27f73adfb38e52660f52a0aab360cd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/227af3ca4a548399d130cc607acd3aff65be569864e3c86626fa4d6f4a3196f4ee454e0da025d7326b0be55030a1acc4e6862da24520873d5d851c4f54603ddc"} 08:09:47 INFO - Running post-run listener: copy_logs_to_upload_dir 08:09:47 INFO - Copying logs to upload dir... 08:09:47 INFO - mkdir: /builds/slave/test/build/upload/logs 08:09:47 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1234.990455 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 35 secs) (at 2016-09-22 08:09:46.820283) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-22 08:09:46.824055) ========= 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=920470bf432669ad7e3a5387000001ca-1474554887.763818-1325601685 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-Aurora/sha512/009743efbb9ce5b81c4255c765369e04e74f8dc9836e34fabfc6a7124c6c2cd3c4f2390ba5ff6c1b396e04420800f95e024a71d64547393853918276a7c457ab", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/690812c489144ad08af0b27491cab089b0240655d674a08501cd9b86466064c9cb69a27bfcb7762e1972404c2776b9f9fc27f73adfb38e52660f52a0aab360cd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/227af3ca4a548399d130cc607acd3aff65be569864e3c86626fa4d6f4a3196f4ee454e0da025d7326b0be55030a1acc4e6862da24520873d5d851c4f54603ddc"} build_url:https://queue.taskcluster.net/v1/task/Szx1hifsTvmxevhQo86Mlw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011414 build_url: 'https://queue.taskcluster.net/v1/task/Szx1hifsTvmxevhQo86Mlw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/009743efbb9ce5b81c4255c765369e04e74f8dc9836e34fabfc6a7124c6c2cd3c4f2390ba5ff6c1b396e04420800f95e024a71d64547393853918276a7c457ab", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/690812c489144ad08af0b27491cab089b0240655d674a08501cd9b86466064c9cb69a27bfcb7762e1972404c2776b9f9fc27f73adfb38e52660f52a0aab360cd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/227af3ca4a548399d130cc607acd3aff65be569864e3c86626fa4d6f4a3196f4ee454e0da025d7326b0be55030a1acc4e6862da24520873d5d851c4f54603ddc"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-22 08:09:46.853385) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-22 08:09:46.854132) ========= 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=920470bf432669ad7e3a5387000001ca-1474554887.763818-1325601685 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004510 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-22 08:09:46.935755) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-22 08:09:46.936164) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-22 08:09:46.936541) ========= ========= Total master_lag: 0.17 =========