builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-054 starttime: 1474401278.01 results: success (0) buildid: 20160920100526 builduid: 68aceab302ef41f8a0af653c3bbce860 revision: e7108b1c6731f1456ee875ed32e469c5eb7df1c1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-20 12:54:38.012907) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-20 12:54:38.013409) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-20 12:54:38.013748) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-20 12:54:38.049143) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 12:54:38.049453) ========= 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=cbe409c6de289db6ac707de3000001d4-1474401277.584668-1243232512 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-20 12:54:38-- 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.0M=0s 2016-09-20 12:54:38 (46.0 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.074738 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 12:54:38.138495) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 12:54:38.138849) ========= 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=cbe409c6de289db6ac707de3000001d4-1474401277.584668-1243232512 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016048 ========= master_lag: 0.74 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 12:54:38.895144) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-20 12:54:38.895499) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev e7108b1c6731f1456ee875ed32e469c5eb7df1c1 --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 e7108b1c6731f1456ee875ed32e469c5eb7df1c1 --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=cbe409c6de289db6ac707de3000001d4-1474401277.584668-1243232512 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-20 12:54:39,246 truncating revision to first 12 chars 2016-09-20 12:54:39,246 Setting DEBUG logging. 2016-09-20 12:54:39,246 attempt 1/10 2016-09-20 12:54:39,246 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/e7108b1c6731?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-20 12:54:40,704 unpacking tar archive at: mozilla-aurora-e7108b1c6731/testing/mozharness/ program finished with exit code 0 elapsedTime=1.803415 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-20 12:54:40.712767) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-20 12:54:40.713088) ========= script_repo_revision: e7108b1c6731f1456ee875ed32e469c5eb7df1c1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-20 12:54:40.713515) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-20 12:54:40.713796) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-20 12:54:40.723760) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 36 secs) (at 2016-09-20 12:54:40.724048) ========= /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=cbe409c6de289db6ac707de3000001d4-1474401277.584668-1243232512 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 12:54:41 INFO - MultiFileLogger online at 20160920 12:54:41 in /builds/slave/test 12:54:41 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 12:54:41 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:54:41 INFO - {'append_to_log': False, 12:54:41 INFO - 'base_work_dir': '/builds/slave/test', 12:54:41 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 12:54:41 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:54:41 INFO - 'branch': 'Mozilla-Aurora', 12:54:41 INFO - 'buildbot_json_path': 'buildprops.json', 12:54:41 INFO - 'config_files': ('talos/linux_config.py',), 12:54:41 INFO - 'default_actions': ('clobber', 12:54:41 INFO - 'read-buildbot-config', 12:54:41 INFO - 'download-and-extract', 12:54:41 INFO - 'populate-webroot', 12:54:41 INFO - 'create-virtualenv', 12:54:41 INFO - 'install', 12:54:41 INFO - 'run-tests'), 12:54:41 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:54:41 INFO - 'download_minidump_stackwalk': True, 12:54:41 INFO - 'download_symbols': 'ondemand', 12:54:41 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:54:41 INFO - 'tooltool.py': '/tools/tooltool.py', 12:54:41 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:54:41 INFO - '/tools/misc-python/virtualenv.py')}, 12:54:41 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:54:41 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:54:41 INFO - 'installer_path': 'installer.exe', 12:54:41 INFO - 'log_level': 'info', 12:54:41 INFO - 'log_name': 'talos', 12:54:41 INFO - 'log_to_console': True, 12:54:41 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 12:54:41 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 12:54:41 INFO - 'opt_config_files': (), 12:54:41 INFO - 'pip_index': False, 12:54:41 INFO - 'sps_profile': False, 12:54:41 INFO - 'sps_profile_interval': 0, 12:54:41 INFO - 'suite': 'dromaeojs', 12:54:41 INFO - 'system_bits': '32', 12:54:41 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:54:41 INFO - 'title': 'talos-linux64-ix-054', 12:54:41 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:54:41 INFO - 'use_talos_json': True, 12:54:41 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:54:41 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:54:41 INFO - 'work_dir': 'build'} 12:54:41 INFO - [mozharness: 2016-09-20 19:54:41.170293Z] Running clobber step. 12:54:41 INFO - Running pre-action listener: _resource_record_pre_action 12:54:41 INFO - Running main action method: clobber 12:54:41 INFO - rmtree: /builds/slave/test/build 12:54:41 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:54:41 INFO - Running post-action listener: _resource_record_post_action 12:54:41 INFO - [mozharness: 2016-09-20 19:54:41.633132Z] Finished clobber step (success) 12:54:41 INFO - [mozharness: 2016-09-20 19:54:41.633234Z] Running read-buildbot-config step. 12:54:41 INFO - Running pre-action listener: _resource_record_pre_action 12:54:41 INFO - Running main action method: read_buildbot_config 12:54:41 INFO - Using buildbot properties: 12:54:41 INFO - { 12:54:41 INFO - "project": "", 12:54:41 INFO - "product": "firefox", 12:54:41 INFO - "script_repo_revision": "production", 12:54:41 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 12:54:41 INFO - "repository": "", 12:54:41 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 12:54:41 INFO - "buildid": "20160920100526", 12:54:41 INFO - "pgo_build": "True", 12:54:41 INFO - "basedir": "/builds/slave/test", 12:54:41 INFO - "buildnumber": 770, 12:54:41 INFO - "slavename": "talos-linux64-ix-054", 12:54:41 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 12:54:41 INFO - "platform": "ubuntu64_hw", 12:54:41 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 12:54:41 INFO - "branch": "mozilla-aurora", 12:54:41 INFO - "repo_path": "releases/mozilla-aurora", 12:54:41 INFO - "revision": "e7108b1c6731f1456ee875ed32e469c5eb7df1c1", 12:54:41 INFO - "stage_platform": "linux64-pgo", 12:54:41 INFO - "builduid": "68aceab302ef41f8a0af653c3bbce860", 12:54:41 INFO - "slavebuilddir": "test" 12:54:41 INFO - } 12:54:41 INFO - Found installer url https://queue.taskcluster.net/v1/task/G2kD2NPnS2OGw3At7WAMQg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2. 12:54:41 INFO - Running post-action listener: _resource_record_post_action 12:54:41 INFO - [mozharness: 2016-09-20 19:54:41.635700Z] Finished read-buildbot-config step (success) 12:54:41 INFO - [mozharness: 2016-09-20 19:54:41.635791Z] Running download-and-extract step. 12:54:41 INFO - Running pre-action listener: _resource_record_pre_action 12:54:41 INFO - Running main action method: download_and_extract 12:54:41 INFO - mkdir: /builds/slave/test/build/tests 12:54: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')]} 12:54:41 INFO - trying https://queue.taskcluster.net/v1/task/G2kD2NPnS2OGw3At7WAMQg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 12:54:41 INFO - Downloading https://queue.taskcluster.net/v1/task/G2kD2NPnS2OGw3At7WAMQg/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 12:54:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/G2kD2NPnS2OGw3At7WAMQg/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 12:54:42 INFO - Downloaded 1361 bytes. 12:54:42 INFO - Reading from file /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 12:54:42 INFO - Using the following test package requirements: 12:54:42 INFO - {u'common': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 12:54:42 INFO - u'cppunittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 12:54:42 INFO - u'firefox-51.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 12:54:42 INFO - u'gtest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 12:54:42 INFO - u'firefox-51.0a2.en-US.linux-x86_64.gtest.tests.zip'], 12:54:42 INFO - u'jittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 12:54:42 INFO - u'jsshell-linux-x86_64.zip'], 12:54:42 INFO - u'mochitest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 12:54:42 INFO - u'firefox-51.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 12:54:42 INFO - u'mozbase': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 12:54:42 INFO - u'reftest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 12:54:42 INFO - u'firefox-51.0a2.en-US.linux-x86_64.reftest.tests.zip'], 12:54:42 INFO - u'talos': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 12:54:42 INFO - u'firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'], 12:54:42 INFO - u'web-platform': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 12:54:42 INFO - u'firefox-51.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 12:54:42 INFO - u'xpcshell': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 12:54:42 INFO - u'firefox-51.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 12:54:42 INFO - Downloading packages: [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 12:54:42 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/G2kD2NPnS2OGw3At7WAMQg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 12:54:42 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/G2kD2NPnS2OGw3At7WAMQg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 12:54:42 INFO - Fetch https://queue.taskcluster.net/v1/task/G2kD2NPnS2OGw3At7WAMQg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 12:54:42 INFO - Expected file size: 21894243 12:54:47 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 12:54:47 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/G2kD2NPnS2OGw3At7WAMQg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 12:54:47 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/G2kD2NPnS2OGw3At7WAMQg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 12:54:47 INFO - Fetch https://queue.taskcluster.net/v1/task/G2kD2NPnS2OGw3At7WAMQg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 12:54:47 INFO - Expected file size: 21894243 12:54:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/G2kD2NPnS2OGw3At7WAMQg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip 12:54:52 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/G2kD2NPnS2OGw3At7WAMQg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 12:54:52 INFO - Fetch https://queue.taskcluster.net/v1/task/G2kD2NPnS2OGw3At7WAMQg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip into memory 12:54:52 INFO - Expected file size: 14414059 12:54:53 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')]} 12:54:53 INFO - trying https://queue.taskcluster.net/v1/task/G2kD2NPnS2OGw3At7WAMQg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 12:54:53 INFO - Downloading https://queue.taskcluster.net/v1/task/G2kD2NPnS2OGw3At7WAMQg/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 12:54:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/G2kD2NPnS2OGw3At7WAMQg/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 12:54:54 INFO - Downloaded 63971086 bytes. 12:54:54 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/G2kD2NPnS2OGw3At7WAMQg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 12:54:54 INFO - mkdir: /builds/slave/test/properties 12:54:54 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:54:54 INFO - Writing to file /builds/slave/test/properties/build_url 12:54:54 INFO - Contents: 12:54:54 INFO - build_url:https://queue.taskcluster.net/v1/task/G2kD2NPnS2OGw3At7WAMQg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 12:54:55 INFO - Running post-action listener: _resource_record_post_action 12:54:55 INFO - Running post-action listener: set_extra_try_arguments 12:54:55 INFO - [mozharness: 2016-09-20 19:54:55.450769Z] Finished download-and-extract step (success) 12:54:55 INFO - [mozharness: 2016-09-20 19:54:55.450997Z] Running populate-webroot step. 12:54:55 INFO - Running pre-action listener: _resource_record_pre_action 12:54:55 INFO - Running main action method: populate_webroot 12:54:55 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:54:55 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:54:55 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:54:55 INFO - '--tppagecycles', 12:54:55 INFO - '7'], 12:54:55 INFO - 'tests': ['tsvgm']}}, 12:54:55 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:54:55 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:54:55 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:54:55 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:54:55 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:54:55 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:54:55 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:54:55 INFO - 'talos_options': ['--disable-e10s'], 12:54:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:54:55 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:54:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:54:55 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:54:55 INFO - 'talos_options': ['--disable-e10s'], 12:54:55 INFO - 'tests': ['damp', 'tps']}, 12:54:55 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:54:55 INFO - 'tests': ['damp', 'tps']}, 12:54:55 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:54:55 INFO - 'tests': ['dromaeo_dom']}, 12:54:55 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:54:55 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:54:55 INFO - 'tests': ['basic_compositor_video']}, 12:54:55 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 12:54:55 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:54:55 INFO - 'tests': ['a11yr', 12:54:55 INFO - 'ts_paint', 12:54:55 INFO - 'tpaint', 12:54:55 INFO - 'sessionrestore', 12:54:55 INFO - 'sessionrestore_no_auto_restore', 12:54:55 INFO - 'tabpaint']}, 12:54:55 INFO - 'other-e10s': {'tests': ['a11yr', 12:54:55 INFO - 'ts_paint', 12:54:55 INFO - 'tpaint', 12:54:55 INFO - 'sessionrestore', 12:54:55 INFO - 'sessionrestore_no_auto_restore', 12:54:55 INFO - 'tabpaint']}, 12:54:55 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:54:55 INFO - 'ts_paint', 12:54:55 INFO - 'tpaint', 12:54:55 INFO - 'sessionrestore', 12:54:55 INFO - 'sessionrestore_no_auto_restore', 12:54:55 INFO - 'tabpaint']}, 12:54:55 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:54:55 INFO - 'ts_paint', 12:54:55 INFO - 'tpaint', 12:54:55 INFO - 'sessionrestore', 12:54:55 INFO - 'sessionrestore_no_auto_restore', 12:54:55 INFO - 'tabpaint']}, 12:54:55 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:54:55 INFO - 'tests': ['a11yr', 12:54:55 INFO - 'ts_paint', 12:54:55 INFO - 'tpaint', 12:54:55 INFO - 'sessionrestore', 12:54:55 INFO - 'sessionrestore_no_auto_restore', 12:54:55 INFO - 'tabpaint']}, 12:54:55 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:54:55 INFO - 'tests': ['a11yr', 12:54:55 INFO - 'ts_paint', 12:54:55 INFO - 'tpaint', 12:54:55 INFO - 'sessionrestore', 12:54:55 INFO - 'sessionrestore_no_auto_restore', 12:54:55 INFO - 'tabpaint']}, 12:54:55 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:54:55 INFO - 'tests': ['tsvgx', 12:54:55 INFO - 'tsvgr_opacity', 12:54:55 INFO - 'tart', 12:54:55 INFO - 'tscrollx', 12:54:55 INFO - 'cart']}, 12:54:55 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:54:55 INFO - 'tsvgr_opacity', 12:54:55 INFO - 'tart', 12:54:55 INFO - 'tscrollx', 12:54:55 INFO - 'cart']}, 12:54:55 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:54:55 INFO - 'talos_options': ['--disable-e10s'], 12:54:55 INFO - 'tests': ['tp5o']}, 12:54:55 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:54:55 INFO - 'tests': ['tp5o']}, 12:54:55 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:54:55 INFO - 'talos_options': ['--disable-e10s', 12:54:55 INFO - '--xperf_path', 12:54:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:54:55 INFO - 'tests': ['tp5n']}, 12:54:55 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:54:55 INFO - 'talos_options': ['--xperf_path', 12:54:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:54:55 INFO - 'tests': ['tp5n']}}, 12:54:55 INFO - 'talos.zip': {'path': '', 12:54:55 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:54:55 INFO - Running post-action listener: _resource_record_post_action 12:54:55 INFO - [mozharness: 2016-09-20 19:54:55.471058Z] Finished populate-webroot step (success) 12:54:55 INFO - [mozharness: 2016-09-20 19:54:55.471147Z] Running create-virtualenv step. 12:54:55 INFO - Running pre-action listener: _resource_record_pre_action 12:54:55 INFO - Running main action method: create_virtualenv 12:54:55 INFO - Creating virtualenv /builds/slave/test/build/venv 12:54:55 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 12:54:55 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:54:55 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:54:55 INFO - Using real prefix '/usr' 12:54:55 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:54:56 INFO - Installing distribute.............................................................................................................................................................................................done. 12:54:58 INFO - Installing pip.................done. 12:54:58 INFO - Return code: 0 12:54:58 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:54:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:54:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:54:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:54:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:54:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:54:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:54:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7657677f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b178a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2be4ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2be1f60>, '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 0x2be2d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2be31d0>, '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': 'cbe409c6de289db6ac707de3000001d4-1474401277.584668-1243232512', '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 12:54:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 12:54:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 12:54:58 INFO - Using env: {'DISPLAY': ':0', 12:54:58 INFO - 'HOME': '/home/cltbld', 12:54:58 INFO - 'LANG': 'en_US.UTF-8', 12:54:58 INFO - 'LANGUAGE': 'en_US:en', 12:54:58 INFO - 'LOGNAME': 'cltbld', 12:54:58 INFO - 'MAIL': '/var/mail/cltbld', 12:54:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:54:58 INFO - 'MOZ_NO_REMOTE': '1', 12:54:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:54:58 INFO - 'NO_EM_RESTART': '1', 12:54:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:54:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:54:58 INFO - 'PWD': '/builds/slave/test', 12:54:58 INFO - 'SHELL': '/bin/bash', 12:54:58 INFO - 'SHLVL': '1', 12:54:58 INFO - 'TERM': 'linux', 12:54:58 INFO - 'TMOUT': '86400', 12:54:58 INFO - 'USER': 'cltbld', 12:54:58 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1474401277.584668-1243232512', 12:54:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:54:58 INFO - '_': '/tools/buildbot/bin/python'} 12:54:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:54:58 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 12:54:58 INFO - Cleaning up... 12:54:58 INFO - Return code: 0 12:54:58 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:54:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:54:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:54:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:54:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:54:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:54:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:54:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f7657677f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b178a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2be4ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2be1f60>, '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 0x2be2d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2be31d0>, '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': 'cbe409c6de289db6ac707de3000001d4-1474401277.584668-1243232512', '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 12:54:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 12:54:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 12:54:58 INFO - Using env: {'DISPLAY': ':0', 12:54:58 INFO - 'HOME': '/home/cltbld', 12:54:58 INFO - 'LANG': 'en_US.UTF-8', 12:54:58 INFO - 'LANGUAGE': 'en_US:en', 12:54:58 INFO - 'LOGNAME': 'cltbld', 12:54:58 INFO - 'MAIL': '/var/mail/cltbld', 12:54:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:54:58 INFO - 'MOZ_NO_REMOTE': '1', 12:54:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:54:58 INFO - 'NO_EM_RESTART': '1', 12:54:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:54:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:54:58 INFO - 'PWD': '/builds/slave/test', 12:54:58 INFO - 'SHELL': '/bin/bash', 12:54:58 INFO - 'SHLVL': '1', 12:54:58 INFO - 'TERM': 'linux', 12:54:58 INFO - 'TMOUT': '86400', 12:54:58 INFO - 'USER': 'cltbld', 12:54:58 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1474401277.584668-1243232512', 12:54:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:54:58 INFO - '_': '/tools/buildbot/bin/python'} 12:54:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:54:58 INFO - Downloading/unpacking psutil>=3.1.1 12:54:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:54:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:54:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:54:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:54:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:54:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:55:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:55:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:55:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:55:00 INFO - Installing collected packages: psutil 12:55:00 INFO - Running setup.py install for psutil 12:55:00 INFO - building 'psutil._psutil_linux' extension 12:55:00 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 12:55:00 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 12:55:00 INFO - building 'psutil._psutil_posix' extension 12:55:00 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 12:55:00 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 12:55:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:55:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:55:01 INFO - Successfully installed psutil 12:55:01 INFO - Cleaning up... 12:55:01 INFO - Return code: 0 12:55:01 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:55:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:55:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:55:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:55:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:55:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:55:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:55:01 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 0x7f7657677f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b178a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2be4ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2be1f60>, '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 0x2be2d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2be31d0>, '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': 'cbe409c6de289db6ac707de3000001d4-1474401277.584668-1243232512', '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 12:55:01 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 12:55:01 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 12:55:01 INFO - Using env: {'DISPLAY': ':0', 12:55:01 INFO - 'HOME': '/home/cltbld', 12:55:01 INFO - 'LANG': 'en_US.UTF-8', 12:55:01 INFO - 'LANGUAGE': 'en_US:en', 12:55:01 INFO - 'LOGNAME': 'cltbld', 12:55:01 INFO - 'MAIL': '/var/mail/cltbld', 12:55:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:55:01 INFO - 'MOZ_NO_REMOTE': '1', 12:55:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:55:01 INFO - 'NO_EM_RESTART': '1', 12:55:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:55:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:55:01 INFO - 'PWD': '/builds/slave/test', 12:55:01 INFO - 'SHELL': '/bin/bash', 12:55:01 INFO - 'SHLVL': '1', 12:55:01 INFO - 'TERM': 'linux', 12:55:01 INFO - 'TMOUT': '86400', 12:55:01 INFO - 'USER': 'cltbld', 12:55:01 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1474401277.584668-1243232512', 12:55:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:55:01 INFO - '_': '/tools/buildbot/bin/python'} 12:55:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:55:01 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:55:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:55:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:55:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:55:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:55:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:55:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:55:03 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:55:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:55:03 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:55:03 INFO - Installing collected packages: mozsystemmonitor 12:55:03 INFO - Running setup.py install for mozsystemmonitor 12:55:03 INFO - Successfully installed mozsystemmonitor 12:55:03 INFO - Cleaning up... 12:55:03 INFO - Return code: 0 12:55:03 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:55:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:55:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:55:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:55:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:55:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:55:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:55:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f7657677f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b178a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2be4ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2be1f60>, '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 0x2be2d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2be31d0>, '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': 'cbe409c6de289db6ac707de3000001d4-1474401277.584668-1243232512', '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 12:55:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 12:55:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 12:55:03 INFO - Using env: {'DISPLAY': ':0', 12:55:03 INFO - 'HOME': '/home/cltbld', 12:55:03 INFO - 'LANG': 'en_US.UTF-8', 12:55:03 INFO - 'LANGUAGE': 'en_US:en', 12:55:03 INFO - 'LOGNAME': 'cltbld', 12:55:03 INFO - 'MAIL': '/var/mail/cltbld', 12:55:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:55:03 INFO - 'MOZ_NO_REMOTE': '1', 12:55:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:55:03 INFO - 'NO_EM_RESTART': '1', 12:55:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:55:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:55:03 INFO - 'PWD': '/builds/slave/test', 12:55:03 INFO - 'SHELL': '/bin/bash', 12:55:03 INFO - 'SHLVL': '1', 12:55:03 INFO - 'TERM': 'linux', 12:55:03 INFO - 'TMOUT': '86400', 12:55:03 INFO - 'USER': 'cltbld', 12:55:03 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1474401277.584668-1243232512', 12:55:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:55:03 INFO - '_': '/tools/buildbot/bin/python'} 12:55:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:55:03 INFO - Downloading/unpacking jsonschema==2.5.1 12:55:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:55:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:55:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:55:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:55:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:55:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:55:05 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:55:05 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:55:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:55:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:55:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:55:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:55:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:55:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:55:05 INFO - Downloading functools32-3.2.3-2.tar.gz 12:55:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:55:05 INFO - warning: no files found matching '*.txt' 12:55:05 INFO - no previously-included directories found matching 'build' 12:55:05 INFO - no previously-included directories found matching 'dist' 12:55:05 INFO - no previously-included directories found matching '.git*' 12:55:05 INFO - Installing collected packages: jsonschema, functools32 12:55:05 INFO - Running setup.py install for functools32 12:55:05 INFO - warning: no files found matching '*.txt' 12:55:05 INFO - no previously-included directories found matching 'build' 12:55:05 INFO - no previously-included directories found matching 'dist' 12:55:05 INFO - no previously-included directories found matching '.git*' 12:55:05 INFO - Successfully installed jsonschema functools32 12:55:05 INFO - Cleaning up... 12:55:05 INFO - Return code: 0 12:55:05 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:55: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')]} 12:55:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:55:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:55: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')]} 12:55:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:55:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:55:05 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 0x7f7657677f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b178a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2be4ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2be1f60>, '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 0x2be2d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2be31d0>, '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': 'cbe409c6de289db6ac707de3000001d4-1474401277.584668-1243232512', '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 12:55:05 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 12:55:05 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 12:55:05 INFO - Using env: {'DISPLAY': ':0', 12:55:05 INFO - 'HOME': '/home/cltbld', 12:55:05 INFO - 'LANG': 'en_US.UTF-8', 12:55:05 INFO - 'LANGUAGE': 'en_US:en', 12:55:05 INFO - 'LOGNAME': 'cltbld', 12:55:05 INFO - 'MAIL': '/var/mail/cltbld', 12:55:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:55:05 INFO - 'MOZ_NO_REMOTE': '1', 12:55:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:55:05 INFO - 'NO_EM_RESTART': '1', 12:55:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:55:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:55:05 INFO - 'PWD': '/builds/slave/test', 12:55:05 INFO - 'SHELL': '/bin/bash', 12:55:05 INFO - 'SHLVL': '1', 12:55:05 INFO - 'TERM': 'linux', 12:55:05 INFO - 'TMOUT': '86400', 12:55:05 INFO - 'USER': 'cltbld', 12:55:05 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1474401277.584668-1243232512', 12:55:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:55:05 INFO - '_': '/tools/buildbot/bin/python'} 12:55:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:55:06 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:55:06 INFO - Cleaning up... 12:55:06 INFO - Return code: 0 12:55:06 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:55:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:55:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:55:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:55:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:55:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:55:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:55:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f7657677f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b178a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2be4ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2be1f60>, '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 0x2be2d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2be31d0>, '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': 'cbe409c6de289db6ac707de3000001d4-1474401277.584668-1243232512', '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 12:55:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 12:55:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 12:55:06 INFO - Using env: {'DISPLAY': ':0', 12:55:06 INFO - 'HOME': '/home/cltbld', 12:55:06 INFO - 'LANG': 'en_US.UTF-8', 12:55:06 INFO - 'LANGUAGE': 'en_US:en', 12:55:06 INFO - 'LOGNAME': 'cltbld', 12:55:06 INFO - 'MAIL': '/var/mail/cltbld', 12:55:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:55:06 INFO - 'MOZ_NO_REMOTE': '1', 12:55:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:55:06 INFO - 'NO_EM_RESTART': '1', 12:55:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:55:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:55:06 INFO - 'PWD': '/builds/slave/test', 12:55:06 INFO - 'SHELL': '/bin/bash', 12:55:06 INFO - 'SHLVL': '1', 12:55:06 INFO - 'TERM': 'linux', 12:55:06 INFO - 'TMOUT': '86400', 12:55:06 INFO - 'USER': 'cltbld', 12:55:06 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1474401277.584668-1243232512', 12:55:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:55:06 INFO - '_': '/tools/buildbot/bin/python'} 12:55:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:55:06 INFO - Downloading/unpacking blobuploader==1.2.4 12:55:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:55:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:55:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:55:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:55:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:55:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:55:08 INFO - Downloading blobuploader-1.2.4.tar.gz 12:55:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:55:08 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:55:08 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 12:55:08 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 12:55:08 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 12:55:08 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 12:55:08 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 12:55:08 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 12:55:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:55:08 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:55:08 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 12:55:08 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 12:55:08 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 12:55:08 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 12:55:08 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 12:55:08 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 12:55:08 INFO - Downloading docopt-0.6.1.tar.gz 12:55:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:55:09 INFO - Installing collected packages: blobuploader, requests, docopt 12:55:09 INFO - Running setup.py install for blobuploader 12:55:09 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:55:09 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:55:09 INFO - Running setup.py install for requests 12:55:09 INFO - Running setup.py install for docopt 12:55:09 INFO - Successfully installed blobuploader requests docopt 12:55:09 INFO - Cleaning up... 12:55:09 INFO - Return code: 0 12:55:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:55:09 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')]} 12:55:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:55:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:55:09 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')]} 12:55:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:55:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:55:09 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 0x7f7657677f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b178a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2be4ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2be1f60>, '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 0x2be2d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2be31d0>, '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': 'cbe409c6de289db6ac707de3000001d4-1474401277.584668-1243232512', '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 12:55:09 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 12:55:09 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 12:55:09 INFO - Using env: {'DISPLAY': ':0', 12:55:09 INFO - 'HOME': '/home/cltbld', 12:55:09 INFO - 'LANG': 'en_US.UTF-8', 12:55:09 INFO - 'LANGUAGE': 'en_US:en', 12:55:09 INFO - 'LOGNAME': 'cltbld', 12:55:09 INFO - 'MAIL': '/var/mail/cltbld', 12:55:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:55:09 INFO - 'MOZ_NO_REMOTE': '1', 12:55:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:55:09 INFO - 'NO_EM_RESTART': '1', 12:55:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:55:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:55:09 INFO - 'PWD': '/builds/slave/test', 12:55:09 INFO - 'SHELL': '/bin/bash', 12:55:09 INFO - 'SHLVL': '1', 12:55:09 INFO - 'TERM': 'linux', 12:55:09 INFO - 'TMOUT': '86400', 12:55:09 INFO - 'USER': 'cltbld', 12:55:09 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1474401277.584668-1243232512', 12:55:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:55:09 INFO - '_': '/tools/buildbot/bin/python'} 12:55:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:55:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:55:09 INFO - Running setup.py (path:/tmp/pip-Z6sVNu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:55:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:55:09 INFO - Running setup.py (path:/tmp/pip-iZ89xV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:55:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:55:10 INFO - Running setup.py (path:/tmp/pip-J_URcp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:55:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:55:10 INFO - Running setup.py (path:/tmp/pip-Q1NNV5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:55:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:55:10 INFO - Running setup.py (path:/tmp/pip-zbMOgO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:55:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:55:10 INFO - Running setup.py (path:/tmp/pip-_OnzEh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:55:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:55:10 INFO - Running setup.py (path:/tmp/pip-RkOVZO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:55:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:55:10 INFO - Running setup.py (path:/tmp/pip-bCjKTH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:55:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:55:10 INFO - Running setup.py (path:/tmp/pip-r4RT2q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:55:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:55:10 INFO - Running setup.py (path:/tmp/pip-0X4s0m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:55:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:55:10 INFO - Running setup.py (path:/tmp/pip-p5e_Ih-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:55:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:55:10 INFO - Running setup.py (path:/tmp/pip-hybkYL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:55:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:55:10 INFO - Running setup.py (path:/tmp/pip-ccqh_7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:55:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:55:10 INFO - Running setup.py (path:/tmp/pip-SyKKDi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:55:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:55:10 INFO - Running setup.py (path:/tmp/pip-Zlwn6E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:55:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:55:10 INFO - Running setup.py (path:/tmp/pip-1HPyst-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:55:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:55:11 INFO - Running setup.py (path:/tmp/pip-xmB5nG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:55:11 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:55:11 INFO - Running setup.py install for manifestparser 12:55:11 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:55:11 INFO - Running setup.py install for mozcrash 12:55:11 INFO - Running setup.py install for mozdebug 12:55:11 INFO - Running setup.py install for mozdevice 12:55:11 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:55:11 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:55:11 INFO - Running setup.py install for mozfile 12:55:11 INFO - Running setup.py install for mozhttpd 12:55:11 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:55:11 INFO - Running setup.py install for mozinfo 12:55:11 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:55:11 INFO - Running setup.py install for mozInstall 12:55:11 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:55:11 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:55:11 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:55:11 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:55:11 INFO - Running setup.py install for mozleak 12:55:11 INFO - Running setup.py install for mozlog 12:55:12 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:55:12 INFO - Running setup.py install for moznetwork 12:55:12 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:55:12 INFO - Running setup.py install for mozprocess 12:55:12 INFO - Running setup.py install for mozprofile 12:55:12 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:55:12 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:55:12 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:55:12 INFO - Running setup.py install for mozrunner 12:55:12 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:55:12 INFO - Running setup.py install for mozscreenshot 12:55:12 INFO - Running setup.py install for moztest 12:55:12 INFO - Running setup.py install for mozversion 12:55:12 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:55:12 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:55:12 INFO - Cleaning up... 12:55:12 INFO - Return code: 0 12:55:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:55:12 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')]} 12:55:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:55:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:55:12 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')]} 12:55:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:55:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:55:12 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 0x7f7657677f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b178a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2be4ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2be1f60>, '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 0x2be2d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2be31d0>, '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': 'cbe409c6de289db6ac707de3000001d4-1474401277.584668-1243232512', '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 12:55:12 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 12:55:12 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 12:55:12 INFO - Using env: {'DISPLAY': ':0', 12:55:12 INFO - 'HOME': '/home/cltbld', 12:55:12 INFO - 'LANG': 'en_US.UTF-8', 12:55:12 INFO - 'LANGUAGE': 'en_US:en', 12:55:12 INFO - 'LOGNAME': 'cltbld', 12:55:12 INFO - 'MAIL': '/var/mail/cltbld', 12:55:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:55:12 INFO - 'MOZ_NO_REMOTE': '1', 12:55:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:55:12 INFO - 'NO_EM_RESTART': '1', 12:55:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:55:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:55:12 INFO - 'PWD': '/builds/slave/test', 12:55:12 INFO - 'SHELL': '/bin/bash', 12:55:12 INFO - 'SHLVL': '1', 12:55:12 INFO - 'TERM': 'linux', 12:55:12 INFO - 'TMOUT': '86400', 12:55:12 INFO - 'USER': 'cltbld', 12:55:12 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1474401277.584668-1243232512', 12:55:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:55:12 INFO - '_': '/tools/buildbot/bin/python'} 12:55:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:55:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:55:13 INFO - Running setup.py (path:/tmp/pip-sNbFoO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:55:13 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)) 12:55:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:55:13 INFO - Running setup.py (path:/tmp/pip-curkC1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:55:13 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)) 12:55:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:55:13 INFO - Running setup.py (path:/tmp/pip-xiIjdm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:55:13 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)) 12:55:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:55:13 INFO - Running setup.py (path:/tmp/pip-dXhxMR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:55:13 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)) 12:55:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:55:13 INFO - Running setup.py (path:/tmp/pip-fKzWZt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:55:13 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)) 12:55:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:55:13 INFO - Running setup.py (path:/tmp/pip-9XemLh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:55:13 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)) 12:55:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:55:13 INFO - Running setup.py (path:/tmp/pip-D6O0Rs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:55:13 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)) 12:55:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:55:13 INFO - Running setup.py (path:/tmp/pip-w8PAqM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:55:13 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)) 12:55:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:55:13 INFO - Running setup.py (path:/tmp/pip-dNwyKi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:55:13 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)) 12:55:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:55:13 INFO - Running setup.py (path:/tmp/pip-viZVIC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:55:13 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)) 12:55:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:55:13 INFO - Running setup.py (path:/tmp/pip-HNEEoD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:55:13 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)) 12:55:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:55:13 INFO - Running setup.py (path:/tmp/pip-PGiGhu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:55:13 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)) 12:55:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:55:13 INFO - Running setup.py (path:/tmp/pip-94I43K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:55:14 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)) 12:55:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:55:14 INFO - Running setup.py (path:/tmp/pip-TiOC0P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:55:14 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)) 12:55:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:55:14 INFO - Running setup.py (path:/tmp/pip-3hjaUi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:55:14 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)) 12:55:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:55:14 INFO - Running setup.py (path:/tmp/pip-yIb4rD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:55:14 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)) 12:55:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:55:14 INFO - Running setup.py (path:/tmp/pip-pDAYFJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:55:14 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)) 12:55:14 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)) 12:55:14 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)) 12:55:14 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)) 12:55:14 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)) 12:55:14 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)) 12:55:14 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:55:14 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 12:55:14 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 12:55:14 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 12:55:14 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 12:55:14 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 12:55:14 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 12:55:16 INFO - Downloading blessings-1.6.tar.gz 12:55:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:55:16 INFO - Installing collected packages: blessings 12:55:16 INFO - Running setup.py install for blessings 12:55:16 INFO - Successfully installed blessings 12:55:16 INFO - Cleaning up... 12:55:16 INFO - Return code: 0 12:55:16 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:55:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:55:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:55:16 INFO - Reading from file tmpfile_stdout 12:55:16 INFO - Current package versions: 12:55:16 INFO - argparse == 1.2.1 12:55:16 INFO - blessings == 1.6 12:55:16 INFO - blobuploader == 1.2.4 12:55:16 INFO - docopt == 0.6.1 12:55:16 INFO - functools32 == 3.2.3-2 12:55:16 INFO - jsonschema == 2.5.1 12:55:16 INFO - manifestparser == 1.1 12:55:16 INFO - mozInstall == 1.12 12:55:16 INFO - mozcrash == 0.17 12:55:16 INFO - mozdebug == 0.1 12:55:16 INFO - mozdevice == 0.48 12:55:16 INFO - mozfile == 1.2 12:55:16 INFO - mozhttpd == 0.7 12:55:16 INFO - mozinfo == 0.9 12:55:16 INFO - mozleak == 0.1 12:55:16 INFO - mozlog == 3.4 12:55:16 INFO - moznetwork == 0.27 12:55:16 INFO - mozprocess == 0.23 12:55:16 INFO - mozprofile == 0.28 12:55:16 INFO - mozrunner == 6.12 12:55:16 INFO - mozscreenshot == 0.1 12:55:16 INFO - mozsystemmonitor == 0.3 12:55:16 INFO - moztest == 0.7 12:55:16 INFO - mozversion == 1.4 12:55:16 INFO - psutil == 3.1.1 12:55:16 INFO - requests == 1.2.3 12:55:16 INFO - wsgiref == 0.1.2 12:55:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:55:16 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')]} 12:55:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:55:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:55:16 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')]} 12:55:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:55:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:55:16 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 0x7f7657677f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b178a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2be4ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2be1f60>, '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 0x2be2d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2be31d0>, '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': 'cbe409c6de289db6ac707de3000001d4-1474401277.584668-1243232512', '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 12:55:16 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 12:55:16 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 12:55:16 INFO - Using env: {'DISPLAY': ':0', 12:55:16 INFO - 'HOME': '/home/cltbld', 12:55:16 INFO - 'LANG': 'en_US.UTF-8', 12:55:16 INFO - 'LANGUAGE': 'en_US:en', 12:55:16 INFO - 'LOGNAME': 'cltbld', 12:55:16 INFO - 'MAIL': '/var/mail/cltbld', 12:55:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:55:16 INFO - 'MOZ_NO_REMOTE': '1', 12:55:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:55:16 INFO - 'NO_EM_RESTART': '1', 12:55:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:55:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:55:16 INFO - 'PWD': '/builds/slave/test', 12:55:16 INFO - 'SHELL': '/bin/bash', 12:55:16 INFO - 'SHLVL': '1', 12:55:16 INFO - 'TERM': 'linux', 12:55:16 INFO - 'TMOUT': '86400', 12:55:16 INFO - 'USER': 'cltbld', 12:55:16 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1474401277.584668-1243232512', 12:55:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:55:16 INFO - '_': '/tools/buildbot/bin/python'} 12:55:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:55:16 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)) 12:55:16 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)) 12:55:16 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)) 12:55:16 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)) 12:55:16 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)) 12:55:16 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)) 12:55:16 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)) 12:55:16 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)) 12:55:16 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)) 12:55:16 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)) 12:55:16 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)) 12:55:16 INFO - Cleaning up... 12:55:16 INFO - Return code: 0 12:55:16 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:55:16 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')]} 12:55:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:55:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:55:16 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')]} 12:55:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:55:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:55:16 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 0x7f7657677f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b178a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2be4ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2be1f60>, '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 0x2be2d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2be31d0>, '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': 'cbe409c6de289db6ac707de3000001d4-1474401277.584668-1243232512', '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 12:55:16 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 12:55:16 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 12:55:16 INFO - Using env: {'DISPLAY': ':0', 12:55:16 INFO - 'HOME': '/home/cltbld', 12:55:16 INFO - 'LANG': 'en_US.UTF-8', 12:55:16 INFO - 'LANGUAGE': 'en_US:en', 12:55:16 INFO - 'LOGNAME': 'cltbld', 12:55:16 INFO - 'MAIL': '/var/mail/cltbld', 12:55:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:55:16 INFO - 'MOZ_NO_REMOTE': '1', 12:55:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:55:16 INFO - 'NO_EM_RESTART': '1', 12:55:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:55:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:55:16 INFO - 'PWD': '/builds/slave/test', 12:55:16 INFO - 'SHELL': '/bin/bash', 12:55:16 INFO - 'SHLVL': '1', 12:55:16 INFO - 'TERM': 'linux', 12:55:16 INFO - 'TMOUT': '86400', 12:55:16 INFO - 'USER': 'cltbld', 12:55:16 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1474401277.584668-1243232512', 12:55:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:55:16 INFO - '_': '/tools/buildbot/bin/python'} 12:55:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:55:17 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:55:17 INFO - Cleaning up... 12:55:17 INFO - Return code: 0 12:55:17 INFO - Running post-action listener: _resource_record_post_action 12:55:17 INFO - Running post-action listener: _start_resource_monitoring 12:55:17 INFO - Starting resource monitoring. 12:55:17 INFO - [mozharness: 2016-09-20 19:55:17.163575Z] Finished create-virtualenv step (success) 12:55:17 INFO - [mozharness: 2016-09-20 19:55:17.164032Z] Running install step. 12:55:17 INFO - Running pre-action listener: _resource_record_pre_action 12:55:17 INFO - Running main action method: install 12:55:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:55:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:55:17 INFO - Reading from file tmpfile_stdout 12:55:17 INFO - Detecting whether we're running mozinstall >=1.0... 12:55:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:55:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:55:17 INFO - Reading from file tmpfile_stdout 12:55:17 INFO - Output received: 12:55:17 INFO - Usage: mozinstall [options] installer 12:55:17 INFO - Options: 12:55:17 INFO - -h, --help show this help message and exit 12:55:17 INFO - -d DEST, --destination=DEST 12:55:17 INFO - Directory to install application into. [default: 12:55:17 INFO - "/builds/slave/test"] 12:55:17 INFO - --app=APP Application being installed. [default: firefox] 12:55:17 INFO - mkdir: /builds/slave/test/build/application 12:55:17 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'] 12:55:17 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 12:55:33 INFO - Reading from file tmpfile_stdout 12:55:33 INFO - Output received: 12:55:33 INFO - /builds/slave/test/build/application/firefox/firefox 12:55:33 INFO - Running post-action listener: _resource_record_post_action 12:55:33 INFO - [mozharness: 2016-09-20 19:55:33.392796Z] Finished install step (success) 12:55:33 INFO - [mozharness: 2016-09-20 19:55:33.393051Z] Running run-tests step. 12:55:33 INFO - Running pre-action listener: _resource_record_pre_action 12:55:33 INFO - Running main action method: run_tests 12:55:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:55:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:55:33 INFO - Python 2.7.3 12:55:33 INFO - Return code: 0 12:55:33 INFO - grabbing minidump binary from tooltool 12:55: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')]} 12:55:33 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 0x2be1f60>, '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 0x2be2d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2be31d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:55:33 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 12:55:33 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 12:55:33 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 12:55:33 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:55:33 INFO - Return code: 0 12:55:33 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 12:55:33 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:55:33 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:55:33 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 12:55:33 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:55:33 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/G2kD2NPnS2OGw3At7WAMQg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-054', '--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 12:55:33 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/G2kD2NPnS2OGw3At7WAMQg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-054 --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 12:55:33 INFO - Using env: {'DISPLAY': ':0', 12:55:33 INFO - 'HOME': '/home/cltbld', 12:55:33 INFO - 'LANG': 'en_US.UTF-8', 12:55:33 INFO - 'LANGUAGE': 'en_US:en', 12:55:33 INFO - 'LOGNAME': 'cltbld', 12:55:33 INFO - 'MAIL': '/var/mail/cltbld', 12:55:33 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:55:33 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:55:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:55:33 INFO - 'MOZ_NO_REMOTE': '1', 12:55:33 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:55:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:55:33 INFO - 'NO_EM_RESTART': '1', 12:55:33 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:55:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:55:33 INFO - 'PWD': '/builds/slave/test', 12:55:33 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:55:33 INFO - 'SHELL': '/bin/bash', 12:55:33 INFO - 'SHLVL': '1', 12:55:33 INFO - 'TERM': 'linux', 12:55:33 INFO - 'TMOUT': '86400', 12:55:33 INFO - 'USER': 'cltbld', 12:55:33 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1474401277.584668-1243232512', 12:55:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:55:33 INFO - '_': '/tools/buildbot/bin/python'} 12:55:33 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/G2kD2NPnS2OGw3At7WAMQg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-054', '--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 12:55:33 INFO - mozversion application_buildid: 20160920100526 12:55:33 INFO - mozversion application_changeset: e7108b1c6731f1456ee875ed32e469c5eb7df1c1 12:55:33 INFO - mozversion application_display_name: FirefoxDeveloperEdition 12:55:33 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:55:33 INFO - mozversion application_name: Firefox 12:55:33 INFO - mozversion application_remotingname: firefox-dev 12:55:33 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 12:55:33 INFO - mozversion application_vendor: Mozilla 12:55:33 INFO - mozversion application_version: 51.0a2 12:55:33 INFO - mozversion platform_buildid: 20160920100526 12:55:33 INFO - mozversion platform_changeset: e7108b1c6731f1456ee875ed32e469c5eb7df1c1 12:55:33 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 12:55:33 INFO - mozversion platform_version: 51.0a2 12:55:33 INFO - using testdate: 1474401333 12:55:33 INFO - actual date: 1474401333 12:55:33 INFO - starting webserver on 'localhost:41824' 12:55:33 INFO - SUITE-START | Running 2 tests 12:55:33 INFO - TEST-START | dromaeo_css 12:55:33 INFO - operating with platform_type : linux_ 12:55:33 INFO - Initialising browser for dromaeo_css test... 12:55:33 INFO - TEST-INFO | started process 6439 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmplWFMjb/profile http://localhost:41824/getInfo.html) 12:55:37 INFO - PROCESS | 6439 | __metrics Screen width/height:1600/1200 12:55:37 INFO - PROCESS | 6439 | colorDepth:24 12:55:37 INFO - PROCESS | 6439 | Browser inner width/height: 1024/697 12:55:37 INFO - PROCESS | 6439 | __metrics 12:55:38 INFO - PROCESS | 6439 | 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" 12:55:38 INFO - PROCESS | 6439 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:55:39 INFO - PROCESS | 6439 | console.error: PushService: 12:55:39 INFO - PROCESS | 6439 | stateChangeProcessEnqueue: Error transitioning state 12:55:39 INFO - PROCESS | 6439 | UnknownError 12:55:39 INFO - TEST-INFO | 6439: exit 0 12:55:39 INFO - Browser initialized. 12:55:39 INFO - Running cycle 1/1 for dromaeo_css test... 12:55:39 INFO - TEST-INFO | started process 6532 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmplWFMjb/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:55:51 INFO - PROCESS | 6532 | RSS: Main: 144015360 12:55:51 INFO - PROCESS | 6532 | 12:56:02 INFO - PROCESS | 6532 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 12:56:10 INFO - PROCESS | 6532 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 12:56:12 INFO - PROCESS | 6532 | [GFX1-]: Refresh driver waiting for the compositor for 1.01623 seconds. 12:56:23 INFO - PROCESS | 6532 | [GFX1-]: Refresh driver waiting for the compositor for 1.16688 seconds. 12:56:42 INFO - PROCESS | 6532 | 1474401402626 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] 12:56:45 INFO - PROCESS | 6532 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 12:56:47 INFO - PROCESS | 6532 | [GFX1-]: Refresh driver waiting for the compositor for 1.01653 seconds. 12:56:49 INFO - PROCESS | 6532 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 12:56:58 INFO - PROCESS | 6532 | [GFX1-]: Refresh driver waiting for the compositor for 1.03316 seconds. 12:57:07 INFO - PROCESS | 6532 | [GFX1-]: Refresh driver waiting for the compositor for 1.06732 seconds. 12:57:16 INFO - PROCESS | 6532 | [GFX1-]: Refresh driver waiting for the compositor for 1.01658 seconds. 12:57:24 INFO - PROCESS | 6532 | [GFX1-]: Refresh driver waiting for the compositor for 1.0003 seconds. 12:57:36 INFO - PROCESS | 6532 | [GFX1-]: Refresh driver waiting for the compositor for 1.06665 seconds. 12:57:39 INFO - PROCESS | 6532 | [GFX1-]: Refresh driver waiting for the compositor for 1.00029 seconds. 12:57:41 INFO - PROCESS | 6532 | [GFX1-]: Refresh driver waiting for the compositor for 1.01631 seconds. 12:57:44 INFO - PROCESS | 6532 | [GFX1-]: Refresh driver waiting for the compositor for 1.15032 seconds. 12:57:58 INFO - PROCESS | 6532 | [GFX1-]: Refresh driver waiting for the compositor for 1.00061 seconds. 12:58:10 INFO - PROCESS | 6532 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 12:58:16 INFO - PROCESS | 6532 | [GFX1-]: Refresh driver waiting for the compositor for 1.01719 seconds. 12:58:26 INFO - PROCESS | 6532 | [GFX1-]: Refresh driver waiting for the compositor for 1.01664 seconds. 12:58:28 INFO - PROCESS | 6532 | [GFX1-]: Refresh driver waiting for the compositor for 1.00018 seconds. 12:58:32 INFO - PROCESS | 6532 | [GFX1-]: Refresh driver waiting for the compositor for 1.00047 seconds. 12:58:39 INFO - PROCESS | 6532 | [GFX1-]: Refresh driver waiting for the compositor for 1.01621 seconds. 12:58:51 INFO - PROCESS | 6532 | Cycle 1(1): loaded http://localhost:41824/tests/dromaeo/cssquery-dojo.html (next: http://localhost:41824/tests/dromaeo/cssquery-ext.html) 12:58:52 INFO - PROCESS | 6532 | RSS: Main: 233844736 12:58:52 INFO - PROCESS | 6532 | 12:58:56 INFO - PROCESS | 6532 | [GFX1-]: Refresh driver waiting for the compositor for 1.0161 seconds. 12:58:57 INFO - PROCESS | 6532 | [GFX1-]: Refresh driver waiting for the compositor for 1.00009 seconds. 12:59:13 INFO - PROCESS | 6532 | [GFX1-]: Refresh driver waiting for the compositor for 1.01696 seconds. 12:59:39 INFO - PROCESS | 6532 | [GFX1-]: Refresh driver waiting for the compositor for 1.00051 seconds. 12:59:45 INFO - PROCESS | 6532 | [GFX1-]: Refresh driver waiting for the compositor for 1.00048 seconds. 13:00:03 INFO - PROCESS | 6532 | [GFX1-]: Refresh driver waiting for the compositor for 1.01699 seconds. 13:00:30 INFO - PROCESS | 6532 | [GFX1-]: Refresh driver waiting for the compositor for 1.00072 seconds. 13:00:32 INFO - PROCESS | 6532 | [GFX1-]: Refresh driver waiting for the compositor for 1.00061 seconds. 13:00:46 INFO - PROCESS | 6532 | [GFX1-]: Refresh driver waiting for the compositor for 1.00047 seconds. 13:00:52 INFO - PROCESS | 6532 | [GFX1-]: Refresh driver waiting for the compositor for 1.00043 seconds. 13:01:18 INFO - PROCESS | 6532 | [GFX1-]: Refresh driver waiting for the compositor for 1.01589 seconds. 13:01:28 INFO - PROCESS | 6532 | [GFX1-]: Refresh driver waiting for the compositor for 1.00036 seconds. 13:01:49 INFO - PROCESS | 6532 | [GFX1-]: Refresh driver waiting for the compositor for 1.01645 seconds. 13:01:53 INFO - PROCESS | 6532 | [GFX1-]: Refresh driver waiting for the compositor for 1.00061 seconds. 13:01:55 INFO - PROCESS | 6532 | Cycle 1(1): loaded http://localhost:41824/tests/dromaeo/cssquery-ext.html (next: http://localhost:41824/tests/dromaeo/cssquery-jquery.html) 13:01:55 INFO - PROCESS | 6532 | RSS: Main: 234033152 13:01:55 INFO - PROCESS | 6532 | 13:01:59 INFO - PROCESS | 6532 | [GFX1-]: Refresh driver waiting for the compositor for 1.04983 seconds. 13:02:02 INFO - PROCESS | 6532 | [GFX1-]: Refresh driver waiting for the compositor for 1.00059 seconds. 13:02:08 INFO - PROCESS | 6532 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 13:02:10 INFO - PROCESS | 6532 | [GFX1-]: Refresh driver waiting for the compositor for 1.00035 seconds. 13:02:30 INFO - PROCESS | 6532 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 13:02:35 INFO - PROCESS | 6532 | [GFX1-]: Refresh driver waiting for the compositor for 1.01736 seconds. 13:02:38 INFO - PROCESS | 6532 | [GFX1-]: Refresh driver waiting for the compositor for 1.00059 seconds. 13:03:26 INFO - PROCESS | 6532 | [GFX1-]: Refresh driver waiting for the compositor for 1.00066 seconds. 13:03:28 INFO - PROCESS | 6532 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 13:03:36 INFO - PROCESS | 6532 | [GFX1-]: Refresh driver waiting for the compositor for 1.00073 seconds. 13:03:41 INFO - PROCESS | 6532 | [GFX1-]: Refresh driver waiting for the compositor for 1.00033 seconds. 13:03:45 INFO - PROCESS | 6532 | [GFX1-]: Refresh driver waiting for the compositor for 1.00049 seconds. 13:04:13 INFO - PROCESS | 6532 | [GFX1-]: Refresh driver waiting for the compositor for 1.00055 seconds. 13:04:35 INFO - PROCESS | 6532 | [GFX1-]: Refresh driver waiting for the compositor for 1.00045 seconds. 13:04:57 INFO - PROCESS | 6532 | Cycle 1(1): loaded http://localhost:41824/tests/dromaeo/cssquery-jquery.html (next: http://localhost:41824/tests/dromaeo/cssquery-mootools.html) 13:04:58 INFO - PROCESS | 6532 | RSS: Main: 175902720 13:04:58 INFO - PROCESS | 6532 | 13:05:01 INFO - PROCESS | 6532 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 13:05:03 INFO - PROCESS | 6532 | [GFX1-]: Refresh driver waiting for the compositor for 1.03328 seconds. 13:05:06 INFO - PROCESS | 6532 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 13:05:30 INFO - PROCESS | 6532 | [GFX1-]: Refresh driver waiting for the compositor for 1.00034 seconds. 13:05:45 INFO - PROCESS | 6532 | [GFX1-]: Refresh driver waiting for the compositor for 1.00013 seconds. 13:05:49 INFO - PROCESS | 6532 | [GFX1-]: Refresh driver waiting for the compositor for 1.00017 seconds. 13:05:51 INFO - PROCESS | 6532 | [GFX1-]: Refresh driver waiting for the compositor for 1.00041 seconds. 13:06:11 INFO - PROCESS | 6532 | [GFX1-]: Refresh driver waiting for the compositor for 1.00037 seconds. 13:06:37 INFO - PROCESS | 6532 | [GFX1-]: Refresh driver waiting for the compositor for 1.00079 seconds. 13:06:53 INFO - PROCESS | 6532 | [GFX1-]: Refresh driver waiting for the compositor for 1.00014 seconds. 13:07:15 INFO - PROCESS | 6532 | [GFX1-]: Refresh driver waiting for the compositor for 1.00041 seconds. 13:07:29 INFO - PROCESS | 6532 | [GFX1-]: Refresh driver waiting for the compositor for 1.00045 seconds. 13:07:31 INFO - PROCESS | 6532 | [GFX1-]: Refresh driver waiting for the compositor for 1.00033 seconds. 13:07:35 INFO - PROCESS | 6532 | [GFX1-]: Refresh driver waiting for the compositor for 1.01658 seconds. 13:07:54 INFO - PROCESS | 6532 | [GFX1-]: Refresh driver waiting for the compositor for 1.00022 seconds. 13:07:56 INFO - PROCESS | 6532 | [GFX1-]: Refresh driver waiting for the compositor for 1.00037 seconds. 13:07:58 INFO - PROCESS | 6532 | [GFX1-]: Refresh driver waiting for the compositor for 1.00048 seconds. 13:08:00 INFO - PROCESS | 6532 | Cycle 1(1): loaded http://localhost:41824/tests/dromaeo/cssquery-mootools.html (next: http://localhost:41824/tests/dromaeo/cssquery-prototype.html) 13:08:00 INFO - PROCESS | 6532 | RSS: Main: 216252416 13:08:00 INFO - PROCESS | 6532 | 13:08:04 INFO - PROCESS | 6532 | [GFX1-]: Refresh driver waiting for the compositor for 1.03388 seconds. 13:08:23 INFO - PROCESS | 6532 | [GFX1-]: Refresh driver waiting for the compositor for 1.01652 seconds. 13:08:25 INFO - PROCESS | 6532 | [GFX1-]: Refresh driver waiting for the compositor for 1.01735 seconds. 13:08:33 INFO - PROCESS | 6532 | [GFX1-]: Refresh driver waiting for the compositor for 1.00025 seconds. 13:08:35 INFO - PROCESS | 6532 | [GFX1-]: Refresh driver waiting for the compositor for 1.00048 seconds. 13:08:47 INFO - PROCESS | 6532 | [GFX1-]: Refresh driver waiting for the compositor for 1.00069 seconds. 13:08:49 INFO - PROCESS | 6532 | [GFX1-]: Refresh driver waiting for the compositor for 1.00024 seconds. 13:09:12 INFO - PROCESS | 6532 | [GFX1-]: Refresh driver waiting for the compositor for 1.04993 seconds. 13:09:22 INFO - PROCESS | 6532 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 13:09:38 INFO - PROCESS | 6532 | [GFX1-]: Refresh driver waiting for the compositor for 1.01656 seconds. 13:09:46 INFO - PROCESS | 6532 | [GFX1-]: Refresh driver waiting for the compositor for 1.00038 seconds. 13:09:54 INFO - PROCESS | 6532 | [GFX1-]: Refresh driver waiting for the compositor for 1.0005 seconds. 13:09:58 INFO - PROCESS | 6532 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 13:10:03 INFO - PROCESS | 6532 | [GFX1-]: Refresh driver waiting for the compositor for 1.01659 seconds. 13:10:06 INFO - PROCESS | 6532 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 13:10:28 INFO - PROCESS | 6532 | [GFX1-]: Refresh driver waiting for the compositor for 1.01665 seconds. 13:10:31 INFO - PROCESS | 6532 | [GFX1-]: Refresh driver waiting for the compositor for 1.00018 seconds. 13:10:47 INFO - PROCESS | 6532 | [GFX1-]: Refresh driver waiting for the compositor for 1.00044 seconds. 13:11:03 INFO - PROCESS | 6532 | Cycle 1(1): loaded http://localhost:41824/tests/dromaeo/cssquery-prototype.html (next: http://localhost:41824/tests/dromaeo/cssquery-yui.html) 13:11:04 INFO - PROCESS | 6532 | RSS: Main: 176087040 13:11:04 INFO - PROCESS | 6532 | 13:11:08 INFO - PROCESS | 6532 | [GFX1-]: Refresh driver waiting for the compositor for 1.01677 seconds. 13:11:10 INFO - PROCESS | 6532 | [GFX1-]: Refresh driver waiting for the compositor for 1.01646 seconds. 13:11:20 INFO - PROCESS | 6532 | [GFX1-]: Refresh driver waiting for the compositor for 1.01653 seconds. 13:11:28 INFO - PROCESS | 6532 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 13:11:52 INFO - PROCESS | 6532 | [GFX1-]: Refresh driver waiting for the compositor for 1.00018 seconds. 13:12:09 INFO - PROCESS | 6532 | [GFX1-]: Refresh driver waiting for the compositor for 1.00016 seconds. 13:12:15 INFO - PROCESS | 6532 | [GFX1-]: Refresh driver waiting for the compositor for 1.00051 seconds. 13:12:27 INFO - PROCESS | 6532 | [GFX1-]: Refresh driver waiting for the compositor for 1.00012 seconds. 13:13:03 INFO - PROCESS | 6532 | [GFX1-]: Refresh driver waiting for the compositor for 1.00014 seconds. 13:13:11 INFO - PROCESS | 6532 | [GFX1-]: Refresh driver waiting for the compositor for 1.00019 seconds. 13:13:23 INFO - PROCESS | 6532 | [GFX1-]: Refresh driver waiting for the compositor for 1.00027 seconds. 13:13:42 INFO - PROCESS | 6532 | [GFX1-]: Refresh driver waiting for the compositor for 1.01714 seconds. 13:13:50 INFO - PROCESS | 6532 | [GFX1-]: Refresh driver waiting for the compositor for 1.03377 seconds. 13:14:06 INFO - PROCESS | 6532 | Cycle 1(1): loaded http://localhost:41824/tests/dromaeo/cssquery-yui.html (next: http://localhost:41824/tests/dromaeo/cssquery-dojo.html) 13:14:06 INFO - PROCESS | 6532 | RSS: Main: 264605696 13:14:06 INFO - PROCESS | 6532 | 13:14:06 INFO - PROCESS | 6532 | __start_tp_report 13:14:06 INFO - PROCESS | 6532 | _x_x_mozilla_page_load 13:14:06 INFO - PROCESS | 6532 | _x_x_mozilla_page_load_details 13:14:06 INFO - PROCESS | 6532 | |i|pagename|runs| 13:14:06 INFO - PROCESS | 6532 | |0;dojo.html;3079.6612372639006;3113.2216945763544;3129.9217519562008;3153.6057992750907;3155.6055493063354;5559.666420014802;5655;5661.830145095653;5677.772889084432;5709.429057094294;5590.720463976805;5676.148577713345;5679.659220446766;5702.372738998707;5730.713464326788;5113.948860511394;5142.61430392722;5170.276161337416;5171.379434467869;5197.688138711671;5004.674696144739;5155.432902380735;5182.041322355376;5185.040767458014;5185.170372740362;4987.276844857493;5022.995400919813;5062;5102.387713474388;5113.821016264432;5039.521245481692;5072.974635126838;5077.669951453162;5141.871453213662;5164.896702065976;4964.379452568429;5044.218146187347;5073.974630126826;5094.414142373637;5095.414027386824;5323.201519772065;5375.6774593524515;5434.130539113723;5442.564594832404;5485.862853428696;5826.388229235911;5840.415958404168;5843.941560584425;5933.169356290123;5934.97032514839;5962.433568810956;5964.165016897595;5971.134185543116;5971.970140149315;5981.820545383645;5417.106177480711;5453.590980676465;5457.617966742329;5479.863003424946;5483.917741233885;6005.819825405245;6013.30846952606;6016.308124565643;6017.247844019497;6029.427204415573;5743.1385292206505;5758.913616295759;5803.8549036274435;5804.970975145097;5805.36141024487;5602.887942241132;5640.097584386478;5784.843031393738;5815.651060936358;5845.327787304429;5863.5309175265875;5869.178315035899;5883.14694369314;5900.203472531153;5902.763889444374;5841;5892.793752218652;5895.115732640138;5904.261967254093;5907.084401917709;4399.758013309299;4446.088551846864;4492.73043617384;4540.409746732904;4542.477615074243;3975.741576797499;4059.3505039193587;4062.5531191568903;4074.1851629673592;4124.237016152021;5722.570807189478;5724.227229323988;5726.0838265877255;5728.232715680398;5730.684812335361;5807.5063619591965;6042.546808989343;6049.818505444844;6057.303410107894;6072.969635151796;5564.248826408464;5622.662640241599;5663.518600918886;5665.886682266331;5754.251947246831;5169.172932330809;5205.427402985669;5210.713410762369;5214.165733482625;5216.426193118754;6143.201383820075;6207.34822843604;6234.251889773256;6237.157983672237;6239.251289845248;5845.210896528914;5872.148538461985;5876.500497457764;5889.322727886348;5905.527557795452;6138.2327209097075;6138.882085680433;6143.266246520256;6147.047207682816;6153.938460615515;5812.505936995402;5815.156802263564;5817.941820581909;5825.737841797045;5864.706764661835;5721.084626459747;5728.226689396878;5869.35437101927;5906.616069955439;5930.52555795544;5835.299764028344;5857.765689372377;5860.267466566679;5886.05823068307;5898.292204935436;5832.679202643895;5855.824325270249;5859.531237501075;5861.824145275648;5863.032599621015;5579.13523403873;5593.4126916674795;5729.742161602821;5807.912881306868;5818.185454036523;5863;5912.408759124053;5927.792527261698;5945.791897283574;5952.970235148797;5787.189793428839;5792.9710351447975;5799.536037116936;5808.82573522795;5811.709414529172 13:14:06 INFO - PROCESS | 6532 | |1;ext.html;585.8154681275398;601.5247954116247;605.0530919111587;628.2053202698587;629.4020680353665;35425.82287088564;38566.614333856654;45016.549834501646;46002.539974600244;46108.76945615275;47588.76205618967;48078.51921480784;48088.51911480884;48117.759411202984;48210.276845847344;8388.916110838905;9313.161815436593;9461;9465.668701595445;9476.573554190043;8496.83006339873;8794.296456283513;8804.735857924241;10995.890041099567;11314;11525.13561482888;11588.188826782129;11589.072874170046;11591;11593.710157246052;11305.378204198807;11364;11370.317780933088;11378.886211137948;11391.601293954755;11153.275037122561;11202.831957520644;11224.326540407601;11229.213955023151;11230.943845280803;45877.77061114706;45878.31182532265;45977.77011114956;46320.76839615814;46347.536524635;3772.07584141887;3863.343231650626;3896.3181443247317;4090.1615168890307;4359.716618419792;35463.4680479793;35840.462393064125;36219.27561448756;36597;37232.25535489275;4366.978165109186;4396.71421357614;4427.46870375557;4485.932711009337;4571.634269258451;344485;344674;344807;344892;346366.26816866006;38470;39664.2067158661;59232.40767592269;59446.70276648676;59546.70226648839;42260.36609450861;42634.360484592755;42707.57292427098;42729.35905961413;42753.57246427558;16835.15824208809;16938.745918811226;16941.91529042347;16953.067581283147;16963;14602.707945841024;14686;14702.41190352374;14705.411783528752;14715.19066451334;9017.2786177107;9205.539723013822;9246.815063698823;9566.473843938651;9678;3647;3654.3970244909824;3681.9631803682155;3703.2963736889906;3737.719671024684;995.7361299255703;1062.0706881478707;1096.5449338524422;1111.522045520434;1156.3119943633524;34826.12934676653;34979.65020349764;35001;35129.59758111044;35634.10914727153;1197.0543270815958;1223.3332833605707;1236.5424792826711;1249.375312343828;1282.5254655777235;17732.645347093243;18358;18398.17208225633;18485.7227141593;18528.722069168973;571901;579307;579999;580224;580461;64841.67579162168;65380;65510.67244663842;65547;65675.40272180997;1396.84634690184;1435.4904009076627;1436.633658417097;1438.5612388221693;1456.3082535795625;31907.042788716375;33182.5022624656;33465.665343346256;33653.00437723797;33883.661163389035;52335.73832130815;52721.7363913178;53103.734481327345;53104.937901242534;53105;492338;494965;495467;496227;496294;31685.20786980344;31789.68210317867;32493.837530812194;32558.51162232521;32586.185345366557;51328;51597;51994.74002629962;52029;53732;42600.78699606606;42651.57348426476;42698.78650606727;42901;44644.55355446404;44556.77721611371;45109.548904511845;45558.31662525131;45767.54232457633;46424.30363544482;42843;43739.56260437355;44836;44998.77500612476;45016.32475512935;36835.447468288534;38962.80518597389;39819.80090099531;40091.599084009955;43126.78436607797;29511.26221844471;29815.403691926473;31287.217819554695;31563.21091972719;31758.364832702755 13:14:06 INFO - PROCESS | 6532 | |2;jquery.html;392.60150946788997;425.8387329362064;427.1116078556603;441.33358628471007;442.53533789521015;16537.503874883758;19855.801441985594;20867.895660521677;21083.47291317714;21292.680609790867;18680.626387472243;20241;20308.796912030874;20383.592328153427;20460.59078818423;5851.093080572518;5888.5289176865745;6096.69516524172;6104.511639068864;6106.114613381057;5974.611650242742;6018.458338749511;6059.121427393025;6062.787802426916;6062.939370606304;5954.553408494358;5992.161097446361;6024.608400453978;6027.939720602803;6028.668423236721;5839.9124013139835;5969.492593129589;5980.611260268069;5986.431289027535;5988.041913293896;5356.464353564651;5393.2449457076045;5396.973015134939;5643.661380317153;5676.545876329884;23285.650715239284;23402.18092366776;23593.646095308584;23721.88139059311;23740.169094081622;2307.5038866643754;2398.124684490166;2515.220281712675;2520.8361456505268;2523.6971563412326;10789.676309710721;10799.892001080047;10951.123910087173;12041.819372709417;14697.853021469755;1883.8304552590332;1897.8576606754548;1927.787943326233;1950.4246247357064;1992.232990298731;85354;86219;90269.54865225697;91146.5442672789;92225;10767.407792571485;11885;11900.404979750978;12313.56902508408;12986.155899866479;15612.453564125428;30896.38207235843;32288.677113229038;32432.83783581114;32661.836690816395;10563.683089507327;10651.254412191227;10849;10887.292325998786;10954.178436617285;9124.680636177702;9137.77155571116;9345.112214339626;9681.322307438553;9872.111509963994;8856.778580535409;9150.954245228731;9167.954160229156;9178.81642367149;9354.29842761782;3421.9486707699402;3461.7230621550216;3465.8613655454005;3543.2027793746715;3638.7816730996224;978.2320878110648;997.5012493753123;1008.7528555503949;1071.1163290285544;1072.1315734255122;19676.90161549183;19958;20210.19159233614;20512.487187820425;20917.163313467594;993.0714781679009;999.2355847776457;1031.742064483879;1032.6592224565875;1091.1598069486452;10578.735531611617;11214.887851121384;11245.943770281096;11248.831267530994;11533.250338728123;477998;480619;483608;483797;494602;89508.5524572373;90622.54688726514;95702.56446153313;100540;105117;523.8821265215372;542.8924993014285;545.6562365709577;547.2639300141303;550.086855819338;10860.891391085988;10921.56313747473;11214.495347709535;11402.657920262405;11503.137264705014;42129;42239;43402;43608.12783744244;44982.100357992;56703;57846.710766445896;60169.69915150396;60501.69749151226;61424.07863881956;8955.373123881265;9259.814803704023;9397.342186046912;9502.19231365314;9610.66362677303;12807.935960320512;13752.31238438094;13915.234662093682;14155.929220353832;14299.714005720036;6231.22109736283;11241.49413276388;11257.380844053656;12342.44458999365;12412.379381031093;11010.449477525932;11095.944520277348;11372.829407559058;11731.120165987755;11840.940795295968;11685.357305348287;12322.260664360167;12644.30456324911;12764.17032892859;12798.424070917017;9236.30727695439;9289.907100929175;9307.301952353466;9325.114114159143;9583.856242156235;10689.51897164645;11502.367369794742;11861.288322700666;12169;12609.369531523571 13:14:06 INFO - PROCESS | 6532 | |3;mootools.html;1387.653086728318;1540.861322480977;1552.5264794237758;1705.6844483770503;1820.080859166121;8071.87892181617;9080.455172689642;9386.953065234682;9391.577379017936;9677.080677335642;9385.389949653269;9462.81074378512;9464.242860571156;9469.053094690527;9473.478958657272;2449.069353645612;2473.0478765675166;2474.0474917156844;2474.6782918220697;2478.7397323281066;2493.6758221431187;2585.9482810343784;2595.6755405574304;2599.909003184889;2599.922002339933;2499.0878329409725;2499.175272160183;2560.6671132752717;2563.5001174770928;2564.8204625676212;2447.7185123710733;2470.2465747946867;2470.962935555968;2471.3450935502105;2478.925632231036;2449.448874003353;2449.5835707929696;2460.2619214235656;2461.1509029384833;2497.126005897939;9302.441853488812;9311.44131352121;9480.099390557885;9510.90489095114;9511.952440237754;5617.550595952314;5811.302643682743;5814.970925145347;5821.970890145564;5840.415958404125;3631.310051090311;3655.597884232759;3680.4479328100733;3714.1271801126713;3716.0524066362905;1091.2852081886376;1124.2860783402598;1148.6898537394857;1188.6849984754047;1209.0267334795485;12585.622431327074;12680.112392132554;12689.428975696115;12710.999999999907;12753.426095825711;4725.40932383452;4759.190937540627;4861.975690121527;4869.756512174377;5052.34319538465;10324.380537167794;10343.534540945675;10359.274850760374;10360.740981475523;10365.274430789923;5288.391834938954;5299.920501192485;5307.203919412042;5319.707416092075;5319.760610772524;4474.127545128668;4626;4944.60443164546;5116.360454943132;5260.10578201699;3955.8219880105466;3981.5023122109737;4033.979830100831;4053.3717273822604;4125.8968525787095;1166.2652528906735;1173.0732721150366;1178.6169494914186;1192.48723049088;1198.4367347346733;881.7839629290864;970.3304719743354;981.2395393569898;990.3166814897697;1009.8990100989989;6140.8771824563255;6154.19995400604;6307.053941908659;6334.968325158345;6404.039394090923;985.4038306824411;998.8102260570469;1043.2384359417667;1049.5906596427399;1072.0565902006185;4204.474440694913;4250.851220207278;4261.062566235423;4287.228298906164;4353.390525326457;11072.889271107186;11534.307941523535;11544;11545.942270288595;11565.710857228636;11437.59968401102;11473.311601303949;11487.36819474937;11500.25248358854;11516.366599836922;8373.790655233546;8658.826823463496;8774.868376974351;8779.429337093068;8814.382993190544;6268.435840774352;6316.7473301069285;6418.133551970424;6448.613083215022;6573.737050517926;10437.425941573287;10739.731506712395;10744;10763.730906727395;10884.292520986428;10762.08522275584;10987;11088.944555277172;11134.220604557604;11149.052330551669;8038.517688938448;8280.627371768165;8354.958225208835;8459.61931713062;8552.273056789994;9162.541872906462;9222.815543689223;9281.767955801159;9288.767780805534;9298.116678915492;8105.27052565272;8152.021757389151;8267.090620031671;8308.958455207685;8510.48937063778;8152.796180095545;8159.46963447374;8191.8771218430575;8245.670173193244;8289.129641387568;7174.605396703231;7310.122785265802;7386.482946193715;7391.704331826882;7392.112946446246;4972.5276098770555;5234.712090835041;5284.101702710472;5323.973380133075;5350.678959262456;5433.347998240237;5564.860878478071;5576.10782274834;5586.692731899622;5795.739191736467 13:14:06 INFO - PROCESS | 6532 | |4;prototype.html;357.92483578448525;361.88238822382726;369.8040038779447;372.7334955506812;373.31124076079624;5350.384705758829;5493.148561972891;5639.436056394364;5668.024287249191;5671.8298451046485;5329.200619907016;5450.236966824637;5473.370562385327;5545.1682247662875;5563.944360556393;4404.427424434819;4405.6475481961525;4442.133783912131;4445.110977804436;4463.575960283768;4171.8539851105215;4356.629686476637;4391.8682439526865;4429;4442.666799989997;4338.522762496123;4350.412694286279;4367.934480982788;4369.912601747963;4384.408104905829;4364.541723119074;4366.672499562514;4390.473143222834;4401.537838526972;4406.625436837872;3812.6759225465867;3879.4180872869015;3908.7654740715648;3916.275489034509;3925.862594809168;5374.731263436813;5381.623286369956;5388.703621300827;5398.6220964532495;5400.83797486076;4988.725620090893;5015.448300686921;5017.724025178613;5018.77415516299;5019.899602007939;5672.574556908207;5731.226284451587;5780.739866706008;5790.392008839053;5797.449242321973;4230.51349094855;4233.386159006926;4237.6821738369745;4254.978725106354;4269.7224680395675;5995.580309378345;6034.185384973016;6041.909371359433;6049.334573196945;6070.3019152797115;4294.463192100988;4360.7601581913295;4363.672724545671;4388.551777913281;4398.318260669537;4369.383328168535;4392.7364358137975;4396.4064851244675;4409.184300904343;4411.933820992687;4936.259561065869;4959.578435832995;4993.70037797726;5007.324011258506;5034.295198672189;5527.585431092684;5531.778728850801;5777.508911742549;5805.332386775575;5894.233749612523;1433.7132573485344;1452.1940323120768;1491.850814918521;1505.3526983397244;1518.1953564610774;1016.6390931219458;1036.160709825044;1046.304207701885;1051;1057.3867157048894;5498.835034948958;5538.421549858271;5545.306836645419;5549.306336707911;5566.749496272678;5867.20792692981;5869.624965644772;5870.207521984563;5878.412158784087;5884.676342801187;5352.9732351338;5520.564653190119;5617.7697823751505;5631.690257035821;5669.971650141723;3144.4372836138473;3165.920851978719;3180.634227063871;3182.8249446280215;3190.298134410426;5891.764329426775;6136.048912418582;6177.96911015451;6196.597221180609;6211.81364559064;6066.150738896558;6107.877842443215;6122.418370254819;6137.3862613738265;6138.969305153446;6151.569390142647;6165.475934545524;6165.938340616536;6175.876482470415;6180.103884936749;5675.943240567707;5966.671833049224;5991.850203744942;6013.699315034319;6018.277806663228;5887.764489420375;5909.261342332208;5913.526917846476;5960.284765828128;6021.668808215591;5910.202122713378;6121.877562448815;6149.754009839557;6161.137440758208;6229.906551401642;4330.891727706832;4359.564043595615;4365.214261432972;4366.65066794662;4375.912481750411;5763.221965034834;5953.10703394477;6054.273487181566;6062.272527296753;6070.332263450929;5676.858078547904;5808.912866307093;5813.883722325614;5815;5830.300363956352;5798.028670252134;5815.499335285867;5818.272715910512;5931.487470694945;5937.970310148422;5645.915311270416;5686.57350698691;5713.885722285614;5720.199172115824;5722.942770572407;5805.129230615273;5807.506361959112;5828.59199856006;5832.512709259112;5843.766249350148;5487.573230959899;5647.604667673223;5707.743151558107;5721.685307308138;5754.18045157878 13:14:06 INFO - PROCESS | 6532 | |5;yui.html;771;822.7079386817684;826.8511667899776;866.4108406283726;913.6299798581575;17107.401240956555;17772.64454710905;17886;17888.910555447223;17913.46259612212;16460.75308870365;16579.66840663186;16588.75116873248;16601.750973735405;16629.50111496657;722.1622917415799;740.0120838680341;838.1199740272689;855.5978690015713;865.8571335729597;881.7927786970057;884.2218847414266;887.1173182683207;887.1394747095308;887.5207388010499;924.5839372282467;925.2274350916992;927.2628260532858;927.5408672707019;928.0070324752484;756.3873262657238;777.3120788102542;892.6206362295998;906.2840356118659;922.3451349541833;678.7352932356386;679.3376457953506;679.4157024958532;679.5990365684221;680.1667956752988;16215.108169050698;16234.350625974947;16260;16265.186740663037;16270.186490675418;14575.781363279559;14659.706805863929;14774.26128693561;14867.776983345258;14898.553043408716;13640.976926730436;13962.09246398991;14009.159450433006;14013.159210447406;14296.642583935381;4654.0924519718665;4663.720176789404;4677.953220467786;4832.130216561018;4873.780679869415;85450;86814.5659271706;87681;88775;90136.54931725365;11811.409429528661;11945;11983.64049078529;12016.158868879094;12297.139200255897;22962.01916641457;23170.536589268122;23676.88161559181;23677.17129900445;23719.1698290559;8522.57387130641;8558.657653693906;8563.529005904611;8913.866292005625;9077.727668169966;4915.3855768029;5014.5737612303365;5053.873653158627;5102.69383836971;5238.895222095537;3478.8260586971055;3553.484744712054;3577.3024260269517;3583.0146709655055;3592.568891733009;620.6958590290725;627.3350248736355;627.3444250757968;631.766246488802;634.4131678197649;641.7368878759686;696.4846013949641;697.4490152779248;700.2017699822206;700.5901547594714;8724.214820666171;8936.32977526688;9449.480278584744;9473.194778443773;9693.903060969442;560.2828379673942;569.2268583362795;618.6813790897692;636.9426751592287;661.4642139866723;4688.4811274680915;4712.976435117871;4785.401824771903;4842.346283251786;4854.514548545118;74354;100661;130327.34836325947;133794;134512.32743836218;93517.5324123375;114606;116760;117488.41255793837;119562;56269.71865140648;58007;81308;93941;96723.51638241764;9918.900810991798;10035.34770239932;10290.742731431774;10321.896781032394;10365.585376585153;52290.47709522856;52925.73537132444;53211;53473.46526534685;54028.459715403915;72458.27541724515;73242.26757732354;79009.60495197718;79929;82130;13489.258090804708;13928.373223204777;14278.143311401349;14350.49773257931;14454.783178252124;15502.689946201239;15904.12527311009;16176.919115404347;16286;16577.66844663124;10189;14162.858371416567;15011.624709381917;15504.534863954099;15604.297806598957;13778;14597.12417254968;14961.40154393812;15000.099993999958;15410.845891540941;14592.197429141075;14596.270186490421;14764.778528322298;14988.100713956761;15264.618384540476;11679.824802627798;12277.140600157903;12435.689107772378;12703.936480317909;13184.077114602269;12291.815622765844;12536;12782.42479088462;13009.869901301245;13243 13:14:06 INFO - PROCESS | 6532 | __end_tp_report 13:14:06 INFO - PROCESS | 6532 | __start_cc_report 13:14:06 INFO - PROCESS | 6532 | _x_x_mozilla_cycle_collect,820 13:14:06 INFO - PROCESS | 6532 | __end_cc_report 13:14:06 INFO - PROCESS | 6532 | __startTimestamp1474402446894__endTimestamp 13:14:06 INFO - PROCESS | 6532 | 13:14:06 INFO - PROCESS | 6532 | ------- Summary: start ------- 13:14:06 INFO - PROCESS | 6532 | Number of tests: 6 13:14:06 INFO - PROCESS | 6532 | 13:14:06 INFO - PROCESS | 6532 | [#0] dojo.html Cycles:165 Average:5520.25 Median:5754.25 stddev:629.60 (10.9%) stddev-sans-first:601.72 13:14:06 INFO - PROCESS | 6532 | Values: 3079.7 3113.2 3129.9 3153.6 3155.6 5559.7 5655.0 5661.8 5677.8 5709.4 5590.7 5676.1 5679.7 5702.4 5730.7 5113.9 5142.6 5170.3 5171.4 5197.7 5004.7 5155.4 5182.0 5185.0 5185.2 4987.3 5023.0 5062.0 5102.4 5113.8 5039.5 5073.0 5077.7 5141.9 5164.9 4964.4 5044.2 5074.0 5094.4 5095.4 5323.2 5375.7 5434.1 5442.6 5485.9 5826.4 5840.4 5843.9 5933.2 5935.0 5962.4 5964.2 5971.1 5972.0 5981.8 5417.1 5453.6 5457.6 5479.9 5483.9 6005.8 6013.3 6016.3 6017.2 6029.4 5743.1 5758.9 5803.9 5805.0 5805.4 5602.9 5640.1 5784.8 5815.7 5845.3 5863.5 5869.2 5883.1 5900.2 5902.8 5841.0 5892.8 5895.1 5904.3 5907.1 4399.8 4446.1 4492.7 4540.4 4542.5 3975.7 4059.4 4062.6 4074.2 4124.2 5722.6 5724.2 5726.1 5728.2 5730.7 5807.5 6042.5 6049.8 6057.3 6073.0 5564.2 5622.7 5663.5 5665.9 5754.3 5169.2 5205.4 5210.7 5214.2 5216.4 6143.2 6207.3 6234.3 6237.2 6239.3 5845.2 5872.1 5876.5 5889.3 5905.5 6138.2 6138.9 6143.3 6147.0 6153.9 5812.5 5815.2 5817.9 5825.7 5864.7 5721.1 5728.2 5869.4 5906.6 5930.5 5835.3 5857.8 5860.3 5886.1 5898.3 5832.7 5855.8 5859.5 5861.8 5863.0 5579.1 5593.4 5729.7 5807.9 5818.2 5863.0 5912.4 5927.8 5945.8 5953.0 5787.2 5793.0 5799.5 5808.8 5811.7 13:14:06 INFO - PROCESS | 6532 | 13:14:06 INFO - PROCESS | 6532 | [#1] ext.html Cycles:180 Average:63559.46 Median:39955.70 stddev:128209.64 (320.9%) stddev-sans-first:128482.12 13:14:06 INFO - PROCESS | 6532 | Values: 585.8 601.5 605.1 628.2 629.4 35425.8 38566.6 45016.5 46002.5 46108.8 47588.8 48078.5 48088.5 48117.8 48210.3 8388.9 9313.2 9461.0 9465.7 9476.6 8496.8 8794.3 8804.7 10995.9 11314.0 11525.1 11588.2 11589.1 11591.0 11593.7 11305.4 11364.0 11370.3 11378.9 11391.6 11153.3 11202.8 11224.3 11229.2 11230.9 45877.8 45878.3 45977.8 46320.8 46347.5 3772.1 3863.3 3896.3 4090.2 4359.7 35463.5 35840.5 36219.3 36597.0 37232.3 4367.0 4396.7 4427.5 4485.9 4571.6 344485.0 344674.0 344807.0 344892.0 346366.3 38470.0 39664.2 59232.4 59446.7 59546.7 42260.4 42634.4 42707.6 42729.4 42753.6 16835.2 16938.7 16941.9 16953.1 16963.0 14602.7 14686.0 14702.4 14705.4 14715.2 9017.3 9205.5 9246.8 9566.5 9678.0 3647.0 3654.4 3682.0 3703.3 3737.7 995.7 1062.1 1096.5 1111.5 1156.3 34826.1 34979.7 35001.0 35129.6 35634.1 1197.1 1223.3 1236.5 1249.4 1282.5 17732.6 18358.0 18398.2 18485.7 18528.7 571901.0 579307.0 579999.0 580224.0 580461.0 64841.7 65380.0 65510.7 65547.0 65675.4 1396.8 1435.5 1436.6 1438.6 1456.3 31907.0 33182.5 33465.7 33653.0 33883.7 52335.7 52721.7 53103.7 53104.9 53105.0 492338.0 494965.0 495467.0 496227.0 496294.0 31685.2 31789.7 32493.8 32558.5 32586.2 51328.0 51597.0 51994.7 52029.0 53732.0 42600.8 42651.6 42698.8 42901.0 44644.6 44556.8 45109.5 45558.3 45767.5 46424.3 42843.0 43739.6 44836.0 44998.8 45016.3 36835.4 38962.8 39819.8 40091.6 43126.8 29511.3 29815.4 31287.2 31563.2 31758.4 13:14:06 INFO - PROCESS | 6532 | 13:14:06 INFO - PROCESS | 6532 | [#2] jquery.html Cycles:180 Average:29981.21 Median:32547.34 stddev:80024.74 (245.9%) stddev-sans-first:80218.40 13:14:06 INFO - PROCESS | 6532 | Values: 392.6 425.8 427.1 441.3 442.5 16537.5 19855.8 20867.9 21083.5 21292.7 18680.6 20241.0 20308.8 20383.6 20460.6 5851.1 5888.5 6096.7 6104.5 6106.1 5974.6 6018.5 6059.1 6062.8 6062.9 5954.6 5992.2 6024.6 6027.9 6028.7 5839.9 5969.5 5980.6 5986.4 5988.0 5356.5 5393.2 5397.0 5643.7 5676.5 23285.7 23402.2 23593.6 23721.9 23740.2 2307.5 2398.1 2515.2 2520.8 2523.7 10789.7 10799.9 10951.1 12041.8 14697.9 1883.8 1897.9 1927.8 1950.4 1992.2 85354.0 86219.0 90269.5 91146.5 92225.0 10767.4 11885.0 11900.4 12313.6 12986.2 15612.5 30896.4 32288.7 32432.8 32661.8 10563.7 10651.3 10849.0 10887.3 10954.2 9124.7 9137.8 9345.1 9681.3 9872.1 8856.8 9151.0 9168.0 9178.8 9354.3 3421.9 3461.7 3465.9 3543.2 3638.8 978.2 997.5 1008.8 1071.1 1072.1 19676.9 19958.0 20210.2 20512.5 20917.2 993.1 999.2 1031.7 1032.7 1091.2 10578.7 11214.9 11245.9 11248.8 11533.3 477998.0 480619.0 483608.0 483797.0 494602.0 89508.6 90622.5 95702.6 100540.0 105117.0 523.9 542.9 545.7 547.3 550.1 10860.9 10921.6 11214.5 11402.7 11503.1 42129.0 42239.0 43402.0 43608.1 44982.1 56703.0 57846.7 60169.7 60501.7 61424.1 8955.4 9259.8 9397.3 9502.2 9610.7 12807.9 13752.3 13915.2 14155.9 14299.7 6231.2 11241.5 11257.4 12342.4 12412.4 11010.4 11095.9 11372.8 11731.1 11840.9 11685.4 12322.3 12644.3 12764.2 12798.4 9236.3 9289.9 9307.3 9325.1 9583.9 10689.5 11502.4 11861.3 12169.0 12609.4 13:14:06 INFO - PROCESS | 6532 | 13:14:06 INFO - PROCESS | 6532 | [#3] mootools.html Cycles:180 Average:6125.86 Median:4675.70 stddev:3477.61 (74.4%) stddev-sans-first:3469.13 13:14:06 INFO - PROCESS | 6532 | Values: 1387.7 1540.9 1552.5 1705.7 1820.1 8071.9 9080.5 9387.0 9391.6 9677.1 9385.4 9462.8 9464.2 9469.1 9473.5 2449.1 2473.0 2474.0 2474.7 2478.7 2493.7 2585.9 2595.7 2599.9 2599.9 2499.1 2499.2 2560.7 2563.5 2564.8 2447.7 2470.2 2471.0 2471.3 2478.9 2449.4 2449.6 2460.3 2461.2 2497.1 9302.4 9311.4 9480.1 9510.9 9512.0 5617.6 5811.3 5815.0 5822.0 5840.4 3631.3 3655.6 3680.4 3714.1 3716.1 1091.3 1124.3 1148.7 1188.7 1209.0 12585.6 12680.1 12689.4 12711.0 12753.4 4725.4 4759.2 4862.0 4869.8 5052.3 10324.4 10343.5 10359.3 10360.7 10365.3 5288.4 5299.9 5307.2 5319.7 5319.8 4474.1 4626.0 4944.6 5116.4 5260.1 3955.8 3981.5 4034.0 4053.4 4125.9 1166.3 1173.1 1178.6 1192.5 1198.4 881.8 970.3 981.2 990.3 1009.9 6140.9 6154.2 6307.1 6335.0 6404.0 985.4 998.8 1043.2 1049.6 1072.1 4204.5 4250.9 4261.1 4287.2 4353.4 11072.9 11534.3 11544.0 11545.9 11565.7 11437.6 11473.3 11487.4 11500.3 11516.4 8373.8 8658.8 8774.9 8779.4 8814.4 6268.4 6316.7 6418.1 6448.6 6573.7 10437.4 10739.7 10744.0 10763.7 10884.3 10762.1 10987.0 11088.9 11134.2 11149.1 8038.5 8280.6 8355.0 8459.6 8552.3 9162.5 9222.8 9281.8 9288.8 9298.1 8105.3 8152.0 8267.1 8309.0 8510.5 8152.8 8159.5 8191.9 8245.7 8289.1 7174.6 7310.1 7386.5 7391.7 7392.1 4972.5 5234.7 5284.1 5324.0 5350.7 5433.3 5564.9 5576.1 5586.7 5795.7 13:14:06 INFO - PROCESS | 6532 | 13:14:06 INFO - PROCESS | 6532 | [#4] prototype.html Cycles:180 Average:4915.40 Median:5535.10 stddev:1420.74 (25.7%) stddev-sans-first:1382.94 13:14:06 INFO - PROCESS | 6532 | Values: 357.9 361.9 369.8 372.7 373.3 5350.4 5493.1 5639.4 5668.0 5671.8 5329.2 5450.2 5473.4 5545.2 5563.9 4404.4 4405.6 4442.1 4445.1 4463.6 4171.9 4356.6 4391.9 4429.0 4442.7 4338.5 4350.4 4367.9 4369.9 4384.4 4364.5 4366.7 4390.5 4401.5 4406.6 3812.7 3879.4 3908.8 3916.3 3925.9 5374.7 5381.6 5388.7 5398.6 5400.8 4988.7 5015.4 5017.7 5018.8 5019.9 5672.6 5731.2 5780.7 5790.4 5797.4 4230.5 4233.4 4237.7 4255.0 4269.7 5995.6 6034.2 6041.9 6049.3 6070.3 4294.5 4360.8 4363.7 4388.6 4398.3 4369.4 4392.7 4396.4 4409.2 4411.9 4936.3 4959.6 4993.7 5007.3 5034.3 5527.6 5531.8 5777.5 5805.3 5894.2 1433.7 1452.2 1491.9 1505.4 1518.2 1016.6 1036.2 1046.3 1051.0 1057.4 5498.8 5538.4 5545.3 5549.3 5566.7 5867.2 5869.6 5870.2 5878.4 5884.7 5353.0 5520.6 5617.8 5631.7 5670.0 3144.4 3165.9 3180.6 3182.8 3190.3 5891.8 6136.0 6178.0 6196.6 6211.8 6066.2 6107.9 6122.4 6137.4 6139.0 6151.6 6165.5 6165.9 6175.9 6180.1 5675.9 5966.7 5991.9 6013.7 6018.3 5887.8 5909.3 5913.5 5960.3 6021.7 5910.2 6121.9 6149.8 6161.1 6229.9 4330.9 4359.6 4365.2 4366.7 4375.9 5763.2 5953.1 6054.3 6062.3 6070.3 5676.9 5808.9 5813.9 5815.0 5830.3 5798.0 5815.5 5818.3 5931.5 5938.0 5645.9 5686.6 5713.9 5720.2 5722.9 5805.1 5807.5 5828.6 5832.5 5843.8 5487.6 5647.6 5707.7 5721.7 5754.2 13:14:06 INFO - PROCESS | 6532 | 13:14:06 INFO - PROCESS | 6532 | [#5] yui.html Cycles:180 Average:21936.88 Median:4700.73 stddev:31559.38 (671.4%) stddev-sans-first:31607.89 13:14:06 INFO - PROCESS | 6532 | Values: 771.0 822.7 826.9 866.4 913.6 17107.4 17772.6 17886.0 17888.9 17913.5 16460.8 16579.7 16588.8 16601.8 16629.5 722.2 740.0 838.1 855.6 865.9 881.8 884.2 887.1 887.1 887.5 924.6 925.2 927.3 927.5 928.0 756.4 777.3 892.6 906.3 922.3 678.7 679.3 679.4 679.6 680.2 16215.1 16234.4 16260.0 16265.2 16270.2 14575.8 14659.7 14774.3 14867.8 14898.6 13641.0 13962.1 14009.2 14013.2 14296.6 4654.1 4663.7 4678.0 4832.1 4873.8 85450.0 86814.6 87681.0 88775.0 90136.5 11811.4 11945.0 11983.6 12016.2 12297.1 22962.0 23170.5 23676.9 23677.2 23719.2 8522.6 8558.7 8563.5 8913.9 9077.7 4915.4 5014.6 5053.9 5102.7 5238.9 3478.8 3553.5 3577.3 3583.0 3592.6 620.7 627.3 627.3 631.8 634.4 641.7 696.5 697.4 700.2 700.6 8724.2 8936.3 9449.5 9473.2 9693.9 560.3 569.2 618.7 636.9 661.5 4688.5 4713.0 4785.4 4842.3 4854.5 74354.0 100661.0 130327.3 133794.0 134512.3 93517.5 114606.0 116760.0 117488.4 119562.0 56269.7 58007.0 81308.0 93941.0 96723.5 9918.9 10035.3 10290.7 10321.9 10365.6 52290.5 52925.7 53211.0 53473.5 54028.5 72458.3 73242.3 79009.6 79929.0 82130.0 13489.3 13928.4 14278.1 14350.5 14454.8 15502.7 15904.1 16176.9 16286.0 16577.7 10189.0 14162.9 15011.6 15504.5 15604.3 13778.0 14597.1 14961.4 15000.1 15410.8 14592.2 14596.3 14764.8 14988.1 15264.6 11679.8 12277.1 12435.7 12703.9 13184.1 12291.8 12536.0 12782.4 13009.9 13243.0 13:14:06 INFO - PROCESS | 6532 | -------- Summary: end -------- 13:14:06 INFO - PROCESS | 6532 | 13:14:07 INFO - TEST-INFO | 6532: exit 0 13:14:07 INFO - TEST-OK | dromaeo_css | took 1113929ms 13:14:07 INFO - TEST-START | kraken 13:14:07 INFO - operating with platform_type : linux_ 13:14:07 INFO - Initialising browser for kraken test... 13:14:07 INFO - TEST-INFO | started process 6845 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp4yiH8r/profile http://localhost:41824/getInfo.html) 13:14:11 INFO - PROCESS | 6845 | __metrics Screen width/height:1600/1200 13:14:11 INFO - PROCESS | 6845 | colorDepth:24 13:14:11 INFO - PROCESS | 6845 | Browser inner width/height: 1024/697 13:14:11 INFO - PROCESS | 6845 | __metrics 13:14:12 INFO - PROCESS | 6845 | 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" 13:14:12 INFO - PROCESS | 6845 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 13:14:13 INFO - PROCESS | 6845 | console.error: PushService: 13:14:13 INFO - PROCESS | 6845 | stateChangeProcessEnqueue: Error transitioning state 13:14:13 INFO - PROCESS | 6845 | UnknownError 13:14:13 INFO - TEST-INFO | 6845: exit 0 13:14:13 INFO - Browser initialized. 13:14:13 INFO - Running cycle 1/1 for kraken test... 13:14:13 INFO - TEST-INFO | started process 6936 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp4yiH8r/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:14:25 INFO - PROCESS | 6936 | RSS: Main: 144515072 13:14:25 INFO - PROCESS | 6936 | 13:14:27 INFO - PROCESS | 6936 | Cycle 1(1): loaded http://localhost:41824/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:41824/tests/kraken/driver.html?testName=audio-beat-detection) 13:14:28 INFO - PROCESS | 6936 | RSS: Main: 186249216 13:14:28 INFO - PROCESS | 6936 | 13:14:31 INFO - PROCESS | 6936 | Cycle 1(1): loaded http://localhost:41824/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:41824/tests/kraken/driver.html?testName=audio-dft) 13:14:31 INFO - PROCESS | 6936 | RSS: Main: 175534080 13:14:31 INFO - PROCESS | 6936 | 13:14:34 INFO - PROCESS | 6936 | Cycle 1(1): loaded http://localhost:41824/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:41824/tests/kraken/driver.html?testName=audio-fft) 13:14:35 INFO - PROCESS | 6936 | RSS: Main: 266227712 13:14:35 INFO - PROCESS | 6936 | 13:14:37 INFO - PROCESS | 6936 | Cycle 1(1): loaded http://localhost:41824/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:41824/tests/kraken/driver.html?testName=audio-oscillator) 13:14:37 INFO - PROCESS | 6936 | RSS: Main: 172990464 13:14:37 INFO - PROCESS | 6936 | 13:14:39 INFO - PROCESS | 6936 | Cycle 1(1): loaded http://localhost:41824/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:41824/tests/kraken/driver.html?testName=imaging-gaussian-blur) 13:14:40 INFO - PROCESS | 6936 | RSS: Main: 174673920 13:14:40 INFO - PROCESS | 6936 | 13:14:43 INFO - PROCESS | 6936 | Cycle 1(1): loaded http://localhost:41824/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:41824/tests/kraken/driver.html?testName=imaging-darkroom) 13:14:44 INFO - PROCESS | 6936 | RSS: Main: 210837504 13:14:44 INFO - PROCESS | 6936 | 13:14:47 INFO - PROCESS | 6936 | Cycle 1(1): loaded http://localhost:41824/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:41824/tests/kraken/driver.html?testName=imaging-desaturate) 13:14:48 INFO - PROCESS | 6936 | RSS: Main: 209829888 13:14:48 INFO - PROCESS | 6936 | 13:14:50 INFO - PROCESS | 6936 | Cycle 1(1): loaded http://localhost:41824/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:41824/tests/kraken/driver.html?testName=json-parse-financial) 13:14:51 INFO - PROCESS | 6936 | RSS: Main: 209383424 13:14:51 INFO - PROCESS | 6936 | 13:14:52 INFO - PROCESS | 6936 | Cycle 1(1): loaded http://localhost:41824/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:41824/tests/kraken/driver.html?testName=json-stringify-tinderbox) 13:14:53 INFO - PROCESS | 6936 | RSS: Main: 171388928 13:14:53 INFO - PROCESS | 6936 | 13:14:55 INFO - PROCESS | 6936 | Cycle 1(1): loaded http://localhost:41824/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:41824/tests/kraken/driver.html?testName=stanford-crypto-aes) 13:14:55 INFO - PROCESS | 6936 | RSS: Main: 179228672 13:14:55 INFO - PROCESS | 6936 | 13:14:57 INFO - PROCESS | 6936 | Cycle 1(1): loaded http://localhost:41824/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:41824/tests/kraken/driver.html?testName=stanford-crypto-ccm) 13:14:58 INFO - PROCESS | 6936 | RSS: Main: 182894592 13:14:58 INFO - PROCESS | 6936 | 13:15:00 INFO - PROCESS | 6936 | Cycle 1(1): loaded http://localhost:41824/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:41824/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 13:15:01 INFO - PROCESS | 6936 | RSS: Main: 183652352 13:15:01 INFO - PROCESS | 6936 | 13:15:04 INFO - PROCESS | 6936 | Cycle 1(1): loaded http://localhost:41824/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:41824/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 13:15:04 INFO - PROCESS | 6936 | RSS: Main: 181854208 13:15:04 INFO - PROCESS | 6936 | 13:15:06 INFO - PROCESS | 6936 | Cycle 1(1): loaded http://localhost:41824/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:41824/tests/kraken/driver.html?testName=ai-astar) 13:15:06 INFO - PROCESS | 6936 | RSS: Main: 234340352 13:15:06 INFO - PROCESS | 6936 | 13:15:06 INFO - PROCESS | 6936 | __start_tp_report 13:15:06 INFO - PROCESS | 6936 | _x_x_mozilla_page_load 13:15:06 INFO - PROCESS | 6936 | _x_x_mozilla_page_load_details 13:15:06 INFO - PROCESS | 6936 | |i|pagename|runs| 13:15:06 INFO - PROCESS | 6936 | |0;ai-astar;109;111;111;111;110;111;110;110;110;111 13:15:06 INFO - PROCESS | 6936 | |1;audio-beat-detection;149;117;117;160;118;122;117;116;151;122 13:15:06 INFO - PROCESS | 6936 | |2;audio-dft;161;161;162;161;160;162;161;160;161;161 13:15:06 INFO - PROCESS | 6936 | |3;audio-fft;75;76;76;117;77;76;76;75;96;76 13:15:06 INFO - PROCESS | 6936 | |4;audio-oscillator;81;82;107;82;81;82;104;81;81;106 13:15:06 INFO - PROCESS | 6936 | |5;imaging-gaussian-blur;117;117;116;116;116;117;116;117;116;116 13:15:06 INFO - PROCESS | 6936 | |6;imaging-darkroom;147;146;147;145;147;147;145;146;145;146 13:15:06 INFO - PROCESS | 6936 | |7;imaging-desaturate;95;95;95;95;95;95;95;96;95;95 13:15:06 INFO - PROCESS | 6936 | |8;json-parse-financial;49;50;50;50;49;49;48;50;49;50 13:15:06 INFO - PROCESS | 6936 | |9;json-stringify-tinderbox;59;59;59;60;60;59;58;60;59;59 13:15:06 INFO - PROCESS | 6936 | |10;stanford-crypto-aes;73;74;73;87;72;72;79;73;78;76 13:15:06 INFO - PROCESS | 6936 | |11;stanford-crypto-ccm;119;120;118;118;116;116;117;119;151;122 13:15:06 INFO - PROCESS | 6936 | |12;stanford-crypto-pbkdf2;153;152;156;152;151;150;151;151;149;152 13:15:06 INFO - PROCESS | 6936 | |13;stanford-crypto-sha256-iterative;63;70;67;61;64;62;62;62;61;61 13:15:06 INFO - PROCESS | 6936 | __end_tp_report 13:15:06 INFO - PROCESS | 6936 | __start_cc_report 13:15:06 INFO - PROCESS | 6936 | _x_x_mozilla_cycle_collect,1589 13:15:06 INFO - PROCESS | 6936 | __end_cc_report 13:15:06 INFO - PROCESS | 6936 | __startTimestamp1474402506607__endTimestamp 13:15:06 INFO - PROCESS | 6936 | 13:15:06 INFO - PROCESS | 6936 | ------- Summary: start ------- 13:15:06 INFO - PROCESS | 6936 | Number of tests: 14 13:15:06 INFO - PROCESS | 6936 | 13:15:06 INFO - PROCESS | 6936 | [#0] ai-astar Cycles:10 Average:110.40 Median:111.00 stddev:0.70 (0.6%) stddev-sans-first:0.53 13:15:06 INFO - PROCESS | 6936 | Values: 109.0 111.0 111.0 111.0 110.0 111.0 110.0 110.0 110.0 111.0 13:15:06 INFO - PROCESS | 6936 | 13:15:06 INFO - PROCESS | 6936 | [#1] audio-beat-detection Cycles:10 Average:128.90 Median:122.00 stddev:17.21 (14.1%) stddev-sans-first:16.64 13:15:06 INFO - PROCESS | 6936 | Values: 149.0 117.0 117.0 160.0 118.0 122.0 117.0 116.0 151.0 122.0 13:15:06 INFO - PROCESS | 6936 | 13:15:06 INFO - PROCESS | 6936 | [#2] audio-dft Cycles:10 Average:161.00 Median:161.00 stddev:0.67 (0.4%) stddev-sans-first:0.71 13:15:06 INFO - PROCESS | 6936 | Values: 161.0 161.0 162.0 161.0 160.0 162.0 161.0 160.0 161.0 161.0 13:15:06 INFO - PROCESS | 6936 | 13:15:06 INFO - PROCESS | 6936 | [#3] audio-fft Cycles:10 Average:82.00 Median:76.00 stddev:13.84 (18.2%) stddev-sans-first:14.45 13:15:06 INFO - PROCESS | 6936 | Values: 75.0 76.0 76.0 117.0 77.0 76.0 76.0 75.0 96.0 76.0 13:15:06 INFO - PROCESS | 6936 | 13:15:06 INFO - PROCESS | 6936 | [#4] audio-oscillator Cycles:10 Average:88.70 Median:81.00 stddev:11.74 (14.5%) stddev-sans-first:12.12 13:15:06 INFO - PROCESS | 6936 | Values: 81.0 82.0 107.0 82.0 81.0 82.0 104.0 81.0 81.0 106.0 13:15:06 INFO - PROCESS | 6936 | 13:15:06 INFO - PROCESS | 6936 | [#5] imaging-gaussian-blur Cycles:10 Average:116.40 Median:116.50 stddev:0.52 (0.4%) stddev-sans-first:0.50 13:15:06 INFO - PROCESS | 6936 | Values: 117.0 117.0 116.0 116.0 116.0 117.0 116.0 117.0 116.0 116.0 13:15:06 INFO - PROCESS | 6936 | 13:15:06 INFO - PROCESS | 6936 | [#6] imaging-darkroom Cycles:10 Average:146.10 Median:146.50 stddev:0.88 (0.6%) stddev-sans-first:0.87 13:15:06 INFO - PROCESS | 6936 | Values: 147.0 146.0 147.0 145.0 147.0 147.0 145.0 146.0 145.0 146.0 13:15:06 INFO - PROCESS | 6936 | 13:15:06 INFO - PROCESS | 6936 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 13:15:06 INFO - PROCESS | 6936 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 95.0 96.0 95.0 95.0 13:15:06 INFO - PROCESS | 6936 | 13:15:06 INFO - PROCESS | 6936 | [#8] json-parse-financial Cycles:10 Average:49.40 Median:50.00 stddev:0.70 (1.4%) stddev-sans-first:0.73 13:15:06 INFO - PROCESS | 6936 | Values: 49.0 50.0 50.0 50.0 49.0 49.0 48.0 50.0 49.0 50.0 13:15:06 INFO - PROCESS | 6936 | 13:15:06 INFO - PROCESS | 6936 | [#9] json-stringify-tinderbox Cycles:10 Average:59.20 Median:59.00 stddev:0.63 (1.1%) stddev-sans-first:0.67 13:15:06 INFO - PROCESS | 6936 | Values: 59.0 59.0 59.0 60.0 60.0 59.0 58.0 60.0 59.0 59.0 13:15:06 INFO - PROCESS | 6936 | 13:15:06 INFO - PROCESS | 6936 | [#10] stanford-crypto-aes Cycles:10 Average:75.70 Median:75.00 stddev:4.67 (6.2%) stddev-sans-first:4.85 13:15:06 INFO - PROCESS | 6936 | Values: 73.0 74.0 73.0 87.0 72.0 72.0 79.0 73.0 78.0 76.0 13:15:06 INFO - PROCESS | 6936 | 13:15:06 INFO - PROCESS | 6936 | [#11] stanford-crypto-ccm Cycles:10 Average:121.60 Median:119.00 stddev:10.49 (8.8%) stddev-sans-first:11.08 13:15:06 INFO - PROCESS | 6936 | Values: 119.0 120.0 118.0 118.0 116.0 116.0 117.0 119.0 151.0 122.0 13:15:06 INFO - PROCESS | 6936 | 13:15:06 INFO - PROCESS | 6936 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:151.70 Median:152.00 stddev:1.89 (1.2%) stddev-sans-first:1.94 13:15:06 INFO - PROCESS | 6936 | Values: 153.0 152.0 156.0 152.0 151.0 150.0 151.0 151.0 149.0 152.0 13:15:06 INFO - PROCESS | 6936 | 13:15:06 INFO - PROCESS | 6936 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.30 Median:62.50 stddev:2.98 (4.8%) stddev-sans-first:3.16 13:15:06 INFO - PROCESS | 6936 | Values: 63.0 70.0 67.0 61.0 64.0 62.0 62.0 62.0 61.0 61.0 13:15:06 INFO - PROCESS | 6936 | -------- Summary: end -------- 13:15:06 INFO - PROCESS | 6936 | 13:15:07 INFO - TEST-INFO | 6936: exit 0 13:15:07 INFO - TEST-OK | kraken | took 60060ms 13:15:07 INFO - SUITE-END | took 1173s 13:15:07 INFO - Completed test suite (00:19:34) 13:15:07 INFO - javascript benchmark 13:15:07 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3079.6612372639006, 3113.2216945763544, 3129.9217519562008, 3153.6057992750907, 3155.6055493063354, 5559.666420014802, 5655.0, 5661.830145095653, 5677.772889084432, 5709.429057094294, 5590.720463976805, 5676.148577713345, 5679.659220446766, 5702.372738998707, 5730.713464326788, 5113.948860511394, 5142.61430392722, 5170.276161337416, 5171.379434467869, 5197.688138711671, 5004.674696144739, 5155.432902380735, 5182.041322355376, 5185.040767458014, 5185.170372740362, 4987.276844857493, 5022.995400919813, 5062.0, 5102.387713474388, 5113.821016264432, 5039.521245481692, 5072.974635126838, 5077.669951453162, 5141.871453213662, 5164.896702065976, 4964.379452568429, 5044.218146187347, 5073.974630126826, 5094.414142373637, 5095.414027386824, 5323.201519772065, 5375.6774593524515, 5434.130539113723, 5442.564594832404, 5485.862853428696, 5826.388229235911, 5840.415958404168, 5843.941560584425, 5933.169356290123, 5934.97032514839, 5962.433568810956, 5964.165016897595, 5971.134185543116, 5971.970140149315, 5981.820545383645, 5417.106177480711, 5453.590980676465, 5457.617966742329, 5479.863003424946, 5483.917741233885, 6005.819825405245, 6013.30846952606, 6016.308124565643, 6017.247844019497, 6029.427204415573, 5743.1385292206505, 5758.913616295759, 5803.8549036274435, 5804.970975145097, 5805.36141024487, 5602.887942241132, 5640.097584386478, 5784.843031393738, 5815.651060936358, 5845.327787304429, 5863.5309175265875, 5869.178315035899, 5883.14694369314, 5900.203472531153, 5902.763889444374, 5841.0, 5892.793752218652, 5895.115732640138, 5904.261967254093, 5907.084401917709, 4399.758013309299, 4446.088551846864, 4492.73043617384, 4540.409746732904, 4542.477615074243, 3975.741576797499, 4059.3505039193587, 4062.5531191568903, 4074.1851629673592, 4124.237016152021, 5722.570807189478, 5724.227229323988, 5726.0838265877255, 5728.232715680398, 5730.684812335361, 5807.5063619591965, 6042.546808989343, 6049.818505444844, 6057.303410107894, 6072.969635151796, 5564.248826408464, 5622.662640241599, 5663.518600918886, 5665.886682266331, 5754.251947246831, 5169.172932330809, 5205.427402985669, 5210.713410762369, 5214.165733482625, 5216.426193118754, 6143.201383820075, 6207.34822843604, 6234.251889773256, 6237.157983672237, 6239.251289845248, 5845.210896528914, 5872.148538461985, 5876.500497457764, 5889.322727886348, 5905.527557795452, 6138.2327209097075, 6138.882085680433, 6143.266246520256, 6147.047207682816, 6153.938460615515, 5812.505936995402, 5815.156802263564, 5817.941820581909, 5825.737841797045, 5864.706764661835, 5721.084626459747, 5728.226689396878, 5869.35437101927, 5906.616069955439, 5930.52555795544, 5835.299764028344, 5857.765689372377, 5860.267466566679, 5886.05823068307, 5898.292204935436, 5832.679202643895, 5855.824325270249, 5859.531237501075, 5861.824145275648, 5863.032599621015, 5579.13523403873, 5593.4126916674795, 5729.742161602821, 5807.912881306868, 5818.185454036523, 5863.0, 5912.408759124053, 5927.792527261698, 5945.791897283574, 5952.970235148797, 5787.189793428839, 5792.9710351447975, 5799.536037116936, 5808.82573522795, 5811.709414529172], "value": 5476.681452283208, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [585.8154681275398, 601.5247954116247, 605.0530919111587, 628.2053202698587, 629.4020680353665, 35425.82287088564, 38566.614333856654, 45016.549834501646, 46002.539974600244, 46108.76945615275, 47588.76205618967, 48078.51921480784, 48088.51911480884, 48117.759411202984, 48210.276845847344, 8388.916110838905, 9313.161815436593, 9461.0, 9465.668701595445, 9476.573554190043, 8496.83006339873, 8794.296456283513, 8804.735857924241, 10995.890041099567, 11314.0, 11525.13561482888, 11588.188826782129, 11589.072874170046, 11591.0, 11593.710157246052, 11305.378204198807, 11364.0, 11370.317780933088, 11378.886211137948, 11391.601293954755, 11153.275037122561, 11202.831957520644, 11224.326540407601, 11229.213955023151, 11230.943845280803, 45877.77061114706, 45878.31182532265, 45977.77011114956, 46320.76839615814, 46347.536524635, 3772.07584141887, 3863.343231650626, 3896.3181443247317, 4090.1615168890307, 4359.716618419792, 35463.4680479793, 35840.462393064125, 36219.27561448756, 36597.0, 37232.25535489275, 4366.978165109186, 4396.71421357614, 4427.46870375557, 4485.932711009337, 4571.634269258451, 344485.0, 344674.0, 344807.0, 344892.0, 346366.26816866006, 38470.0, 39664.2067158661, 59232.40767592269, 59446.70276648676, 59546.70226648839, 42260.36609450861, 42634.360484592755, 42707.57292427098, 42729.35905961413, 42753.57246427558, 16835.15824208809, 16938.745918811226, 16941.91529042347, 16953.067581283147, 16963.0, 14602.707945841024, 14686.0, 14702.41190352374, 14705.411783528752, 14715.19066451334, 9017.2786177107, 9205.539723013822, 9246.815063698823, 9566.473843938651, 9678.0, 3647.0, 3654.3970244909824, 3681.9631803682155, 3703.2963736889906, 3737.719671024684, 995.7361299255703, 1062.0706881478707, 1096.5449338524422, 1111.522045520434, 1156.3119943633524, 34826.12934676653, 34979.65020349764, 35001.0, 35129.59758111044, 35634.10914727153, 1197.0543270815958, 1223.3332833605707, 1236.5424792826711, 1249.375312343828, 1282.5254655777235, 17732.645347093243, 18358.0, 18398.17208225633, 18485.7227141593, 18528.722069168973, 571901.0, 579307.0, 579999.0, 580224.0, 580461.0, 64841.67579162168, 65380.0, 65510.67244663842, 65547.0, 65675.40272180997, 1396.84634690184, 1435.4904009076627, 1436.633658417097, 1438.5612388221693, 1456.3082535795625, 31907.042788716375, 33182.5022624656, 33465.665343346256, 33653.00437723797, 33883.661163389035, 52335.73832130815, 52721.7363913178, 53103.734481327345, 53104.937901242534, 53105.0, 492338.0, 494965.0, 495467.0, 496227.0, 496294.0, 31685.20786980344, 31789.68210317867, 32493.837530812194, 32558.51162232521, 32586.185345366557, 51328.0, 51597.0, 51994.74002629962, 52029.0, 53732.0, 42600.78699606606, 42651.57348426476, 42698.78650606727, 42901.0, 44644.55355446404, 44556.77721611371, 45109.548904511845, 45558.31662525131, 45767.54232457633, 46424.30363544482, 42843.0, 43739.56260437355, 44836.0, 44998.77500612476, 45016.32475512935, 36835.447468288534, 38962.80518597389, 39819.80090099531, 40091.599084009955, 43126.78436607797, 29511.26221844471, 29815.403691926473, 31287.217819554695, 31563.21091972719, 31758.364832702755], "value": 20676.09639674047, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [392.60150946788997, 425.8387329362064, 427.1116078556603, 441.33358628471007, 442.53533789521015, 16537.503874883758, 19855.801441985594, 20867.895660521677, 21083.47291317714, 21292.680609790867, 18680.626387472243, 20241.0, 20308.796912030874, 20383.592328153427, 20460.59078818423, 5851.093080572518, 5888.5289176865745, 6096.69516524172, 6104.511639068864, 6106.114613381057, 5974.611650242742, 6018.458338749511, 6059.121427393025, 6062.787802426916, 6062.939370606304, 5954.553408494358, 5992.161097446361, 6024.608400453978, 6027.939720602803, 6028.668423236721, 5839.9124013139835, 5969.492593129589, 5980.611260268069, 5986.431289027535, 5988.041913293896, 5356.464353564651, 5393.2449457076045, 5396.973015134939, 5643.661380317153, 5676.545876329884, 23285.650715239284, 23402.18092366776, 23593.646095308584, 23721.88139059311, 23740.169094081622, 2307.5038866643754, 2398.124684490166, 2515.220281712675, 2520.8361456505268, 2523.6971563412326, 10789.676309710721, 10799.892001080047, 10951.123910087173, 12041.819372709417, 14697.853021469755, 1883.8304552590332, 1897.8576606754548, 1927.787943326233, 1950.4246247357064, 1992.232990298731, 85354.0, 86219.0, 90269.54865225697, 91146.5442672789, 92225.0, 10767.407792571485, 11885.0, 11900.404979750978, 12313.56902508408, 12986.155899866479, 15612.453564125428, 30896.38207235843, 32288.677113229038, 32432.83783581114, 32661.836690816395, 10563.683089507327, 10651.254412191227, 10849.0, 10887.292325998786, 10954.178436617285, 9124.680636177702, 9137.77155571116, 9345.112214339626, 9681.322307438553, 9872.111509963994, 8856.778580535409, 9150.954245228731, 9167.954160229156, 9178.81642367149, 9354.29842761782, 3421.9486707699402, 3461.7230621550216, 3465.8613655454005, 3543.2027793746715, 3638.7816730996224, 978.2320878110648, 997.5012493753123, 1008.7528555503949, 1071.1163290285544, 1072.1315734255122, 19676.90161549183, 19958.0, 20210.19159233614, 20512.487187820425, 20917.163313467594, 993.0714781679009, 999.2355847776457, 1031.742064483879, 1032.6592224565875, 1091.1598069486452, 10578.735531611617, 11214.887851121384, 11245.943770281096, 11248.831267530994, 11533.250338728123, 477998.0, 480619.0, 483608.0, 483797.0, 494602.0, 89508.5524572373, 90622.54688726514, 95702.56446153313, 100540.0, 105117.0, 523.8821265215372, 542.8924993014285, 545.6562365709577, 547.2639300141303, 550.086855819338, 10860.891391085988, 10921.56313747473, 11214.495347709535, 11402.657920262405, 11503.137264705014, 42129.0, 42239.0, 43402.0, 43608.12783744244, 44982.100357992, 56703.0, 57846.710766445896, 60169.69915150396, 60501.69749151226, 61424.07863881956, 8955.373123881265, 9259.814803704023, 9397.342186046912, 9502.19231365314, 9610.66362677303, 12807.935960320512, 13752.31238438094, 13915.234662093682, 14155.929220353832, 14299.714005720036, 6231.22109736283, 11241.49413276388, 11257.380844053656, 12342.44458999365, 12412.379381031093, 11010.449477525932, 11095.944520277348, 11372.829407559058, 11731.120165987755, 11840.940795295968, 11685.357305348287, 12322.260664360167, 12644.30456324911, 12764.17032892859, 12798.424070917017, 9236.30727695439, 9289.907100929175, 9307.301952353466, 9325.114114159143, 9583.856242156235, 10689.51897164645, 11502.367369794742, 11861.288322700666, 12169.0, 12609.369531523571], "value": 9858.996097652605, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1387.653086728318, 1540.861322480977, 1552.5264794237758, 1705.6844483770503, 1820.080859166121, 8071.87892181617, 9080.455172689642, 9386.953065234682, 9391.577379017936, 9677.080677335642, 9385.389949653269, 9462.81074378512, 9464.242860571156, 9469.053094690527, 9473.478958657272, 2449.069353645612, 2473.0478765675166, 2474.0474917156844, 2474.6782918220697, 2478.7397323281066, 2493.6758221431187, 2585.9482810343784, 2595.6755405574304, 2599.909003184889, 2599.922002339933, 2499.0878329409725, 2499.175272160183, 2560.6671132752717, 2563.5001174770928, 2564.8204625676212, 2447.7185123710733, 2470.2465747946867, 2470.962935555968, 2471.3450935502105, 2478.925632231036, 2449.448874003353, 2449.5835707929696, 2460.2619214235656, 2461.1509029384833, 2497.126005897939, 9302.441853488812, 9311.44131352121, 9480.099390557885, 9510.90489095114, 9511.952440237754, 5617.550595952314, 5811.302643682743, 5814.970925145347, 5821.970890145564, 5840.415958404125, 3631.310051090311, 3655.597884232759, 3680.4479328100733, 3714.1271801126713, 3716.0524066362905, 1091.2852081886376, 1124.2860783402598, 1148.6898537394857, 1188.6849984754047, 1209.0267334795485, 12585.622431327074, 12680.112392132554, 12689.428975696115, 12710.999999999907, 12753.426095825711, 4725.40932383452, 4759.190937540627, 4861.975690121527, 4869.756512174377, 5052.34319538465, 10324.380537167794, 10343.534540945675, 10359.274850760374, 10360.740981475523, 10365.274430789923, 5288.391834938954, 5299.920501192485, 5307.203919412042, 5319.707416092075, 5319.760610772524, 4474.127545128668, 4626.0, 4944.60443164546, 5116.360454943132, 5260.10578201699, 3955.8219880105466, 3981.5023122109737, 4033.979830100831, 4053.3717273822604, 4125.8968525787095, 1166.2652528906735, 1173.0732721150366, 1178.6169494914186, 1192.48723049088, 1198.4367347346733, 881.7839629290864, 970.3304719743354, 981.2395393569898, 990.3166814897697, 1009.8990100989989, 6140.8771824563255, 6154.19995400604, 6307.053941908659, 6334.968325158345, 6404.039394090923, 985.4038306824411, 998.8102260570469, 1043.2384359417667, 1049.5906596427399, 1072.0565902006185, 4204.474440694913, 4250.851220207278, 4261.062566235423, 4287.228298906164, 4353.390525326457, 11072.889271107186, 11534.307941523535, 11544.0, 11545.942270288595, 11565.710857228636, 11437.59968401102, 11473.311601303949, 11487.36819474937, 11500.25248358854, 11516.366599836922, 8373.790655233546, 8658.826823463496, 8774.868376974351, 8779.429337093068, 8814.382993190544, 6268.435840774352, 6316.7473301069285, 6418.133551970424, 6448.613083215022, 6573.737050517926, 10437.425941573287, 10739.731506712395, 10744.0, 10763.730906727395, 10884.292520986428, 10762.08522275584, 10987.0, 11088.944555277172, 11134.220604557604, 11149.052330551669, 8038.517688938448, 8280.627371768165, 8354.958225208835, 8459.61931713062, 8552.273056789994, 9162.541872906462, 9222.815543689223, 9281.767955801159, 9288.767780805534, 9298.116678915492, 8105.27052565272, 8152.021757389151, 8267.090620031671, 8308.958455207685, 8510.48937063778, 8152.796180095545, 8159.46963447374, 8191.8771218430575, 8245.670173193244, 8289.129641387568, 7174.605396703231, 7310.122785265802, 7386.482946193715, 7391.704331826882, 7392.112946446246, 4972.5276098770555, 5234.712090835041, 5284.101702710472, 5323.973380133075, 5350.678959262456, 5433.347998240237, 5564.860878478071, 5576.10782274834, 5586.692731899622, 5795.739191736467], "value": 4886.769057185658, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [357.92483578448525, 361.88238822382726, 369.8040038779447, 372.7334955506812, 373.31124076079624, 5350.384705758829, 5493.148561972891, 5639.436056394364, 5668.024287249191, 5671.8298451046485, 5329.200619907016, 5450.236966824637, 5473.370562385327, 5545.1682247662875, 5563.944360556393, 4404.427424434819, 4405.6475481961525, 4442.133783912131, 4445.110977804436, 4463.575960283768, 4171.8539851105215, 4356.629686476637, 4391.8682439526865, 4429.0, 4442.666799989997, 4338.522762496123, 4350.412694286279, 4367.934480982788, 4369.912601747963, 4384.408104905829, 4364.541723119074, 4366.672499562514, 4390.473143222834, 4401.537838526972, 4406.625436837872, 3812.6759225465867, 3879.4180872869015, 3908.7654740715648, 3916.275489034509, 3925.862594809168, 5374.731263436813, 5381.623286369956, 5388.703621300827, 5398.6220964532495, 5400.83797486076, 4988.725620090893, 5015.448300686921, 5017.724025178613, 5018.77415516299, 5019.899602007939, 5672.574556908207, 5731.226284451587, 5780.739866706008, 5790.392008839053, 5797.449242321973, 4230.51349094855, 4233.386159006926, 4237.6821738369745, 4254.978725106354, 4269.7224680395675, 5995.580309378345, 6034.185384973016, 6041.909371359433, 6049.334573196945, 6070.3019152797115, 4294.463192100988, 4360.7601581913295, 4363.672724545671, 4388.551777913281, 4398.318260669537, 4369.383328168535, 4392.7364358137975, 4396.4064851244675, 4409.184300904343, 4411.933820992687, 4936.259561065869, 4959.578435832995, 4993.70037797726, 5007.324011258506, 5034.295198672189, 5527.585431092684, 5531.778728850801, 5777.508911742549, 5805.332386775575, 5894.233749612523, 1433.7132573485344, 1452.1940323120768, 1491.850814918521, 1505.3526983397244, 1518.1953564610774, 1016.6390931219458, 1036.160709825044, 1046.304207701885, 1051.0, 1057.3867157048894, 5498.835034948958, 5538.421549858271, 5545.306836645419, 5549.306336707911, 5566.749496272678, 5867.20792692981, 5869.624965644772, 5870.207521984563, 5878.412158784087, 5884.676342801187, 5352.9732351338, 5520.564653190119, 5617.7697823751505, 5631.690257035821, 5669.971650141723, 3144.4372836138473, 3165.920851978719, 3180.634227063871, 3182.8249446280215, 3190.298134410426, 5891.764329426775, 6136.048912418582, 6177.96911015451, 6196.597221180609, 6211.81364559064, 6066.150738896558, 6107.877842443215, 6122.418370254819, 6137.3862613738265, 6138.969305153446, 6151.569390142647, 6165.475934545524, 6165.938340616536, 6175.876482470415, 6180.103884936749, 5675.943240567707, 5966.671833049224, 5991.850203744942, 6013.699315034319, 6018.277806663228, 5887.764489420375, 5909.261342332208, 5913.526917846476, 5960.284765828128, 6021.668808215591, 5910.202122713378, 6121.877562448815, 6149.754009839557, 6161.137440758208, 6229.906551401642, 4330.891727706832, 4359.564043595615, 4365.214261432972, 4366.65066794662, 4375.912481750411, 5763.221965034834, 5953.10703394477, 6054.273487181566, 6062.272527296753, 6070.332263450929, 5676.858078547904, 5808.912866307093, 5813.883722325614, 5815.0, 5830.300363956352, 5798.028670252134, 5815.499335285867, 5818.272715910512, 5931.487470694945, 5937.970310148422, 5645.915311270416, 5686.57350698691, 5713.885722285614, 5720.199172115824, 5722.942770572407, 5805.129230615273, 5807.506361959112, 5828.59199856006, 5832.512709259112, 5843.766249350148, 5487.573230959899, 5647.604667673223, 5707.743151558107, 5721.685307308138, 5754.18045157878], "value": 4469.311540034257, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [771.0, 822.7079386817684, 826.8511667899776, 866.4108406283726, 913.6299798581575, 17107.401240956555, 17772.64454710905, 17886.0, 17888.910555447223, 17913.46259612212, 16460.75308870365, 16579.66840663186, 16588.75116873248, 16601.750973735405, 16629.50111496657, 722.1622917415799, 740.0120838680341, 838.1199740272689, 855.5978690015713, 865.8571335729597, 881.7927786970057, 884.2218847414266, 887.1173182683207, 887.1394747095308, 887.5207388010499, 924.5839372282467, 925.2274350916992, 927.2628260532858, 927.5408672707019, 928.0070324752484, 756.3873262657238, 777.3120788102542, 892.6206362295998, 906.2840356118659, 922.3451349541833, 678.7352932356386, 679.3376457953506, 679.4157024958532, 679.5990365684221, 680.1667956752988, 16215.108169050698, 16234.350625974947, 16260.0, 16265.186740663037, 16270.186490675418, 14575.781363279559, 14659.706805863929, 14774.26128693561, 14867.776983345258, 14898.553043408716, 13640.976926730436, 13962.09246398991, 14009.159450433006, 14013.159210447406, 14296.642583935381, 4654.0924519718665, 4663.720176789404, 4677.953220467786, 4832.130216561018, 4873.780679869415, 85450.0, 86814.5659271706, 87681.0, 88775.0, 90136.54931725365, 11811.409429528661, 11945.0, 11983.64049078529, 12016.158868879094, 12297.139200255897, 22962.01916641457, 23170.536589268122, 23676.88161559181, 23677.17129900445, 23719.1698290559, 8522.57387130641, 8558.657653693906, 8563.529005904611, 8913.866292005625, 9077.727668169966, 4915.3855768029, 5014.5737612303365, 5053.873653158627, 5102.69383836971, 5238.895222095537, 3478.8260586971055, 3553.484744712054, 3577.3024260269517, 3583.0146709655055, 3592.568891733009, 620.6958590290725, 627.3350248736355, 627.3444250757968, 631.766246488802, 634.4131678197649, 641.7368878759686, 696.4846013949641, 697.4490152779248, 700.2017699822206, 700.5901547594714, 8724.214820666171, 8936.32977526688, 9449.480278584744, 9473.194778443773, 9693.903060969442, 560.2828379673942, 569.2268583362795, 618.6813790897692, 636.9426751592287, 661.4642139866723, 4688.4811274680915, 4712.976435117871, 4785.401824771903, 4842.346283251786, 4854.514548545118, 74354.0, 100661.0, 130327.34836325947, 133794.0, 134512.32743836218, 93517.5324123375, 114606.0, 116760.0, 117488.41255793837, 119562.0, 56269.71865140648, 58007.0, 81308.0, 93941.0, 96723.51638241764, 9918.900810991798, 10035.34770239932, 10290.742731431774, 10321.896781032394, 10365.585376585153, 52290.47709522856, 52925.73537132444, 53211.0, 53473.46526534685, 54028.459715403915, 72458.27541724515, 73242.26757732354, 79009.60495197718, 79929.0, 82130.0, 13489.258090804708, 13928.373223204777, 14278.143311401349, 14350.49773257931, 14454.783178252124, 15502.689946201239, 15904.12527311009, 16176.919115404347, 16286.0, 16577.66844663124, 10189.0, 14162.858371416567, 15011.624709381917, 15504.534863954099, 15604.297806598957, 13778.0, 14597.12417254968, 14961.40154393812, 15000.099993999958, 15410.845891540941, 14592.197429141075, 14596.270186490421, 14764.778528322298, 14988.100713956761, 15264.618384540476, 11679.824802627798, 12277.140600157903, 12435.689107772378, 12703.936480317909, 13184.077114602269, 12291.815622765844, 12536.0, 12782.42479088462, 13009.869901301245, 13243.0], "value": 7995.031214717407, "unit": "score"}], "name": "dromaeo_css", "value": 7614.779058421256, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 111.0, 111.0, 111.0, 110.0, 111.0, 110.0, 110.0, 110.0, 111.0], "value": 110.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 117.0, 117.0, 160.0, 118.0, 122.0, 117.0, 116.0, 151.0, 122.0], "value": 128.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 162.0, 161.0, 160.0, 162.0, 161.0, 160.0, 161.0, 161.0], "value": 161.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 76.0, 117.0, 77.0, 76.0, 76.0, 75.0, 96.0, 76.0], "value": 82.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 82.0, 107.0, 82.0, 81.0, 82.0, 104.0, 81.0, 81.0, 106.0], "value": 88.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 116.0, 116.0, 116.0, 117.0, 116.0, 117.0, 116.0, 116.0], "value": 116.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 147.0, 145.0, 147.0, 147.0, 145.0, 146.0, 145.0, 146.0], "value": 146.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 96.0, 95.0, 95.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [49.0, 50.0, 50.0, 50.0, 49.0, 49.0, 48.0, 50.0, 49.0, 50.0], "value": 49.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 59.0, 59.0, 60.0, 60.0, 59.0, 58.0, 60.0, 59.0, 59.0], "value": 59.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 74.0, 73.0, 87.0, 72.0, 72.0, 79.0, 73.0, 78.0, 76.0], "value": 75.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 120.0, 118.0, 118.0, 116.0, 116.0, 117.0, 119.0, 151.0, 122.0], "value": 121.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 152.0, 156.0, 152.0, 151.0, 150.0, 151.0, 151.0, 149.0, 152.0], "value": 151.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 70.0, 67.0, 61.0, 64.0, 62.0, 62.0, 62.0, 61.0, 61.0], "value": 63.3, "unit": "score"}], "name": "kraken", "value": 1449.5, "alertThreshold": 2.0}]} 13:15:07 INFO - Return code: 0 13:15:07 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 13:15:07 INFO - # TBPL SUCCESS # 13:15:07 INFO - Running post-action listener: _resource_record_post_action 13:15:07 INFO - [mozharness: 2016-09-20 20:15:07.938996Z] Finished run-tests step (success) 13:15:07 INFO - Running post-run listener: _resource_record_post_run 13:15:08 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 262144; Write bytes: 353157120; Read time: 20; Write time: 353644 13:15:08 INFO - TinderboxPrint: CPU usage
13.0% 13:15:08 INFO - TinderboxPrint: I/O read bytes / time
262,144 / 20 13:15:08 INFO - TinderboxPrint: I/O write bytes / time
353,157,120 / 353,644 13:15:08 INFO - TinderboxPrint: CPU idle
8,266.4 (87.0%) 13:15:08 INFO - TinderboxPrint: CPU user
1,182.7 (12.5%) 13:15:08 INFO - TinderboxPrint: Swap in / out
0 / 0 13:15:08 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 107323392; Read time: 0; Write time: 103812 13:15:08 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 262144; Write bytes: 245833728; Read time: 20; Write time: 249832 13:15:08 INFO - Running post-run listener: _upload_blobber_files 13:15:08 INFO - Blob upload gear active. 13:15:08 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 13:15:08 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 13:15:08 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'] 13:15:08 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 13:15:08 INFO - (blobuploader) - INFO - Open directory for files ... 13:15:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 13:15:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:15:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:15:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 13:15:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:15:09 INFO - (blobuploader) - INFO - Done attempting. 13:15:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 13:15:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:15:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:15:16 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 13:15:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:15:16 INFO - (blobuploader) - INFO - Done attempting. 13:15:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 13:15:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:15:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:15:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 13:15:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:15:17 INFO - (blobuploader) - INFO - Done attempting. 13:15:17 INFO - (blobuploader) - INFO - Iteration through files over. 13:15:17 INFO - Return code: 0 13:15:17 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 13:15:17 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 13:15:17 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ecd837dbb08482dd2eda81834730cb575491487d1db37df31e4c6cf3bcf2c76756b587f4c048000638588668672f924fb5c03aaaa0096f286ddbd7f0b1276b21", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c37662cf48f64ed5ece6f3af1b3de5f3f43efd0f66c51c99570510e241e1f5eb350c020406097d93172fd9a5a464ad80253b2ffa1f73a8a17fa5e012490d2af2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/45284986a73495747ebcb79f3812b76e1859aeb47dc8d39adfa009800de7f020255a493c9a7240c6a81f405758635ece1e1251a475ede1c6f8fda91e88da6fac"} 13:15:17 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 13:15:17 INFO - Writing to file /builds/slave/test/properties/blobber_files 13:15:17 INFO - Contents: 13:15:17 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ecd837dbb08482dd2eda81834730cb575491487d1db37df31e4c6cf3bcf2c76756b587f4c048000638588668672f924fb5c03aaaa0096f286ddbd7f0b1276b21", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c37662cf48f64ed5ece6f3af1b3de5f3f43efd0f66c51c99570510e241e1f5eb350c020406097d93172fd9a5a464ad80253b2ffa1f73a8a17fa5e012490d2af2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/45284986a73495747ebcb79f3812b76e1859aeb47dc8d39adfa009800de7f020255a493c9a7240c6a81f405758635ece1e1251a475ede1c6f8fda91e88da6fac"} 13:15:17 INFO - Running post-run listener: copy_logs_to_upload_dir 13:15:17 INFO - Copying logs to upload dir... 13:15:17 INFO - mkdir: /builds/slave/test/build/upload/logs 13:15:17 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1236.143865 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 36 secs) (at 2016-09-20 13:15:16.880262) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-20 13:15:16.884163) ========= 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=cbe409c6de289db6ac707de3000001d4-1474401277.584668-1243232512 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/ecd837dbb08482dd2eda81834730cb575491487d1db37df31e4c6cf3bcf2c76756b587f4c048000638588668672f924fb5c03aaaa0096f286ddbd7f0b1276b21", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c37662cf48f64ed5ece6f3af1b3de5f3f43efd0f66c51c99570510e241e1f5eb350c020406097d93172fd9a5a464ad80253b2ffa1f73a8a17fa5e012490d2af2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/45284986a73495747ebcb79f3812b76e1859aeb47dc8d39adfa009800de7f020255a493c9a7240c6a81f405758635ece1e1251a475ede1c6f8fda91e88da6fac"} build_url:https://queue.taskcluster.net/v1/task/G2kD2NPnS2OGw3At7WAMQg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011412 build_url: 'https://queue.taskcluster.net/v1/task/G2kD2NPnS2OGw3At7WAMQg/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/ecd837dbb08482dd2eda81834730cb575491487d1db37df31e4c6cf3bcf2c76756b587f4c048000638588668672f924fb5c03aaaa0096f286ddbd7f0b1276b21", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c37662cf48f64ed5ece6f3af1b3de5f3f43efd0f66c51c99570510e241e1f5eb350c020406097d93172fd9a5a464ad80253b2ffa1f73a8a17fa5e012490d2af2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/45284986a73495747ebcb79f3812b76e1859aeb47dc8d39adfa009800de7f020255a493c9a7240c6a81f405758635ece1e1251a475ede1c6f8fda91e88da6fac"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-20 13:15:16.910124) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 13:15:16.910479) ========= 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=cbe409c6de289db6ac707de3000001d4-1474401277.584668-1243232512 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004496 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 13:15:16.995912) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-20 13:15:16.996280) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-20 13:15:16.996646) ========= ========= Total master_lag: 0.88 =========