builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-021 starttime: 1474497873.99 results: success (0) buildid: 20160921124659 builduid: ffd6c3952c5e4b7eb4d6c070f87f6379 revision: 573d1489613418ddff1f26b6352e6ce6fc9a6839 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-21 15:44:33.990658) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-21 15:44:33.991208) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-21 15:44:33.991505) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-21 15:44:34.008987) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-21 15:44:34.009395) ========= 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=df17445c3590ff7897617a0e00000225-1474496057.196679-798627917 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-21 15:44:34-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 40.0M=0s 2016-09-21 15:44:34 (40.0 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.090080 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-21 15:44:34.113582) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-21 15:44:34.113911) ========= 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=df17445c3590ff7897617a0e00000225-1474496057.196679-798627917 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017777 ========= master_lag: 0.06 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-21 15:44:34.188975) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-21 15:44:34.189365) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 573d1489613418ddff1f26b6352e6ce6fc9a6839 --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 573d1489613418ddff1f26b6352e6ce6fc9a6839 --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=df17445c3590ff7897617a0e00000225-1474496057.196679-798627917 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-21 15:44:34,326 truncating revision to first 12 chars 2016-09-21 15:44:34,326 Setting DEBUG logging. 2016-09-21 15:44:34,326 attempt 1/10 2016-09-21 15:44:34,326 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/573d14896134?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-21 15:44:34,637 unpacking tar archive at: mozilla-aurora-573d14896134/testing/mozharness/ program finished with exit code 0 elapsedTime=0.620956 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-21 15:44:34.825396) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-21 15:44:34.825738) ========= script_repo_revision: 573d1489613418ddff1f26b6352e6ce6fc9a6839 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-21 15:44:34.826186) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-21 15:44:34.826474) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-21 15:44:34.837525) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 35 secs) (at 2016-09-21 15:44:34.837942) ========= /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=df17445c3590ff7897617a0e00000225-1474496057.196679-798627917 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:44:35 INFO - MultiFileLogger online at 20160921 15:44:35 in /builds/slave/test 15:44:35 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 15:44:35 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:44:35 INFO - {'append_to_log': False, 15:44:35 INFO - 'base_work_dir': '/builds/slave/test', 15:44:35 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 15:44:35 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:44:35 INFO - 'branch': 'Mozilla-Aurora', 15:44:35 INFO - 'buildbot_json_path': 'buildprops.json', 15:44:35 INFO - 'config_files': ('talos/linux_config.py',), 15:44:35 INFO - 'default_actions': ('clobber', 15:44:35 INFO - 'read-buildbot-config', 15:44:35 INFO - 'download-and-extract', 15:44:35 INFO - 'populate-webroot', 15:44:35 INFO - 'create-virtualenv', 15:44:35 INFO - 'install', 15:44:35 INFO - 'run-tests'), 15:44:35 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:44:35 INFO - 'download_minidump_stackwalk': True, 15:44:35 INFO - 'download_symbols': 'ondemand', 15:44:35 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:44:35 INFO - 'tooltool.py': '/tools/tooltool.py', 15:44:35 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:44:35 INFO - '/tools/misc-python/virtualenv.py')}, 15:44:35 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:44:35 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:44:35 INFO - 'installer_path': 'installer.exe', 15:44:35 INFO - 'log_level': 'info', 15:44:35 INFO - 'log_name': 'talos', 15:44:35 INFO - 'log_to_console': True, 15:44:35 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:44:35 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:44:35 INFO - 'opt_config_files': (), 15:44:35 INFO - 'pip_index': False, 15:44:35 INFO - 'sps_profile': False, 15:44:35 INFO - 'sps_profile_interval': 0, 15:44:35 INFO - 'suite': 'dromaeojs', 15:44:35 INFO - 'system_bits': '32', 15:44:35 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:44:35 INFO - 'title': 'talos-linux64-ix-021', 15:44:35 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:44:35 INFO - 'use_talos_json': True, 15:44:35 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:44:35 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:44:35 INFO - 'work_dir': 'build'} 15:44:35 INFO - [mozharness: 2016-09-21 22:44:35.071138Z] Running clobber step. 15:44:35 INFO - Running pre-action listener: _resource_record_pre_action 15:44:35 INFO - Running main action method: clobber 15:44:35 INFO - rmtree: /builds/slave/test/build 15:44:35 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:44:35 INFO - Running post-action listener: _resource_record_post_action 15:44:35 INFO - [mozharness: 2016-09-21 22:44:35.526129Z] Finished clobber step (success) 15:44:35 INFO - [mozharness: 2016-09-21 22:44:35.526231Z] Running read-buildbot-config step. 15:44:35 INFO - Running pre-action listener: _resource_record_pre_action 15:44:35 INFO - Running main action method: read_buildbot_config 15:44:35 INFO - Using buildbot properties: 15:44:35 INFO - { 15:44:35 INFO - "project": "", 15:44:35 INFO - "product": "firefox", 15:44:35 INFO - "script_repo_revision": "production", 15:44:35 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 15:44:35 INFO - "repository": "", 15:44:35 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 15:44:35 INFO - "buildid": "20160921124659", 15:44:35 INFO - "pgo_build": "True", 15:44:35 INFO - "basedir": "/builds/slave/test", 15:44:35 INFO - "buildnumber": 484, 15:44:35 INFO - "slavename": "talos-linux64-ix-021", 15:44:35 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 15:44:35 INFO - "platform": "ubuntu64_hw", 15:44:35 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 15:44:35 INFO - "branch": "mozilla-aurora", 15:44:35 INFO - "repo_path": "releases/mozilla-aurora", 15:44:35 INFO - "revision": "573d1489613418ddff1f26b6352e6ce6fc9a6839", 15:44:35 INFO - "stage_platform": "linux64-pgo", 15:44:35 INFO - "builduid": "ffd6c3952c5e4b7eb4d6c070f87f6379", 15:44:35 INFO - "slavebuilddir": "test" 15:44:35 INFO - } 15:44:35 INFO - Found installer url https://queue.taskcluster.net/v1/task/FAFL-AgiTsWl9dkPR4yY7A/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2. 15:44:35 INFO - Running post-action listener: _resource_record_post_action 15:44:35 INFO - [mozharness: 2016-09-21 22:44:35.528671Z] Finished read-buildbot-config step (success) 15:44:35 INFO - [mozharness: 2016-09-21 22:44:35.528762Z] Running download-and-extract step. 15:44:35 INFO - Running pre-action listener: _resource_record_pre_action 15:44:35 INFO - Running main action method: download_and_extract 15:44:35 INFO - mkdir: /builds/slave/test/build/tests 15:44:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:44:35 INFO - trying https://queue.taskcluster.net/v1/task/FAFL-AgiTsWl9dkPR4yY7A/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 15:44:35 INFO - Downloading https://queue.taskcluster.net/v1/task/FAFL-AgiTsWl9dkPR4yY7A/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 15:44:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FAFL-AgiTsWl9dkPR4yY7A/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 15:44:36 INFO - Downloaded 1361 bytes. 15:44:36 INFO - Reading from file /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 15:44:36 INFO - Using the following test package requirements: 15:44:36 INFO - {u'common': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 15:44:36 INFO - u'cppunittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 15:44:36 INFO - u'firefox-51.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 15:44:36 INFO - u'gtest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 15:44:36 INFO - u'firefox-51.0a2.en-US.linux-x86_64.gtest.tests.zip'], 15:44:36 INFO - u'jittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 15:44:36 INFO - u'jsshell-linux-x86_64.zip'], 15:44:36 INFO - u'mochitest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 15:44:36 INFO - u'firefox-51.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 15:44:36 INFO - u'mozbase': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 15:44:36 INFO - u'reftest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 15:44:36 INFO - u'firefox-51.0a2.en-US.linux-x86_64.reftest.tests.zip'], 15:44:36 INFO - u'talos': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 15:44:36 INFO - u'firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'], 15:44:36 INFO - u'web-platform': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 15:44:36 INFO - u'firefox-51.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 15:44:36 INFO - u'xpcshell': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 15:44:36 INFO - u'firefox-51.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 15:44:36 INFO - Downloading packages: [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 15:44:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FAFL-AgiTsWl9dkPR4yY7A/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 15:44:36 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FAFL-AgiTsWl9dkPR4yY7A/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 15:44:36 INFO - Fetch https://queue.taskcluster.net/v1/task/FAFL-AgiTsWl9dkPR4yY7A/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 15:44:36 INFO - Expected file size: 21894260 15:44:41 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 15:44:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FAFL-AgiTsWl9dkPR4yY7A/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 15:44:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FAFL-AgiTsWl9dkPR4yY7A/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 15:44:41 INFO - Fetch https://queue.taskcluster.net/v1/task/FAFL-AgiTsWl9dkPR4yY7A/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 15:44:41 INFO - Expected file size: 21894260 15:44:46 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FAFL-AgiTsWl9dkPR4yY7A/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip 15:44:46 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FAFL-AgiTsWl9dkPR4yY7A/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 15:44:46 INFO - Fetch https://queue.taskcluster.net/v1/task/FAFL-AgiTsWl9dkPR4yY7A/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip into memory 15:44:46 INFO - Expected file size: 14414059 15:44:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:44:47 INFO - trying https://queue.taskcluster.net/v1/task/FAFL-AgiTsWl9dkPR4yY7A/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 15:44:47 INFO - Downloading https://queue.taskcluster.net/v1/task/FAFL-AgiTsWl9dkPR4yY7A/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 15:44:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FAFL-AgiTsWl9dkPR4yY7A/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 15:44:48 INFO - Downloaded 63948372 bytes. 15:44:48 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/FAFL-AgiTsWl9dkPR4yY7A/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 15:44:48 INFO - mkdir: /builds/slave/test/properties 15:44:48 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:44:48 INFO - Writing to file /builds/slave/test/properties/build_url 15:44:48 INFO - Contents: 15:44:48 INFO - build_url:https://queue.taskcluster.net/v1/task/FAFL-AgiTsWl9dkPR4yY7A/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 15:44:49 INFO - Running post-action listener: _resource_record_post_action 15:44:49 INFO - Running post-action listener: set_extra_try_arguments 15:44:49 INFO - [mozharness: 2016-09-21 22:44:49.135697Z] Finished download-and-extract step (success) 15:44:49 INFO - [mozharness: 2016-09-21 22:44:49.135997Z] Running populate-webroot step. 15:44:49 INFO - Running pre-action listener: _resource_record_pre_action 15:44:49 INFO - Running main action method: populate_webroot 15:44:49 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:44:49 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:44:49 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:44:49 INFO - '--tppagecycles', 15:44:49 INFO - '7'], 15:44:49 INFO - 'tests': ['tsvgm']}}, 15:44:49 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:44:49 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:44:49 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:44:49 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:44:49 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:44:49 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:44:49 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:44:49 INFO - 'talos_options': ['--disable-e10s'], 15:44:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:44:49 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:44:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:44:49 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:44:49 INFO - 'talos_options': ['--disable-e10s'], 15:44:49 INFO - 'tests': ['damp', 'tps']}, 15:44:49 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:44:49 INFO - 'tests': ['damp', 'tps']}, 15:44:49 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:44:49 INFO - 'tests': ['dromaeo_dom']}, 15:44:49 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:44:49 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:44:49 INFO - 'tests': ['basic_compositor_video']}, 15:44:49 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:44:49 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:44:49 INFO - 'tests': ['a11yr', 15:44:49 INFO - 'ts_paint', 15:44:49 INFO - 'tpaint', 15:44:49 INFO - 'sessionrestore', 15:44:49 INFO - 'sessionrestore_no_auto_restore', 15:44:49 INFO - 'tabpaint']}, 15:44:49 INFO - 'other-e10s': {'tests': ['a11yr', 15:44:49 INFO - 'ts_paint', 15:44:49 INFO - 'tpaint', 15:44:49 INFO - 'sessionrestore', 15:44:49 INFO - 'sessionrestore_no_auto_restore', 15:44:49 INFO - 'tabpaint']}, 15:44:49 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:44:49 INFO - 'ts_paint', 15:44:49 INFO - 'tpaint', 15:44:49 INFO - 'sessionrestore', 15:44:49 INFO - 'sessionrestore_no_auto_restore', 15:44:49 INFO - 'tabpaint']}, 15:44:49 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:44:49 INFO - 'ts_paint', 15:44:49 INFO - 'tpaint', 15:44:49 INFO - 'sessionrestore', 15:44:49 INFO - 'sessionrestore_no_auto_restore', 15:44:49 INFO - 'tabpaint']}, 15:44:49 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:44:49 INFO - 'tests': ['a11yr', 15:44:49 INFO - 'ts_paint', 15:44:49 INFO - 'tpaint', 15:44:49 INFO - 'sessionrestore', 15:44:49 INFO - 'sessionrestore_no_auto_restore', 15:44:49 INFO - 'tabpaint']}, 15:44:49 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:44:49 INFO - 'tests': ['a11yr', 15:44:49 INFO - 'ts_paint', 15:44:49 INFO - 'tpaint', 15:44:49 INFO - 'sessionrestore', 15:44:49 INFO - 'sessionrestore_no_auto_restore', 15:44:49 INFO - 'tabpaint']}, 15:44:49 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:44:49 INFO - 'tests': ['tsvgx', 15:44:49 INFO - 'tsvgr_opacity', 15:44:49 INFO - 'tart', 15:44:49 INFO - 'tscrollx', 15:44:49 INFO - 'cart']}, 15:44:49 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:44:49 INFO - 'tsvgr_opacity', 15:44:49 INFO - 'tart', 15:44:49 INFO - 'tscrollx', 15:44:49 INFO - 'cart']}, 15:44:49 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:44:49 INFO - 'talos_options': ['--disable-e10s'], 15:44:49 INFO - 'tests': ['tp5o']}, 15:44:49 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:44:49 INFO - 'tests': ['tp5o']}, 15:44:49 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:44:49 INFO - 'talos_options': ['--disable-e10s', 15:44:49 INFO - '--xperf_path', 15:44:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:44:49 INFO - 'tests': ['tp5n']}, 15:44:49 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:44:49 INFO - 'talos_options': ['--xperf_path', 15:44:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:44:49 INFO - 'tests': ['tp5n']}}, 15:44:49 INFO - 'talos.zip': {'path': '', 15:44:49 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:44:49 INFO - Running post-action listener: _resource_record_post_action 15:44:49 INFO - [mozharness: 2016-09-21 22:44:49.156770Z] Finished populate-webroot step (success) 15:44:49 INFO - [mozharness: 2016-09-21 22:44:49.156863Z] Running create-virtualenv step. 15:44:49 INFO - Running pre-action listener: _resource_record_pre_action 15:44:49 INFO - Running main action method: create_virtualenv 15:44:49 INFO - Creating virtualenv /builds/slave/test/build/venv 15:44:49 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 15:44:49 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:44:49 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:44:49 INFO - Using real prefix '/usr' 15:44:49 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:44:49 INFO - Installing distribute.............................................................................................................................................................................................done. 15:44:51 INFO - Installing pip.................done. 15:44:51 INFO - Return code: 0 15:44:51 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:44:51 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')]} 15:44:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:44:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:44:51 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')]} 15:44:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:44:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:44:51 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 0x7f362bb0df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ebe8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f8bca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f88f60>, '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 0x1f89d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f8a1d0>, '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': 'df17445c3590ff7897617a0e00000225-1474496057.196679-798627917', '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 15:44:51 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 15:44:51 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 15:44:51 INFO - Using env: {'DISPLAY': ':0', 15:44:51 INFO - 'HOME': '/home/cltbld', 15:44:51 INFO - 'LANG': 'en_US.UTF-8', 15:44:51 INFO - 'LANGUAGE': 'en_US:en', 15:44:51 INFO - 'LOGNAME': 'cltbld', 15:44:51 INFO - 'MAIL': '/var/mail/cltbld', 15:44:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:44:51 INFO - 'MOZ_NO_REMOTE': '1', 15:44:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:44:51 INFO - 'NO_EM_RESTART': '1', 15:44:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:44:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:44:51 INFO - 'PWD': '/builds/slave/test', 15:44:51 INFO - 'SHELL': '/bin/bash', 15:44:51 INFO - 'SHLVL': '1', 15:44:51 INFO - 'TERM': 'linux', 15:44:51 INFO - 'TMOUT': '86400', 15:44:51 INFO - 'USER': 'cltbld', 15:44:51 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1474496057.196679-798627917', 15:44:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:44:51 INFO - '_': '/tools/buildbot/bin/python'} 15:44:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:44:51 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 15:44:51 INFO - Cleaning up... 15:44:51 INFO - Return code: 0 15:44:51 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:44:51 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')]} 15:44:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:44:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:44:51 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')]} 15:44:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:44:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:44:51 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 0x7f362bb0df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ebe8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f8bca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f88f60>, '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 0x1f89d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f8a1d0>, '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': 'df17445c3590ff7897617a0e00000225-1474496057.196679-798627917', '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 15:44:51 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 15:44:51 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 15:44:51 INFO - Using env: {'DISPLAY': ':0', 15:44:51 INFO - 'HOME': '/home/cltbld', 15:44:51 INFO - 'LANG': 'en_US.UTF-8', 15:44:51 INFO - 'LANGUAGE': 'en_US:en', 15:44:51 INFO - 'LOGNAME': 'cltbld', 15:44:51 INFO - 'MAIL': '/var/mail/cltbld', 15:44:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:44:51 INFO - 'MOZ_NO_REMOTE': '1', 15:44:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:44:51 INFO - 'NO_EM_RESTART': '1', 15:44:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:44:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:44:51 INFO - 'PWD': '/builds/slave/test', 15:44:51 INFO - 'SHELL': '/bin/bash', 15:44:51 INFO - 'SHLVL': '1', 15:44:51 INFO - 'TERM': 'linux', 15:44:51 INFO - 'TMOUT': '86400', 15:44:51 INFO - 'USER': 'cltbld', 15:44:51 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1474496057.196679-798627917', 15:44:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:44:51 INFO - '_': '/tools/buildbot/bin/python'} 15:44:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:44:52 INFO - Downloading/unpacking psutil>=3.1.1 15:44:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:44:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:44:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:44:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:44:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:44:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:44:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:44:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:44:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:44:54 INFO - Installing collected packages: psutil 15:44:54 INFO - Running setup.py install for psutil 15:44:54 INFO - building 'psutil._psutil_linux' extension 15:44:54 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 15:44:54 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 15:44:54 INFO - building 'psutil._psutil_posix' extension 15:44:54 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 15:44:54 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 15:44:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:44:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:44:54 INFO - Successfully installed psutil 15:44:54 INFO - Cleaning up... 15:44:54 INFO - Return code: 0 15:44:54 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:44:54 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')]} 15:44:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:44:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:44:54 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')]} 15:44:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:44:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:44:54 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 0x7f362bb0df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ebe8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f8bca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f88f60>, '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 0x1f89d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f8a1d0>, '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': 'df17445c3590ff7897617a0e00000225-1474496057.196679-798627917', '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 15:44:54 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 15:44:54 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 15:44:54 INFO - Using env: {'DISPLAY': ':0', 15:44:54 INFO - 'HOME': '/home/cltbld', 15:44:54 INFO - 'LANG': 'en_US.UTF-8', 15:44:54 INFO - 'LANGUAGE': 'en_US:en', 15:44:54 INFO - 'LOGNAME': 'cltbld', 15:44:54 INFO - 'MAIL': '/var/mail/cltbld', 15:44:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:44:54 INFO - 'MOZ_NO_REMOTE': '1', 15:44:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:44:54 INFO - 'NO_EM_RESTART': '1', 15:44:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:44:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:44:54 INFO - 'PWD': '/builds/slave/test', 15:44:54 INFO - 'SHELL': '/bin/bash', 15:44:54 INFO - 'SHLVL': '1', 15:44:54 INFO - 'TERM': 'linux', 15:44:54 INFO - 'TMOUT': '86400', 15:44:54 INFO - 'USER': 'cltbld', 15:44:54 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1474496057.196679-798627917', 15:44:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:44:54 INFO - '_': '/tools/buildbot/bin/python'} 15:44:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:44:54 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:44:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:44:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:44:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:44:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:44:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:44:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:44:56 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:44:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:44:56 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:44:56 INFO - Installing collected packages: mozsystemmonitor 15:44:56 INFO - Running setup.py install for mozsystemmonitor 15:44:56 INFO - Successfully installed mozsystemmonitor 15:44:56 INFO - Cleaning up... 15:44:56 INFO - Return code: 0 15:44:56 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:44:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:44:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:44:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:44:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:44:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:44:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:44:56 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 0x7f362bb0df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ebe8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f8bca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f88f60>, '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 0x1f89d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f8a1d0>, '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': 'df17445c3590ff7897617a0e00000225-1474496057.196679-798627917', '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 15:44:56 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 15:44:56 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 15:44:56 INFO - Using env: {'DISPLAY': ':0', 15:44:56 INFO - 'HOME': '/home/cltbld', 15:44:56 INFO - 'LANG': 'en_US.UTF-8', 15:44:56 INFO - 'LANGUAGE': 'en_US:en', 15:44:56 INFO - 'LOGNAME': 'cltbld', 15:44:56 INFO - 'MAIL': '/var/mail/cltbld', 15:44:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:44:56 INFO - 'MOZ_NO_REMOTE': '1', 15:44:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:44:56 INFO - 'NO_EM_RESTART': '1', 15:44:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:44:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:44:56 INFO - 'PWD': '/builds/slave/test', 15:44:56 INFO - 'SHELL': '/bin/bash', 15:44:56 INFO - 'SHLVL': '1', 15:44:56 INFO - 'TERM': 'linux', 15:44:56 INFO - 'TMOUT': '86400', 15:44:56 INFO - 'USER': 'cltbld', 15:44:57 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1474496057.196679-798627917', 15:44:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:44:57 INFO - '_': '/tools/buildbot/bin/python'} 15:44:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:44:57 INFO - Downloading/unpacking jsonschema==2.5.1 15:44:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:44:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:44:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:44:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:44:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:44:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:44:59 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:44:59 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:44:59 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 15:44:59 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 15:44:59 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 15:44:59 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 15:44:59 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 15:44:59 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 15:44:59 INFO - Downloading functools32-3.2.3-2.tar.gz 15:44:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:44:59 INFO - warning: no files found matching '*.txt' 15:44:59 INFO - no previously-included directories found matching 'build' 15:44:59 INFO - no previously-included directories found matching 'dist' 15:44:59 INFO - no previously-included directories found matching '.git*' 15:44:59 INFO - Installing collected packages: jsonschema, functools32 15:44:59 INFO - Running setup.py install for functools32 15:44:59 INFO - warning: no files found matching '*.txt' 15:44:59 INFO - no previously-included directories found matching 'build' 15:44:59 INFO - no previously-included directories found matching 'dist' 15:44:59 INFO - no previously-included directories found matching '.git*' 15:44:59 INFO - Successfully installed jsonschema functools32 15:44:59 INFO - Cleaning up... 15:44:59 INFO - Return code: 0 15:44:59 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:44:59 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')]} 15:44:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:44:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:44:59 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')]} 15:44:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:44:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:44:59 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 0x7f362bb0df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ebe8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f8bca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f88f60>, '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 0x1f89d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f8a1d0>, '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': 'df17445c3590ff7897617a0e00000225-1474496057.196679-798627917', '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 15:44:59 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 15:44:59 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 15:44:59 INFO - Using env: {'DISPLAY': ':0', 15:44:59 INFO - 'HOME': '/home/cltbld', 15:44:59 INFO - 'LANG': 'en_US.UTF-8', 15:44:59 INFO - 'LANGUAGE': 'en_US:en', 15:44:59 INFO - 'LOGNAME': 'cltbld', 15:44:59 INFO - 'MAIL': '/var/mail/cltbld', 15:44:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:44:59 INFO - 'MOZ_NO_REMOTE': '1', 15:44:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:44:59 INFO - 'NO_EM_RESTART': '1', 15:44:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:44:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:44:59 INFO - 'PWD': '/builds/slave/test', 15:44:59 INFO - 'SHELL': '/bin/bash', 15:44:59 INFO - 'SHLVL': '1', 15:44:59 INFO - 'TERM': 'linux', 15:44:59 INFO - 'TMOUT': '86400', 15:44:59 INFO - 'USER': 'cltbld', 15:44:59 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1474496057.196679-798627917', 15:44:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:44:59 INFO - '_': '/tools/buildbot/bin/python'} 15:44:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:44:59 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:44:59 INFO - Cleaning up... 15:44:59 INFO - Return code: 0 15:44:59 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:44:59 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')]} 15:44:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:44:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:44:59 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')]} 15:44:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:44:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:44:59 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 0x7f362bb0df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ebe8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f8bca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f88f60>, '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 0x1f89d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f8a1d0>, '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': 'df17445c3590ff7897617a0e00000225-1474496057.196679-798627917', '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 15:44:59 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 15:44:59 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 15:44:59 INFO - Using env: {'DISPLAY': ':0', 15:44:59 INFO - 'HOME': '/home/cltbld', 15:44:59 INFO - 'LANG': 'en_US.UTF-8', 15:44:59 INFO - 'LANGUAGE': 'en_US:en', 15:44:59 INFO - 'LOGNAME': 'cltbld', 15:44:59 INFO - 'MAIL': '/var/mail/cltbld', 15:44:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:44:59 INFO - 'MOZ_NO_REMOTE': '1', 15:44:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:44:59 INFO - 'NO_EM_RESTART': '1', 15:44:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:44:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:44:59 INFO - 'PWD': '/builds/slave/test', 15:44:59 INFO - 'SHELL': '/bin/bash', 15:44:59 INFO - 'SHLVL': '1', 15:44:59 INFO - 'TERM': 'linux', 15:44:59 INFO - 'TMOUT': '86400', 15:44:59 INFO - 'USER': 'cltbld', 15:44:59 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1474496057.196679-798627917', 15:44:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:44:59 INFO - '_': '/tools/buildbot/bin/python'} 15:44:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:44:59 INFO - Downloading/unpacking blobuploader==1.2.4 15:44:59 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 15:44:59 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 15:44:59 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 15:44:59 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 15:44:59 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 15:44:59 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 15:45:01 INFO - Downloading blobuploader-1.2.4.tar.gz 15:45:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:45:01 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:45: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 15:45: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 15:45: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 15:45: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 15:45: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 15:45: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 15:45:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:45:02 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:45:02 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 15:45:02 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 15:45:02 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 15:45:02 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 15:45:02 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 15:45:02 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 15:45:02 INFO - Downloading docopt-0.6.1.tar.gz 15:45:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:45:02 INFO - Installing collected packages: blobuploader, requests, docopt 15:45:02 INFO - Running setup.py install for blobuploader 15:45:02 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:45:02 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:45:02 INFO - Running setup.py install for requests 15:45:03 INFO - Running setup.py install for docopt 15:45:03 INFO - Successfully installed blobuploader requests docopt 15:45:03 INFO - Cleaning up... 15:45:03 INFO - Return code: 0 15:45:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:45: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')]} 15:45:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:45:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:45: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')]} 15:45:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:45:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:45:03 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 0x7f362bb0df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ebe8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f8bca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f88f60>, '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 0x1f89d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f8a1d0>, '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': 'df17445c3590ff7897617a0e00000225-1474496057.196679-798627917', '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 15:45:03 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 15:45:03 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 15:45:03 INFO - Using env: {'DISPLAY': ':0', 15:45:03 INFO - 'HOME': '/home/cltbld', 15:45:03 INFO - 'LANG': 'en_US.UTF-8', 15:45:03 INFO - 'LANGUAGE': 'en_US:en', 15:45:03 INFO - 'LOGNAME': 'cltbld', 15:45:03 INFO - 'MAIL': '/var/mail/cltbld', 15:45:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:45:03 INFO - 'MOZ_NO_REMOTE': '1', 15:45:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:45:03 INFO - 'NO_EM_RESTART': '1', 15:45:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:45:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:45:03 INFO - 'PWD': '/builds/slave/test', 15:45:03 INFO - 'SHELL': '/bin/bash', 15:45:03 INFO - 'SHLVL': '1', 15:45:03 INFO - 'TERM': 'linux', 15:45:03 INFO - 'TMOUT': '86400', 15:45:03 INFO - 'USER': 'cltbld', 15:45:03 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1474496057.196679-798627917', 15:45:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:45:03 INFO - '_': '/tools/buildbot/bin/python'} 15:45:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:45:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:45:03 INFO - Running setup.py (path:/tmp/pip-sl_Fpj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:45:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:45:03 INFO - Running setup.py (path:/tmp/pip-UbLx65-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:45:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:45:03 INFO - Running setup.py (path:/tmp/pip-otdckB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:45:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:45:03 INFO - Running setup.py (path:/tmp/pip-H7lkQy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:45:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:45:03 INFO - Running setup.py (path:/tmp/pip-9oXN03-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:45:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:45:03 INFO - Running setup.py (path:/tmp/pip-nE6DHV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:45:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:45:03 INFO - Running setup.py (path:/tmp/pip-UUTNj0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:45:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:45:03 INFO - Running setup.py (path:/tmp/pip-Bs2la_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:45:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:45:04 INFO - Running setup.py (path:/tmp/pip-FUZMgF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:45:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:45:04 INFO - Running setup.py (path:/tmp/pip-MqamV3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:45:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:45:04 INFO - Running setup.py (path:/tmp/pip-NWHf3J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:45:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:45:04 INFO - Running setup.py (path:/tmp/pip-iukiwN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:45:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:45:04 INFO - Running setup.py (path:/tmp/pip-Sn7laW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:45:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:45:04 INFO - Running setup.py (path:/tmp/pip-puK2N6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:45:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:45:04 INFO - Running setup.py (path:/tmp/pip-3Z8Bjp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:45:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:45:04 INFO - Running setup.py (path:/tmp/pip-sgaYoK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:45:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:45:04 INFO - Running setup.py (path:/tmp/pip-n8JQ37-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:45:04 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:45:04 INFO - Running setup.py install for manifestparser 15:45:04 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:45:04 INFO - Running setup.py install for mozcrash 15:45:04 INFO - Running setup.py install for mozdebug 15:45:05 INFO - Running setup.py install for mozdevice 15:45:05 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:45:05 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:45:05 INFO - Running setup.py install for mozfile 15:45:05 INFO - Running setup.py install for mozhttpd 15:45:05 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:45:05 INFO - Running setup.py install for mozinfo 15:45:05 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:45:05 INFO - Running setup.py install for mozInstall 15:45:05 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:45:05 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:45:05 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:45:05 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:45:05 INFO - Running setup.py install for mozleak 15:45:05 INFO - Running setup.py install for mozlog 15:45:05 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:45:05 INFO - Running setup.py install for moznetwork 15:45:05 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:45:05 INFO - Running setup.py install for mozprocess 15:45:05 INFO - Running setup.py install for mozprofile 15:45:06 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:45:06 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:45:06 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:45:06 INFO - Running setup.py install for mozrunner 15:45:06 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:45:06 INFO - Running setup.py install for mozscreenshot 15:45:06 INFO - Running setup.py install for moztest 15:45:06 INFO - Running setup.py install for mozversion 15:45:06 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:45:06 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:45:06 INFO - Cleaning up... 15:45:06 INFO - Return code: 0 15:45:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:45: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')]} 15:45:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:45:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:45: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')]} 15:45:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:45:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:45:06 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 0x7f362bb0df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ebe8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f8bca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f88f60>, '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 0x1f89d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f8a1d0>, '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': 'df17445c3590ff7897617a0e00000225-1474496057.196679-798627917', '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 15:45:06 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 15:45:06 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 15:45:06 INFO - Using env: {'DISPLAY': ':0', 15:45:06 INFO - 'HOME': '/home/cltbld', 15:45:06 INFO - 'LANG': 'en_US.UTF-8', 15:45:06 INFO - 'LANGUAGE': 'en_US:en', 15:45:06 INFO - 'LOGNAME': 'cltbld', 15:45:06 INFO - 'MAIL': '/var/mail/cltbld', 15:45:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:45:06 INFO - 'MOZ_NO_REMOTE': '1', 15:45:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:45:06 INFO - 'NO_EM_RESTART': '1', 15:45:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:45:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:45:06 INFO - 'PWD': '/builds/slave/test', 15:45:06 INFO - 'SHELL': '/bin/bash', 15:45:06 INFO - 'SHLVL': '1', 15:45:06 INFO - 'TERM': 'linux', 15:45:06 INFO - 'TMOUT': '86400', 15:45:06 INFO - 'USER': 'cltbld', 15:45:06 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1474496057.196679-798627917', 15:45:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:45:06 INFO - '_': '/tools/buildbot/bin/python'} 15:45:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:45:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:45:06 INFO - Running setup.py (path:/tmp/pip-7uCYLF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:45:06 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)) 15:45:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:45:06 INFO - Running setup.py (path:/tmp/pip-7VsHxw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:45:06 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)) 15:45:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:45:06 INFO - Running setup.py (path:/tmp/pip-zfYJZv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:45:06 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)) 15:45:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:45:06 INFO - Running setup.py (path:/tmp/pip-ty8Ta8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:45:07 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)) 15:45:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:45:07 INFO - Running setup.py (path:/tmp/pip-JK7hoA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:45:07 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)) 15:45:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:45:07 INFO - Running setup.py (path:/tmp/pip-5yVR0o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:45:07 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)) 15:45:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:45:07 INFO - Running setup.py (path:/tmp/pip-1sTGnC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:45:07 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)) 15:45:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:45:07 INFO - Running setup.py (path:/tmp/pip-smGVTB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:45:07 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)) 15:45:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:45:07 INFO - Running setup.py (path:/tmp/pip-SnT44D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:45:07 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)) 15:45:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:45:07 INFO - Running setup.py (path:/tmp/pip-cis6kE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:45:07 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)) 15:45:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:45:07 INFO - Running setup.py (path:/tmp/pip-ymWEs2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:45:07 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)) 15:45:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:45:07 INFO - Running setup.py (path:/tmp/pip-4GqmhH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:45:07 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)) 15:45:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:45:07 INFO - Running setup.py (path:/tmp/pip-nM6O1D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:45:07 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)) 15:45:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:45:07 INFO - Running setup.py (path:/tmp/pip-_k4dHE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:45:07 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)) 15:45:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:45:07 INFO - Running setup.py (path:/tmp/pip-8eu22q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:45:07 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)) 15:45:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:45:07 INFO - Running setup.py (path:/tmp/pip-FOzoGS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:45:07 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)) 15:45:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:45:07 INFO - Running setup.py (path:/tmp/pip-m3OiCw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:45:07 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)) 15:45:07 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)) 15:45:07 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)) 15:45:07 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)) 15:45:07 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)) 15:45:07 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)) 15:45:07 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:45:07 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 15:45:07 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 15:45:07 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 15:45:07 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 15:45:07 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 15:45:07 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 15:45:09 INFO - Downloading blessings-1.6.tar.gz 15:45:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:45:09 INFO - Installing collected packages: blessings 15:45:09 INFO - Running setup.py install for blessings 15:45:10 INFO - Successfully installed blessings 15:45:10 INFO - Cleaning up... 15:45:10 INFO - Return code: 0 15:45:10 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:45:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:45:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:45:10 INFO - Reading from file tmpfile_stdout 15:45:10 INFO - Current package versions: 15:45:10 INFO - argparse == 1.2.1 15:45:10 INFO - blessings == 1.6 15:45:10 INFO - blobuploader == 1.2.4 15:45:10 INFO - docopt == 0.6.1 15:45:10 INFO - functools32 == 3.2.3-2 15:45:10 INFO - jsonschema == 2.5.1 15:45:10 INFO - manifestparser == 1.1 15:45:10 INFO - mozInstall == 1.12 15:45:10 INFO - mozcrash == 0.17 15:45:10 INFO - mozdebug == 0.1 15:45:10 INFO - mozdevice == 0.48 15:45:10 INFO - mozfile == 1.2 15:45:10 INFO - mozhttpd == 0.7 15:45:10 INFO - mozinfo == 0.9 15:45:10 INFO - mozleak == 0.1 15:45:10 INFO - mozlog == 3.4 15:45:10 INFO - moznetwork == 0.27 15:45:10 INFO - mozprocess == 0.23 15:45:10 INFO - mozprofile == 0.28 15:45:10 INFO - mozrunner == 6.12 15:45:10 INFO - mozscreenshot == 0.1 15:45:10 INFO - mozsystemmonitor == 0.3 15:45:10 INFO - moztest == 0.7 15:45:10 INFO - mozversion == 1.4 15:45:10 INFO - psutil == 3.1.1 15:45:10 INFO - requests == 1.2.3 15:45:10 INFO - wsgiref == 0.1.2 15:45:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:45:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:45:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:45:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:45:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:45:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:45:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:45:10 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 0x7f362bb0df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ebe8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f8bca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f88f60>, '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 0x1f89d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f8a1d0>, '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': 'df17445c3590ff7897617a0e00000225-1474496057.196679-798627917', '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 15:45:10 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 15:45:10 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 15:45:10 INFO - Using env: {'DISPLAY': ':0', 15:45:10 INFO - 'HOME': '/home/cltbld', 15:45:10 INFO - 'LANG': 'en_US.UTF-8', 15:45:10 INFO - 'LANGUAGE': 'en_US:en', 15:45:10 INFO - 'LOGNAME': 'cltbld', 15:45:10 INFO - 'MAIL': '/var/mail/cltbld', 15:45:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:45:10 INFO - 'MOZ_NO_REMOTE': '1', 15:45:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:45:10 INFO - 'NO_EM_RESTART': '1', 15:45:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:45:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:45:10 INFO - 'PWD': '/builds/slave/test', 15:45:10 INFO - 'SHELL': '/bin/bash', 15:45:10 INFO - 'SHLVL': '1', 15:45:10 INFO - 'TERM': 'linux', 15:45:10 INFO - 'TMOUT': '86400', 15:45:10 INFO - 'USER': 'cltbld', 15:45:10 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1474496057.196679-798627917', 15:45:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:45:10 INFO - '_': '/tools/buildbot/bin/python'} 15:45:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:45:10 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)) 15:45:10 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)) 15:45:10 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)) 15:45:10 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)) 15:45:10 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)) 15:45:10 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)) 15:45:10 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)) 15:45:10 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)) 15:45:10 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)) 15:45:10 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)) 15:45:10 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)) 15:45:10 INFO - Cleaning up... 15:45:10 INFO - Return code: 0 15:45:10 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:45:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:45:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:45:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:45:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:45:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:45:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:45:10 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 0x7f362bb0df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ebe8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f8bca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f88f60>, '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 0x1f89d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f8a1d0>, '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': 'df17445c3590ff7897617a0e00000225-1474496057.196679-798627917', '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 15:45:10 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 15:45:10 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 15:45:10 INFO - Using env: {'DISPLAY': ':0', 15:45:10 INFO - 'HOME': '/home/cltbld', 15:45:10 INFO - 'LANG': 'en_US.UTF-8', 15:45:10 INFO - 'LANGUAGE': 'en_US:en', 15:45:10 INFO - 'LOGNAME': 'cltbld', 15:45:10 INFO - 'MAIL': '/var/mail/cltbld', 15:45:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:45:10 INFO - 'MOZ_NO_REMOTE': '1', 15:45:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:45:10 INFO - 'NO_EM_RESTART': '1', 15:45:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:45:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:45:10 INFO - 'PWD': '/builds/slave/test', 15:45:10 INFO - 'SHELL': '/bin/bash', 15:45:10 INFO - 'SHLVL': '1', 15:45:10 INFO - 'TERM': 'linux', 15:45:10 INFO - 'TMOUT': '86400', 15:45:10 INFO - 'USER': 'cltbld', 15:45:10 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1474496057.196679-798627917', 15:45:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:45:10 INFO - '_': '/tools/buildbot/bin/python'} 15:45:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:45:10 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:45:10 INFO - Cleaning up... 15:45:10 INFO - Return code: 0 15:45:10 INFO - Running post-action listener: _resource_record_post_action 15:45:10 INFO - Running post-action listener: _start_resource_monitoring 15:45:10 INFO - Starting resource monitoring. 15:45:10 INFO - [mozharness: 2016-09-21 22:45:10.820886Z] Finished create-virtualenv step (success) 15:45:10 INFO - [mozharness: 2016-09-21 22:45:10.821460Z] Running install step. 15:45:10 INFO - Running pre-action listener: _resource_record_pre_action 15:45:10 INFO - Running main action method: install 15:45:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:45:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:45:11 INFO - Reading from file tmpfile_stdout 15:45:11 INFO - Detecting whether we're running mozinstall >=1.0... 15:45:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:45:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:45:11 INFO - Reading from file tmpfile_stdout 15:45:11 INFO - Output received: 15:45:11 INFO - Usage: mozinstall [options] installer 15:45:11 INFO - Options: 15:45:11 INFO - -h, --help show this help message and exit 15:45:11 INFO - -d DEST, --destination=DEST 15:45:11 INFO - Directory to install application into. [default: 15:45:11 INFO - "/builds/slave/test"] 15:45:11 INFO - --app=APP Application being installed. [default: firefox] 15:45:11 INFO - mkdir: /builds/slave/test/build/application 15:45:11 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'] 15:45:11 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 15:45:27 INFO - Reading from file tmpfile_stdout 15:45:27 INFO - Output received: 15:45:27 INFO - /builds/slave/test/build/application/firefox/firefox 15:45:27 INFO - Running post-action listener: _resource_record_post_action 15:45:27 INFO - [mozharness: 2016-09-21 22:45:27.064587Z] Finished install step (success) 15:45:27 INFO - [mozharness: 2016-09-21 22:45:27.064834Z] Running run-tests step. 15:45:27 INFO - Running pre-action listener: _resource_record_pre_action 15:45:27 INFO - Running main action method: run_tests 15:45:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:45:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:45:27 INFO - Python 2.7.3 15:45:27 INFO - Return code: 0 15:45:27 INFO - grabbing minidump binary from tooltool 15:45:27 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')]} 15:45:27 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 0x1f88f60>, '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 0x1f89d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f8a1d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:45:27 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 15:45:27 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 15:45:27 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 15:45:27 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:45:27 INFO - Return code: 0 15:45:27 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 15:45:27 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:45:27 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:45:27 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 15:45:27 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:45:27 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/FAFL-AgiTsWl9dkPR4yY7A/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-021', '--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 15:45:27 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/FAFL-AgiTsWl9dkPR4yY7A/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-021 --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 15:45:27 INFO - Using env: {'DISPLAY': ':0', 15:45:27 INFO - 'HOME': '/home/cltbld', 15:45:27 INFO - 'LANG': 'en_US.UTF-8', 15:45:27 INFO - 'LANGUAGE': 'en_US:en', 15:45:27 INFO - 'LOGNAME': 'cltbld', 15:45:27 INFO - 'MAIL': '/var/mail/cltbld', 15:45:27 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:45:27 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:45:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:45:27 INFO - 'MOZ_NO_REMOTE': '1', 15:45:27 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:45:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:45:27 INFO - 'NO_EM_RESTART': '1', 15:45:27 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:45:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:45:27 INFO - 'PWD': '/builds/slave/test', 15:45:27 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:45:27 INFO - 'SHELL': '/bin/bash', 15:45:27 INFO - 'SHLVL': '1', 15:45:27 INFO - 'TERM': 'linux', 15:45:27 INFO - 'TMOUT': '86400', 15:45:27 INFO - 'USER': 'cltbld', 15:45:27 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1474496057.196679-798627917', 15:45:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:45:27 INFO - '_': '/tools/buildbot/bin/python'} 15:45:27 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/FAFL-AgiTsWl9dkPR4yY7A/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-021', '--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 15:45:27 INFO - mozversion application_buildid: 20160921124659 15:45:27 INFO - mozversion application_changeset: 573d1489613418ddff1f26b6352e6ce6fc9a6839 15:45:27 INFO - mozversion application_display_name: FirefoxDeveloperEdition 15:45:27 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:45:27 INFO - mozversion application_name: Firefox 15:45:27 INFO - mozversion application_remotingname: firefox-dev 15:45:27 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 15:45:27 INFO - mozversion application_vendor: Mozilla 15:45:27 INFO - mozversion application_version: 51.0a2 15:45:27 INFO - mozversion platform_buildid: 20160921124659 15:45:27 INFO - mozversion platform_changeset: 573d1489613418ddff1f26b6352e6ce6fc9a6839 15:45:27 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 15:45:27 INFO - mozversion platform_version: 51.0a2 15:45:27 INFO - using testdate: 1474497927 15:45:27 INFO - actual date: 1474497927 15:45:27 INFO - starting webserver on 'localhost:43182' 15:45:27 INFO - SUITE-START | Running 2 tests 15:45:27 INFO - TEST-START | dromaeo_css 15:45:27 INFO - operating with platform_type : linux_ 15:45:27 INFO - Initialising browser for dromaeo_css test... 15:45:27 INFO - TEST-INFO | started process 17343 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpPB1_6Z/profile http://localhost:43182/getInfo.html) 15:45:32 INFO - PROCESS | 17343 | __metrics Screen width/height:1600/1200 15:45:32 INFO - PROCESS | 17343 | colorDepth:24 15:45:32 INFO - PROCESS | 17343 | Browser inner width/height: 1024/697 15:45:32 INFO - PROCESS | 17343 | __metrics 15:45:32 INFO - PROCESS | 17343 | 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" 15:45:32 INFO - PROCESS | 17343 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:45:33 INFO - PROCESS | 17343 | console.error: PushService: 15:45:33 INFO - PROCESS | 17343 | stateChangeProcessEnqueue: Error transitioning state 15:45:33 INFO - PROCESS | 17343 | UnknownError 15:45:34 INFO - TEST-INFO | 17343: exit 0 15:45:34 INFO - Browser initialized. 15:45:34 INFO - Running cycle 1/1 for dromaeo_css test... 15:45:34 INFO - TEST-INFO | started process 17438 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpPB1_6Z/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:45:45 INFO - PROCESS | 17438 | RSS: Main: 144732160 15:45:45 INFO - PROCESS | 17438 | 15:45:56 INFO - PROCESS | 17438 | [GFX1-]: Refresh driver waiting for the compositor for 1.0006 seconds. 15:46:04 INFO - PROCESS | 17438 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 15:46:07 INFO - PROCESS | 17438 | [GFX1-]: Refresh driver waiting for the compositor for 1.01649 seconds. 15:46:36 INFO - PROCESS | 17438 | 1474497996974 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] 15:46:43 INFO - PROCESS | 17438 | [GFX1-]: Refresh driver waiting for the compositor for 1.00052 seconds. 15:46:57 INFO - PROCESS | 17438 | [GFX1-]: Refresh driver waiting for the compositor for 1.01605 seconds. 15:46:59 INFO - PROCESS | 17438 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 15:47:00 INFO - PROCESS | 17438 | [GFX1-]: Refresh driver waiting for the compositor for 2.06633 seconds. 15:47:12 INFO - PROCESS | 17438 | [GFX1-]: Refresh driver waiting for the compositor for 1.01662 seconds. 15:47:16 INFO - PROCESS | 17438 | [GFX1-]: Refresh driver waiting for the compositor for 1.0165 seconds. 15:47:18 INFO - PROCESS | 17438 | [GFX1-]: Refresh driver waiting for the compositor for 1.16733 seconds. 15:47:30 INFO - PROCESS | 17438 | [GFX1-]: Refresh driver waiting for the compositor for 1.03273 seconds. 15:47:45 INFO - PROCESS | 17438 | [GFX1-]: Refresh driver waiting for the compositor for 1.03288 seconds. 15:48:08 INFO - PROCESS | 17438 | [GFX1-]: Refresh driver waiting for the compositor for 1.00059 seconds. 15:48:31 INFO - PROCESS | 17438 | [GFX1-]: Refresh driver waiting for the compositor for 1.01647 seconds. 15:48:37 INFO - PROCESS | 17438 | [GFX1-]: Refresh driver waiting for the compositor for 2.20013 seconds. 15:48:46 INFO - PROCESS | 17438 | Cycle 1(1): loaded http://localhost:43182/tests/dromaeo/cssquery-dojo.html (next: http://localhost:43182/tests/dromaeo/cssquery-ext.html) 15:48:47 INFO - PROCESS | 17438 | RSS: Main: 242278400 15:48:47 INFO - PROCESS | 17438 | 15:48:50 INFO - PROCESS | 17438 | [GFX1-]: Refresh driver waiting for the compositor for 1.01666 seconds. 15:49:26 INFO - PROCESS | 17438 | [GFX1-]: Refresh driver waiting for the compositor for 1.00011 seconds. 15:49:50 INFO - PROCESS | 17438 | [GFX1-]: Refresh driver waiting for the compositor for 1.00022 seconds. 15:49:59 INFO - PROCESS | 17438 | [GFX1-]: Refresh driver waiting for the compositor for 1.01619 seconds. 15:50:10 INFO - PROCESS | 17438 | [GFX1-]: Refresh driver waiting for the compositor for 1.00062 seconds. 15:50:24 INFO - PROCESS | 17438 | [GFX1-]: Refresh driver waiting for the compositor for 1.00049 seconds. 15:50:32 INFO - PROCESS | 17438 | [GFX1-]: Refresh driver waiting for the compositor for 1.00035 seconds. 15:51:02 INFO - PROCESS | 17438 | [GFX1-]: Refresh driver waiting for the compositor for 1.01664 seconds. 15:51:05 INFO - PROCESS | 17438 | [GFX1-]: Refresh driver waiting for the compositor for 1.00026 seconds. 15:51:12 INFO - PROCESS | 17438 | [GFX1-]: Refresh driver waiting for the compositor for 1.03351 seconds. 15:51:15 INFO - PROCESS | 17438 | [GFX1-]: Refresh driver waiting for the compositor for 1.00043 seconds. 15:51:23 INFO - PROCESS | 17438 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 15:51:29 INFO - PROCESS | 17438 | [GFX1-]: Refresh driver waiting for the compositor for 1.00036 seconds. 15:51:47 INFO - PROCESS | 17438 | [GFX1-]: Refresh driver waiting for the compositor for 1.00073 seconds. 15:51:49 INFO - PROCESS | 17438 | [GFX1-]: Refresh driver waiting for the compositor for 1.01684 seconds. 15:51:49 INFO - PROCESS | 17438 | Cycle 1(1): loaded http://localhost:43182/tests/dromaeo/cssquery-ext.html (next: http://localhost:43182/tests/dromaeo/cssquery-jquery.html) 15:51:50 INFO - PROCESS | 17438 | RSS: Main: 236339200 15:51:50 INFO - PROCESS | 17438 | 15:51:56 INFO - PROCESS | 17438 | [GFX1-]: Refresh driver waiting for the compositor for 1.00007 seconds. 15:52:12 INFO - PROCESS | 17438 | [GFX1-]: Refresh driver waiting for the compositor for 1.00085 seconds. 15:52:14 INFO - PROCESS | 17438 | [GFX1-]: Refresh driver waiting for the compositor for 1.01626 seconds. 15:52:42 INFO - PROCESS | 17438 | [GFX1-]: Refresh driver waiting for the compositor for 1.00054 seconds. 15:53:20 INFO - PROCESS | 17438 | [GFX1-]: Refresh driver waiting for the compositor for 1.01648 seconds. 15:53:22 INFO - PROCESS | 17438 | [GFX1-]: Refresh driver waiting for the compositor for 1.01663 seconds. 15:53:36 INFO - PROCESS | 17438 | [GFX1-]: Refresh driver waiting for the compositor for 1.0163 seconds. 15:53:38 INFO - PROCESS | 17438 | [GFX1-]: Refresh driver waiting for the compositor for 1.01617 seconds. 15:53:53 INFO - PROCESS | 17438 | [GFX1-]: Refresh driver waiting for the compositor for 1.01601 seconds. 15:54:07 INFO - PROCESS | 17438 | [GFX1-]: Refresh driver waiting for the compositor for 1.00012 seconds. 15:54:47 INFO - PROCESS | 17438 | [GFX1-]: Refresh driver waiting for the compositor for 1.01703 seconds. 15:54:51 INFO - PROCESS | 17438 | Cycle 1(1): loaded http://localhost:43182/tests/dromaeo/cssquery-jquery.html (next: http://localhost:43182/tests/dromaeo/cssquery-mootools.html) 15:54:52 INFO - PROCESS | 17438 | RSS: Main: 175177728 15:54:52 INFO - PROCESS | 17438 | 15:54:55 INFO - PROCESS | 17438 | [GFX1-]: Refresh driver waiting for the compositor for 1.01739 seconds. 15:55:09 INFO - PROCESS | 17438 | [GFX1-]: Refresh driver waiting for the compositor for 1.00058 seconds. 15:55:11 INFO - PROCESS | 17438 | [GFX1-]: Refresh driver waiting for the compositor for 1.00018 seconds. 15:55:16 INFO - PROCESS | 17438 | [GFX1-]: Refresh driver waiting for the compositor for 1.00016 seconds. 15:55:21 INFO - PROCESS | 17438 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 15:55:23 INFO - PROCESS | 17438 | [GFX1-]: Refresh driver waiting for the compositor for 1.0004 seconds. 15:55:38 INFO - PROCESS | 17438 | [GFX1-]: Refresh driver waiting for the compositor for 1.01748 seconds. 15:55:41 INFO - PROCESS | 17438 | [GFX1-]: Refresh driver waiting for the compositor for 1.01648 seconds. 15:56:01 INFO - PROCESS | 17438 | [GFX1-]: Refresh driver waiting for the compositor for 1.00042 seconds. 15:56:03 INFO - PROCESS | 17438 | [GFX1-]: Refresh driver waiting for the compositor for 1.00055 seconds. 15:56:19 INFO - PROCESS | 17438 | [GFX1-]: Refresh driver waiting for the compositor for 1.00038 seconds. 15:56:21 INFO - PROCESS | 17438 | [GFX1-]: Refresh driver waiting for the compositor for 1.00036 seconds. 15:56:38 INFO - PROCESS | 17438 | [GFX1-]: Refresh driver waiting for the compositor for 1.01665 seconds. 15:56:56 INFO - PROCESS | 17438 | [GFX1-]: Refresh driver waiting for the compositor for 1.00065 seconds. 15:57:02 INFO - PROCESS | 17438 | [GFX1-]: Refresh driver waiting for the compositor for 1.01644 seconds. 15:57:04 INFO - PROCESS | 17438 | [GFX1-]: Refresh driver waiting for the compositor for 1.01744 seconds. 15:57:26 INFO - PROCESS | 17438 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 15:57:34 INFO - PROCESS | 17438 | [GFX1-]: Refresh driver waiting for the compositor for 1.00009 seconds. 15:57:46 INFO - PROCESS | 17438 | [GFX1-]: Refresh driver waiting for the compositor for 1.01649 seconds. 15:57:54 INFO - PROCESS | 17438 | Cycle 1(1): loaded http://localhost:43182/tests/dromaeo/cssquery-mootools.html (next: http://localhost:43182/tests/dromaeo/cssquery-prototype.html) 15:57:55 INFO - PROCESS | 17438 | RSS: Main: 218771456 15:57:55 INFO - PROCESS | 17438 | 15:58:11 INFO - PROCESS | 17438 | [GFX1-]: Refresh driver waiting for the compositor for 1.00032 seconds. 15:58:15 INFO - PROCESS | 17438 | [GFX1-]: Refresh driver waiting for the compositor for 1.01733 seconds. 15:58:23 INFO - PROCESS | 17438 | [GFX1-]: Refresh driver waiting for the compositor for 1.00032 seconds. 15:58:25 INFO - PROCESS | 17438 | [GFX1-]: Refresh driver waiting for the compositor for 1.00056 seconds. 15:58:42 INFO - PROCESS | 17438 | [GFX1-]: Refresh driver waiting for the compositor for 1.00036 seconds. 15:58:47 INFO - PROCESS | 17438 | [GFX1-]: Refresh driver waiting for the compositor for 1.00019 seconds. 15:58:48 INFO - PROCESS | 17438 | [GFX1-]: Refresh driver waiting for the compositor for 1.03335 seconds. 15:58:50 INFO - PROCESS | 17438 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 15:58:52 INFO - PROCESS | 17438 | [GFX1-]: Refresh driver waiting for the compositor for 1.00016 seconds. 15:58:54 INFO - PROCESS | 17438 | [GFX1-]: Refresh driver waiting for the compositor for 1.00022 seconds. 15:58:58 INFO - PROCESS | 17438 | [GFX1-]: Refresh driver waiting for the compositor for 1.00051 seconds. 15:59:08 INFO - PROCESS | 17438 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 15:59:16 INFO - PROCESS | 17438 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 15:59:28 INFO - PROCESS | 17438 | [GFX1-]: Refresh driver waiting for the compositor for 1.0173 seconds. 15:59:47 INFO - PROCESS | 17438 | [GFX1-]: Refresh driver waiting for the compositor for 1.01651 seconds. 15:59:50 INFO - PROCESS | 17438 | [GFX1-]: Refresh driver waiting for the compositor for 1.01612 seconds. 15:59:57 INFO - PROCESS | 17438 | [GFX1-]: Refresh driver waiting for the compositor for 1.00014 seconds. 16:00:08 INFO - PROCESS | 17438 | [GFX1-]: Refresh driver waiting for the compositor for 1.00052 seconds. 16:00:16 INFO - PROCESS | 17438 | [GFX1-]: Refresh driver waiting for the compositor for 1.00022 seconds. 16:00:21 INFO - PROCESS | 17438 | [GFX1-]: Refresh driver waiting for the compositor for 1.00039 seconds. 16:00:37 INFO - PROCESS | 17438 | [GFX1-]: Refresh driver waiting for the compositor for 1.00035 seconds. 16:00:51 INFO - PROCESS | 17438 | [GFX1-]: Refresh driver waiting for the compositor for 1.0002 seconds. 16:00:57 INFO - PROCESS | 17438 | Cycle 1(1): loaded http://localhost:43182/tests/dromaeo/cssquery-prototype.html (next: http://localhost:43182/tests/dromaeo/cssquery-yui.html) 16:00:58 INFO - PROCESS | 17438 | RSS: Main: 175943680 16:00:58 INFO - PROCESS | 17438 | 16:01:01 INFO - PROCESS | 17438 | [GFX1-]: Refresh driver waiting for the compositor for 1.01667 seconds. 16:01:02 INFO - PROCESS | 17438 | [GFX1-]: Refresh driver waiting for the compositor for 1.0005 seconds. 16:01:06 INFO - PROCESS | 17438 | [GFX1-]: Refresh driver waiting for the compositor for 1.05042 seconds. 16:01:21 INFO - PROCESS | 17438 | [GFX1-]: Refresh driver waiting for the compositor for 1.0004 seconds. 16:01:27 INFO - PROCESS | 17438 | [GFX1-]: Refresh driver waiting for the compositor for 1.00025 seconds. 16:01:45 INFO - PROCESS | 17438 | [GFX1-]: Refresh driver waiting for the compositor for 1.01615 seconds. 16:01:49 INFO - PROCESS | 17438 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 16:01:51 INFO - PROCESS | 17438 | [GFX1-]: Refresh driver waiting for the compositor for 1.01716 seconds. 16:02:29 INFO - PROCESS | 17438 | [GFX1-]: Refresh driver waiting for the compositor for 1.00045 seconds. 16:02:43 INFO - PROCESS | 17438 | [GFX1-]: Refresh driver waiting for the compositor for 1.00051 seconds. 16:03:01 INFO - PROCESS | 17438 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 16:03:10 INFO - PROCESS | 17438 | [GFX1-]: Refresh driver waiting for the compositor for 1.01645 seconds. 16:03:20 INFO - PROCESS | 17438 | [GFX1-]: Refresh driver waiting for the compositor for 1.00022 seconds. 16:03:41 INFO - PROCESS | 17438 | [GFX1-]: Refresh driver waiting for the compositor for 1.01667 seconds. 16:03:46 INFO - PROCESS | 17438 | [GFX1-]: Refresh driver waiting for the compositor for 1.0003 seconds. 16:04:00 INFO - PROCESS | 17438 | Cycle 1(1): loaded http://localhost:43182/tests/dromaeo/cssquery-yui.html (next: http://localhost:43182/tests/dromaeo/cssquery-dojo.html) 16:04:01 INFO - PROCESS | 17438 | RSS: Main: 269250560 16:04:01 INFO - PROCESS | 17438 | 16:04:01 INFO - PROCESS | 17438 | __start_tp_report 16:04:01 INFO - PROCESS | 17438 | _x_x_mozilla_page_load 16:04:01 INFO - PROCESS | 17438 | _x_x_mozilla_page_load_details 16:04:01 INFO - PROCESS | 17438 | |i|pagename|runs| 16:04:01 INFO - PROCESS | 17438 | |0;dojo.html;3065.3562751822114;3099.7985130966485;3104.3636054608805;3122.3286993296438;3151.732102771264;5505.889882202354;5590.077637189869;5595.076812325961;5612.803551875685;5632.943670563298;5542.0855558832845;5576.191452239433;5584.553235741141;5613.578981576388;5615.775368985236;5014.097462456747;5049.798008079672;5072.771725272353;5114.462981386957;5130.666506677073;4852.393450818648;5084.008618319429;5145.125328694131;5152.124138896378;5152.845414637567;4821.951780482203;4988.725620090893;4998.2752500887345;5015.7492125393765;5029.2456131580375;5017.7491125443585;5043.899122017558;5045.192769156917;5109.6167787415725;5130.9230361544605;4962.727050012248;5036.924446133311;5042.218456139304;5063.898722025576;5087.79648814047;5331.173668081415;5376.569874410038;5385.434529374398;5451.454854514556;5482.0406428875185;5786.132080187964;5796.97101514494;5802.680852553108;5887.234659494281;5891.617044892068;5865.20819689345;5874.530037597025;5884.6763428011445;5887.0286402743495;5917.644941303535;5419.214213938957;5452.672839629635;5467.289252397202;5490.313710786152;5517.889642207133;6034.064719968412;6050.304215015241;6065.059915713072;6066.241719785027;6066.363031881677;5824.534037277008;5826.1552074948895;5828.067509198508;5828.504577110993;5832.562557808182;5707.857303567444;5782.739776710058;5800.88398232033;5870.706464676835;5879.2944846618675;5873.148393482921;5887.970560147171;5893.233879595625;5894.793682221101;5918.378570250148;5842.591018628656;5897.380775018647;5901.3213480450295;5905;5925.703714814328;4418.8453404130705;4449.065696203768;4526.841560545365;4561.429821272341;4563.247064234431;4035.9394609080887;4054.2094291612852;4066.877993660195;4086.223617512663;4107.301758701028;5686.800961966312;5687.886242275132;5690.516306113944;5692.430756924357;5700.515456186271;5762.510186634099;5776.653400795965;5894.911576326359;6015.639061656315;6016.338202797689;5652.519535839499;5656.575756818255;5679;5682.147677848356;5688.601797874192;5153.536181743586;5157.896842063138;5164.2770012198325;5166;5199.714015729096;5792.0732682771395;5946;6153.2000839890525;6153.815385538441;6160.260768707783;5909;5968.194293770285;5975.940240597538;5991.760329586942;6044.274687037584;6128.754849805958;6150.600210986271;6153.569250152446;6153.66154861469;6198.194234749454;5624.493795558419;5843.853903652443;5847.502962248256;5850.151727999331;5850.561207909339;5687.857803554944;5890.764369425175;5941.1385349125;5950.137230101697;6000.8799824004145;5780.566457515619;5848.181254624271;5849.444302791228;5854.736536855767;5865.648061116347;5775.422457754191;5825.475707186373;5826.41735826414;5827.3881242470625;5829.53363730892;5557.861053473695;5572.526335261548;5651.88696226065;5678.886422271614;5702.315722113207;5806.303243610793;5806.767729290781;5808.244928159313;5828.213191219131;5845.064789633809;5460.972695136499;5530.198121272473;5665.3201615806365;5669.319681638065;5669.518090962314 16:04:01 INFO - PROCESS | 17438 | |1;ext.html;561.1274468201945;591.4204080001598;621.9067139929011;624.0701354981077;645.9580127291729;35884.64115358849;39996.400053999176;47223.055538889246;47806;48350.032999339994;48442.51557484424;48774.51225487744;48815;48895.75552122243;48939.26591101127;9274.814503709938;9420.717378478657;9422.717318480456;9428.151466368026;9429.057094290585;8544.060153383123;8758.912410875906;11078.169137314693;11328.830067548994;11339.433028348549;11477.368744719037;11563.710907227345;11571.421428928563;11579.421028948562;11579.53681852725;11274.210805243636;11335;11357.091432685365;11360.60237891674;11362.522872026624;11064.944675276653;11086.944565277203;11136.387498687569;11136.721581960435;11157.832632510519;46876.765616172044;46997.76501117473;47193.29210061852;47198.528014719755;47235.5276447238;3732.1789206374556;4000.119973605802;4225.577442255781;4269.87190384289;4581.4502259728715;35598.644013559795;35794;35877.28245435077;35920;36091.81954090239;4665.393498845162;4736.668433209677;4741.668083234175;4761.9761751223305;4816.807327706888;368775;369648;369974;370553;371618;66943.66528167328;67339.66330168318;67374.32625673681;67412.662936685;67422.66288668623;41595.7920210397;42916;42953.78523107364;44809;44919.77540112279;18694;18858.434246972614;18913.05434728258;18914.527136821413;18932.24271029171;11698.590549330902;11966.940165299118;14185.503507377192;14387.064840785315;14394.856051439561;20687;21814.672779908313;21987.560248795253;22111.33665990023;22114.115435382726;7649.541027538366;7763.573003484749;7774;7775.961120194363;7934.166912474223;1003.2776400991274;1029.8867124616286;1030.1655658916302;1072.0673014477454;1125.763589646167;34553.65446345555;34918.30163396768;34982.82508587441;34987.30025399478;35099;1134.3704244144578;1161.8547681539808;1166.912481563886;1237.294741997071;1255.3974092435683;18225.362112326005;18457.723134153;18613.7207941881;18632.44102676922;18644.34744783926;613385;616714;620250;620744;621291;64228.67885660542;64537.677311613144;64667;64850.351496485375;64854.351456485776;1299.0127503097715;1334.8865346445466;1420.5667271482298;1447.6525633848012;1469.6619777451247;31144;36228.27543449169;36396.27207455783;36692.81653591715;36842.8157859209;57589.42410576008;58091.70954145202;58428.4157158423;58437.4156258432;58452;453396;456870;457561;458697;458709;31697.8415107923;32500;32932.83533582317;32974.5053824188;34080;52804.73597631987;53207.46792532025;53679.19481207707;53872.73063634657;54391;43529.56470435382;43808.78095609502;44362.334564980905;44666.776666116464;45522.54477455183;43010.35484467673;43065.354019690356;43109;44894.3265851006;44895.77552112219;44677;44768.55231447644;45421.54578454305;45518.54481455143;46011.3098303519;37244.627553724116;39801.312932131426;39958.60041399665;40207.59792402155;40415.1916961665;30173.54739678863;30899.382012359176;31893.809519090555;32055;32061.839690801397 16:04:01 INFO - PROCESS | 17438 | |2;jquery.html;391.4958403566962;414.2522746442672;427.96790240731946;429.3495354537875;440.79723327269454;15345.846541534582;19398;21173.68239476405;21276.89361553194;21479.46301342465;19545.12046957887;20205.696914546257;20301.289454869082;20675.483112922186;20715.964201789924;5784.103463963072;5800.593958422923;6003.819885403423;6011.158437818709;6011.75952961881;5843.18195452637;5885.20549725788;5914.585978981473;5932.940670593303;5935.436133567304;5767.653940763567;5800.564957628174;5889.941100589003;5895.852603684899;5897.144913987469;5827.854303642401;5839.941600584004;5850.707464626794;5873.618214816023;5906.793262235801;5228.895422091575;5284.233786101032;5285.418603953562;5300.178472336794;5346.064438723208;24308.635370469456;24312.149074782476;24488.265352039467;24499.38751531226;24742.628860567107;2491.750824917512;2528.936776580582;2601.8438893666253;2613.6994245661804;2656.3624730064644;14667.63330916725;15636.140012299318;15684.294206760724;15835.68328633421;16312.592185195346;1807.1235450806348;1833.330834245491;2004.9599008019904;2021.5148364392587;2064.6386882295537;88544.5572772132;88781.11218887793;90415;91965.54017229938;92376.53811730898;10640.89359106399;11767.352795596324;11895.5725312963;12035.217710848767;12346.938265308616;15795.842041579437;15891.125988070537;16673.749893751603;16704.83295167057;17369.392071277445;8735.563221838882;8832.205101540892;9003.604441311607;9096.90903090974;9100.408473449204;8684.652613895374;8740.519271440131;8751.343649226334;8793.768872357876;8971.50656713874;5106.591472682177;5178.404483484447;5195.220716892496;5223.582113430916;5247.737613119405;2008.8292495138078;2125.4473836802545;2143.7213162288804;2150.290404166621;2158.6222411077997;947.7677968897664;1019.4749703902496;1039.3140527252128;1051.3429106808244;1082.9620963266248;19775.40673779789;20473;20477.692834607493;20645.070971806304;20742.58514829695;1014.1785154025263;1031.3244824639874;1046.9371837689764;1055.36677993205;1071.8231491803922;10634.46827658614;11582.36297003656;11590.343367896894;11615.477303521362;11736.76526469483;513775;515060;523285;527810;532235;66123.33876661268;90745;92095.53952230196;109125;112631.4368428169;1036.383351905615;1046.9162467002623;1089.5206109311878;1090.4275255490772;1098.549594666183;18345.81654183441;18490.44528664142;18736.159087387325;18938.905305473385;19226.134823933236;45865.54134458746;46227.306590401844;46398.07203855836;46733.532664672915;46992.29511557261;59187;60697;63530;64446.35553644591;64634.353656464715;7424.406047516294;8279.95860020696;8513.659453621785;9164.404313719588;9676.12914837668;9287.442753434816;13070.01974851871;13303.46786128544;13368.866311336762;13403.128796628189;11436;11790.587329443428;11865.762684746083;12194.634160975185;12393.318367489514;11332.206745528063;11471.369074700971;11685.298882067103;11698.532058717556;12066.879331206575;10937.726556836144;11079.5568177272;11522.423878806194;11811.822822657496;12235.388230588613;8108.75673729789;8586.914130858611;8752.78118047054;8815.118488151134;8911.50986695712;10218.846717299099;11282.94358528235;11387.373694446886;11404.429778511207;11549.076073914235 16:04:01 INFO - PROCESS | 17438 | |3;mootools.html;1361.5302720561403;1546.6983938132062;1573.05616630022;1733.3846484498004;1840.8803427777186;8008.439409241349;8880.777980550489;9165.495897725623;9175.128362805539;9451.196648284904;9242.26061915047;9344.345895787297;9348.906510934889;9353.438793672385;9353.485558294291;2513.610390389488;2538.0482319130324;2544.567423537994;2544.656471376359;2545.732698066704;2461.5569197544437;2491.6636254105647;2508.24752574227;2515.346010037385;2515.949681006388;2479.814013948952;2493.7381574934643;2527.696676398835;2528.645989561463;2532.189699296226;2449.963250551243;2462.495188486356;2470.4441500662388;2487.4900645367657;2491.1405565080017;2429.79346755526;2447.339218411019;2461.88921498533;2461.90152393904;2464.383904023994;8966.327525435618;9110.908890911072;9300.953495232481;9308;9312.534373281376;5572.3034620672415;5697.515711164556;5716.771329146829;5728.828135155952;5736.713164341808;3622.2212224371624;3632.854685812564;3657.3416784978695;3674.0447483654175;3676.8345424455965;1248.1762037055591;1251.7246205834701;1256.0767835640888;1291.4511332683571;1297.6820678933723;12555.309457979807;12637.17858339205;12650.683732906658;12676.873231267662;12701.618951431472;4730.763461826895;4785.504876248944;4799.136155491975;4899.265110233494;4949.851504454872;10064.899351006396;10084.69745907624;10089.697309080739;10095.444750538643;10121.595136194617;5551.639143455663;5554.77780888768;5562.471565201299;5562.888742225133;5568.192612071227;4584.083183363341;4735.573798358164;5039.420466646383;5150.48495150482;5183.611229157828;4064.4309796628495;4090.652294554937;4161.479815023122;4163.97918010408;4208.873733787991;1217.9634610961684;1228.2139430764319;1234.6419538333782;1240.3612139748036;1243.055277988735;958.0850287974968;974.5663179884883;978.2418625565244;982.8525721141743;991.8710567626163;5848.64908105515;5941.702914854326;6033.728482218311;6078.817635470943;6131.356207598227;958.836997710391;980.9656662016795;994.5623925472769;1000.979980400388;1024.1601886453186;3837.213371258885;4075.959240407558;4077.91844163121;4111.280525908014;4127.33962565994;11398.94300528492;11934.104942129376;11944.283342999448;11948.940255298668;11958.16292859509;11788.528458861549;11878.227915185485;11879.88120118805;11897.70255743596;11899.286042837457;8227.506349619041;8242.835143297101;8322.334213262924;8322.70870519529;8672.8699069514;5961.52307815365;5996.730147143302;6087.512998959984;6153.815385538441;6184.072389141665;10704.09015233714;10731.624393146203;10755.677329680122;10764.515596798006;10771.730706732395;10336.844947325946;10558;10811.243212975016;10944.343339399662;10961.287516311733;7017.2631873652535;7274.127104747253;7524;7734.2265773422205;7940.880886786818;8796.648134074821;8836.602352894264;8842.778930526789;8867.955660221658;8871.955640221757;7916.881246781188;7930.643121059451;7963.243491868263;7982;8200.26197642215;7730.45886787942;7748.8450230996195;7767.53394796314;7805.960970195113;7850.842983140419;6777.627230502172;6857.759978400932;6859.142607174103;6867.484938629723;6871.072405225231;4928.507149285043;4934.605231581393;4937.308776771327;4950.702957822542;5170.146925757208;5245.895082098413;5273.28810610578;5291.3385826771655;5329.973350133225;5442.945570544244 16:04:01 INFO - PROCESS | 17438 | |4;prototype.html;345.55941175001874;349.57526605174706;353.67634640412484;360.59613233178845;361.2359858898429;5458.399576046631;5536.778528858841;5537.335519737627;5567.972160139194;5578.386377498477;5506.669599824003;5510.366307874596;5516.338039435263;5529.474699903502;5530.340897730684;4065.26825171469;4374.715643483163;4377.6716746244;4378.248700519785;4380.649548036149;3892.610738926113;4248.511421186557;4345.391645169679;4349.608535231844;4351.521332653406;4316.935245971313;4327.5070100815165;4333.068390296085;4335.43639326887;4340.218760623086;4169.145325208356;4330.826766929319;4348.412964249817;4354.303311470149;4357.9782101089295;3718.572364178127;3829.7127715421457;3865.536135663738;3870.651641352292;3889.630485103911;5336.91994620081;5361.2226227197225;5367.75845086972;5398.703071331075;5400.837974860721;4717.834875779331;4969.7515124244;4994.700317980933;4999.700017998895;5002;5767.452092051249;5795.391483894215;5804.187413762077;5807.767689292423;5811.186433899257;4239.894002649928;4240.109576988836;4242.5121111072;4245.617894389489;4253.21315556623;5846.941530584682;5988.251468566473;6032.547558933054;6068.362821903681;6071.785642871443;4221.472315960505;4225.133847561243;4227.471566054243;4230.2174097792;4231.597998190228;4280.250956082673;4284.421603083606;4287.256156060124;4287.585096918009;4288.914221715549;4784.497627749036;4909.288153217834;4923.384576927884;4924.532169443897;4930.654854160246;5747.224124743189;5794.768209271666;5796.275465566804;5812.244408226903;5827.388124246893;1487.7247709173837;1492.843251458603;1500.1074360755333;1508.8566586174295;1509.9924500377647;928.7538802217417;971.3297824501213;974.35205588283;981.5828272984096;986.3835103060587;4953.356063711766;4993.70037797726;5006.123928312531;5009.348784657971;5026.4973502649445;5217.8173763918085;5247.475252474798;5266.97366513165;5273.6572122811895;5280.759021629914;5019.6737212081825;5022.924656130161;5034.546890779848;5308.840734777963;5702.942970570324;3156.6369867465537;3178.968210317867;3181.8886338978023;3184.7611429142908;3195.8242296673907;6021.6989150542295;6123.4488895999575;6142.78500252498;6170.136180934673;6175.876482470326;6086.573939824259;6101.420365065312;6145.324014358417;6148.231471066117;6148.784792532329;5894.614765530097;6020.277566692025;6070.423309785563;6188.514756458508;6188.731310081423;5945.10823376497;5995.111539864929;6001.519878409804;6004.849878753066;6010.33886272501;5635.126555383922;5917.408259174048;5923.111533270044;5928.170056192223;5958.612690175125;6239.625622462667;6242.313345531897;6246.656433896179;6248.343923887926;6250.468710159687;4360.498542667634;4367.235733746518;4368.650507959419;4378.255696531661;4379.802908869045;5765.8558536036935;5914.852128696817;5967.791127310524;5987.940120598738;5988.221531200916;5865.384134665969;5878.178474332259;5897.5576831736935;5899.321578018411;5918.763249469973;5690.886182276248;5791.333996590446;5809.6347358371595;5887.8233652990475;5908.615939963888;5801;5815.476607105381;5816.621919575214;5824.767009319749;5825.592208545361;5894.6757928314355;5903.852403689943;5909.852253693692;5925.140854575976;5928.407159284037;5459.181122831607;5712.400197979152;5797.594168408171;5802.796902108406;5803.172000819728 16:04:01 INFO - PROCESS | 17438 | |5;yui.html;793.7261644732565;839.1776059461731;890.3678388344275;906.5234824448239;909.7407238936904;16498;17130.314787408504;17291.567710807238;17298.221580028898;17311.22099505522;15825.84174158258;17889.28442862284;18210.180541875616;18224.63550728988;18234.635307293847;870.4429165334191;888.2938064238913;893.0622846011694;893.7586851550078;894.2979760887705;888.1961824548762;888.6223355074068;890.6348397157167;891.6255172827396;895.1361935732017;933.1788026536639;941.8869735631733;942.194423767679;943.6555657185147;943.6697155995415;939.2532936315646;939.8825146856609;939.8872135343769;939.891912430069;940.3464592108487;692.0518889121885;693.0505207865207;695.2664938489891;697.285282585354;697.6999890047277;14654.706905861824;17786.822131778645;17951.281948722037;17982.460526184237;18082.728759068625;14931.178785166812;16017.919910400373;16301.429449969195;16595.585110372336;17174.055426951512;13369.131006484547;13400.128991615515;13487.595372138852;13641.386137623831;13662.726745465035;4044.3933409988445;4055.776932268724;4087.9386809197886;4113.1568028554075;4169.207850508394;82002.58998705087;82222.17777822205;84087;87617.56191219066;89441;12084.637460876187;12404.937975310246;12542.435590398454;12564;12739.936300318439;22805.88597057004;22862.885685571797;22900;22903;22923.426914327276;8568.271696905833;8785.253253473398;8803.603837827313;8825.161609647073;8919.554022298858;4939.456659767423;5127.359080114986;5222.973885130626;5259.211118332204;5314.97342513285;3585.6952159066773;3708.7774733516076;3709.0356507307756;3709.4435834624483;3719.683826874692;636.7771280051945;662.6752891083335;665.3778716899715;672.6199697171082;687.7936619014275;670.5473805181483;712.6436781609195;773.1340898194059;782.1162086841832;796.2674339607574;9727.902720972841;10342.482875856327;10638.680839574825;10661.733456663645;11079;546.5409056392649;644.1465058797113;644.2140588482044;675.3314218923231;706.1208795050195;5098.719570423588;5101.821436249714;5126.5898728101665;5132.666376685504;5191.2472691459525;133124;136290;139142;140092;142767.2861635706;70818;72306.63846680733;75116;115356.42321788338;117716.41141794236;77793;96288;96566;96822;98785.50607247061;10132.933533233383;10386.221033422373;10728.409937453514;10739.785204296026;10789.892101079202;53542;53976.730116349165;54093.18860217021;54375.184372233656;54391.18413223884;76070.61964690141;81810;84918.71480093803;85506.57246713726;88198;12623.30571818522;12931.676708082374;13036.608901732963;13171.012174087173;13231.206127632373;15095.698086037997;15148.848511514743;15385.769213461583;15896.602584935468;16488.25802838851;14434.566962991126;14530;15464.768028479357;15612;15671.921640391725;13582.93208533951;13872.514461993782;13909.165450073027;14025.438982440995;14038.578842634737;12903.419346129635;12931.482740690642;13043.934780326037;13286.734265314446;13367.06430549891;10775.191860610328;11116.21635119619;11189.328640281608;11212.37420573996;11642.068634509389;11633.827652134274;12244.510219591117;12250.571230006906;12478.937605311916;12703.42834572428 16:04:01 INFO - PROCESS | 17438 | __end_tp_report 16:04:01 INFO - PROCESS | 17438 | __start_cc_report 16:04:01 INFO - PROCESS | 17438 | _x_x_mozilla_cycle_collect,879 16:04:01 INFO - PROCESS | 17438 | __end_cc_report 16:04:01 INFO - PROCESS | 17438 | __startTimestamp1474499041146__endTimestamp 16:04:01 INFO - PROCESS | 17438 | 16:04:01 INFO - PROCESS | 17438 | ------- Summary: start ------- 16:04:01 INFO - PROCESS | 17438 | Number of tests: 6 16:04:01 INFO - PROCESS | 17438 | 16:04:01 INFO - PROCESS | 17438 | [#0] dojo.html Cycles:165 Average:5489.96 Median:5688.60 stddev:626.75 (11.0%) stddev-sans-first:599.12 16:04:01 INFO - PROCESS | 17438 | Values: 3065.4 3099.8 3104.4 3122.3 3151.7 5505.9 5590.1 5595.1 5612.8 5632.9 5542.1 5576.2 5584.6 5613.6 5615.8 5014.1 5049.8 5072.8 5114.5 5130.7 4852.4 5084.0 5145.1 5152.1 5152.8 4822.0 4988.7 4998.3 5015.7 5029.2 5017.7 5043.9 5045.2 5109.6 5130.9 4962.7 5036.9 5042.2 5063.9 5087.8 5331.2 5376.6 5385.4 5451.5 5482.0 5786.1 5797.0 5802.7 5887.2 5891.6 5865.2 5874.5 5884.7 5887.0 5917.6 5419.2 5452.7 5467.3 5490.3 5517.9 6034.1 6050.3 6065.1 6066.2 6066.4 5824.5 5826.2 5828.1 5828.5 5832.6 5707.9 5782.7 5800.9 5870.7 5879.3 5873.1 5888.0 5893.2 5894.8 5918.4 5842.6 5897.4 5901.3 5905.0 5925.7 4418.8 4449.1 4526.8 4561.4 4563.2 4035.9 4054.2 4066.9 4086.2 4107.3 5686.8 5687.9 5690.5 5692.4 5700.5 5762.5 5776.7 5894.9 6015.6 6016.3 5652.5 5656.6 5679.0 5682.1 5688.6 5153.5 5157.9 5164.3 5166.0 5199.7 5792.1 5946.0 6153.2 6153.8 6160.3 5909.0 5968.2 5975.9 5991.8 6044.3 6128.8 6150.6 6153.6 6153.7 6198.2 5624.5 5843.9 5847.5 5850.2 5850.6 5687.9 5890.8 5941.1 5950.1 6000.9 5780.6 5848.2 5849.4 5854.7 5865.6 5775.4 5825.5 5826.4 5827.4 5829.5 5557.9 5572.5 5651.9 5678.9 5702.3 5806.3 5806.8 5808.2 5828.2 5845.1 5461.0 5530.2 5665.3 5669.3 5669.5 16:04:01 INFO - PROCESS | 17438 | 16:04:01 INFO - PROCESS | 17438 | [#1] ext.html Cycles:180 Average:65638.01 Median:40311.39 stddev:130755.59 (324.4%) stddev-sans-first:131031.11 16:04:01 INFO - PROCESS | 17438 | Values: 561.1 591.4 621.9 624.1 646.0 35884.6 39996.4 47223.1 47806.0 48350.0 48442.5 48774.5 48815.0 48895.8 48939.3 9274.8 9420.7 9422.7 9428.2 9429.1 8544.1 8758.9 11078.2 11328.8 11339.4 11477.4 11563.7 11571.4 11579.4 11579.5 11274.2 11335.0 11357.1 11360.6 11362.5 11064.9 11086.9 11136.4 11136.7 11157.8 46876.8 46997.8 47193.3 47198.5 47235.5 3732.2 4000.1 4225.6 4269.9 4581.5 35598.6 35794.0 35877.3 35920.0 36091.8 4665.4 4736.7 4741.7 4762.0 4816.8 368775.0 369648.0 369974.0 370553.0 371618.0 66943.7 67339.7 67374.3 67412.7 67422.7 41595.8 42916.0 42953.8 44809.0 44919.8 18694.0 18858.4 18913.1 18914.5 18932.2 11698.6 11966.9 14185.5 14387.1 14394.9 20687.0 21814.7 21987.6 22111.3 22114.1 7649.5 7763.6 7774.0 7776.0 7934.2 1003.3 1029.9 1030.2 1072.1 1125.8 34553.7 34918.3 34982.8 34987.3 35099.0 1134.4 1161.9 1166.9 1237.3 1255.4 18225.4 18457.7 18613.7 18632.4 18644.3 613385.0 616714.0 620250.0 620744.0 621291.0 64228.7 64537.7 64667.0 64850.4 64854.4 1299.0 1334.9 1420.6 1447.7 1469.7 31144.0 36228.3 36396.3 36692.8 36842.8 57589.4 58091.7 58428.4 58437.4 58452.0 453396.0 456870.0 457561.0 458697.0 458709.0 31697.8 32500.0 32932.8 32974.5 34080.0 52804.7 53207.5 53679.2 53872.7 54391.0 43529.6 43808.8 44362.3 44666.8 45522.5 43010.4 43065.4 43109.0 44894.3 44895.8 44677.0 44768.6 45421.5 45518.5 46011.3 37244.6 39801.3 39958.6 40207.6 40415.2 30173.5 30899.4 31893.8 32055.0 32061.8 16:04:01 INFO - PROCESS | 17438 | 16:04:01 INFO - PROCESS | 17438 | [#2] jquery.html Cycles:180 Average:30912.58 Median:24310.39 stddev:86221.34 (354.7%) stddev-sans-first:86432.76 16:04:01 INFO - PROCESS | 17438 | Values: 391.5 414.3 428.0 429.3 440.8 15345.8 19398.0 21173.7 21276.9 21479.5 19545.1 20205.7 20301.3 20675.5 20716.0 5784.1 5800.6 6003.8 6011.2 6011.8 5843.2 5885.2 5914.6 5932.9 5935.4 5767.7 5800.6 5889.9 5895.9 5897.1 5827.9 5839.9 5850.7 5873.6 5906.8 5228.9 5284.2 5285.4 5300.2 5346.1 24308.6 24312.1 24488.3 24499.4 24742.6 2491.8 2528.9 2601.8 2613.7 2656.4 14667.6 15636.1 15684.3 15835.7 16312.6 1807.1 1833.3 2005.0 2021.5 2064.6 88544.6 88781.1 90415.0 91965.5 92376.5 10640.9 11767.4 11895.6 12035.2 12346.9 15795.8 15891.1 16673.7 16704.8 17369.4 8735.6 8832.2 9003.6 9096.9 9100.4 8684.7 8740.5 8751.3 8793.8 8971.5 5106.6 5178.4 5195.2 5223.6 5247.7 2008.8 2125.4 2143.7 2150.3 2158.6 947.8 1019.5 1039.3 1051.3 1083.0 19775.4 20473.0 20477.7 20645.1 20742.6 1014.2 1031.3 1046.9 1055.4 1071.8 10634.5 11582.4 11590.3 11615.5 11736.8 513775.0 515060.0 523285.0 527810.0 532235.0 66123.3 90745.0 92095.5 109125.0 112631.4 1036.4 1046.9 1089.5 1090.4 1098.5 18345.8 18490.4 18736.2 18938.9 19226.1 45865.5 46227.3 46398.1 46733.5 46992.3 59187.0 60697.0 63530.0 64446.4 64634.4 7424.4 8280.0 8513.7 9164.4 9676.1 9287.4 13070.0 13303.5 13368.9 13403.1 11436.0 11790.6 11865.8 12194.6 12393.3 11332.2 11471.4 11685.3 11698.5 12066.9 10937.7 11079.6 11522.4 11811.8 12235.4 8108.8 8586.9 8752.8 8815.1 8911.5 10218.8 11282.9 11387.4 11404.4 11549.1 16:04:01 INFO - PROCESS | 17438 | 16:04:01 INFO - PROCESS | 17438 | [#3] mootools.html Cycles:180 Average:6013.81 Median:4792.32 stddev:3432.45 (71.6%) stddev-sans-first:3424.27 16:04:01 INFO - PROCESS | 17438 | Values: 1361.5 1546.7 1573.1 1733.4 1840.9 8008.4 8880.8 9165.5 9175.1 9451.2 9242.3 9344.3 9348.9 9353.4 9353.5 2513.6 2538.0 2544.6 2544.7 2545.7 2461.6 2491.7 2508.2 2515.3 2515.9 2479.8 2493.7 2527.7 2528.6 2532.2 2450.0 2462.5 2470.4 2487.5 2491.1 2429.8 2447.3 2461.9 2461.9 2464.4 8966.3 9110.9 9301.0 9308.0 9312.5 5572.3 5697.5 5716.8 5728.8 5736.7 3622.2 3632.9 3657.3 3674.0 3676.8 1248.2 1251.7 1256.1 1291.5 1297.7 12555.3 12637.2 12650.7 12676.9 12701.6 4730.8 4785.5 4799.1 4899.3 4949.9 10064.9 10084.7 10089.7 10095.4 10121.6 5551.6 5554.8 5562.5 5562.9 5568.2 4584.1 4735.6 5039.4 5150.5 5183.6 4064.4 4090.7 4161.5 4164.0 4208.9 1218.0 1228.2 1234.6 1240.4 1243.1 958.1 974.6 978.2 982.9 991.9 5848.6 5941.7 6033.7 6078.8 6131.4 958.8 981.0 994.6 1001.0 1024.2 3837.2 4076.0 4077.9 4111.3 4127.3 11398.9 11934.1 11944.3 11948.9 11958.2 11788.5 11878.2 11879.9 11897.7 11899.3 8227.5 8242.8 8322.3 8322.7 8672.9 5961.5 5996.7 6087.5 6153.8 6184.1 10704.1 10731.6 10755.7 10764.5 10771.7 10336.8 10558.0 10811.2 10944.3 10961.3 7017.3 7274.1 7524.0 7734.2 7940.9 8796.6 8836.6 8842.8 8868.0 8872.0 7916.9 7930.6 7963.2 7982.0 8200.3 7730.5 7748.8 7767.5 7806.0 7850.8 6777.6 6857.8 6859.1 6867.5 6871.1 4928.5 4934.6 4937.3 4950.7 5170.1 5245.9 5273.3 5291.3 5330.0 5442.9 16:04:01 INFO - PROCESS | 17438 | 16:04:01 INFO - PROCESS | 17438 | [#4] prototype.html Cycles:180 Average:4861.11 Median:5482.93 stddev:1425.39 (26.0%) stddev-sans-first:1388.51 16:04:01 INFO - PROCESS | 17438 | Values: 345.6 349.6 353.7 360.6 361.2 5458.4 5536.8 5537.3 5568.0 5578.4 5506.7 5510.4 5516.3 5529.5 5530.3 4065.3 4374.7 4377.7 4378.2 4380.6 3892.6 4248.5 4345.4 4349.6 4351.5 4316.9 4327.5 4333.1 4335.4 4340.2 4169.1 4330.8 4348.4 4354.3 4358.0 3718.6 3829.7 3865.5 3870.7 3889.6 5336.9 5361.2 5367.8 5398.7 5400.8 4717.8 4969.8 4994.7 4999.7 5002.0 5767.5 5795.4 5804.2 5807.8 5811.2 4239.9 4240.1 4242.5 4245.6 4253.2 5846.9 5988.3 6032.5 6068.4 6071.8 4221.5 4225.1 4227.5 4230.2 4231.6 4280.3 4284.4 4287.3 4287.6 4288.9 4784.5 4909.3 4923.4 4924.5 4930.7 5747.2 5794.8 5796.3 5812.2 5827.4 1487.7 1492.8 1500.1 1508.9 1510.0 928.8 971.3 974.4 981.6 986.4 4953.4 4993.7 5006.1 5009.3 5026.5 5217.8 5247.5 5267.0 5273.7 5280.8 5019.7 5022.9 5034.5 5308.8 5702.9 3156.6 3179.0 3181.9 3184.8 3195.8 6021.7 6123.4 6142.8 6170.1 6175.9 6086.6 6101.4 6145.3 6148.2 6148.8 5894.6 6020.3 6070.4 6188.5 6188.7 5945.1 5995.1 6001.5 6004.8 6010.3 5635.1 5917.4 5923.1 5928.2 5958.6 6239.6 6242.3 6246.7 6248.3 6250.5 4360.5 4367.2 4368.7 4378.3 4379.8 5765.9 5914.9 5967.8 5987.9 5988.2 5865.4 5878.2 5897.6 5899.3 5918.8 5690.9 5791.3 5809.6 5887.8 5908.6 5801.0 5815.5 5816.6 5824.8 5825.6 5894.7 5903.9 5909.9 5925.1 5928.4 5459.2 5712.4 5797.6 5802.8 5803.2 16:04:01 INFO - PROCESS | 17438 | 16:04:01 INFO - PROCESS | 17438 | [#5] yui.html Cycles:180 Average:22521.03 Median:3882.04 stddev:33035.31 (851.0%) stddev-sans-first:33087.70 16:04:01 INFO - PROCESS | 17438 | Values: 793.7 839.2 890.4 906.5 909.7 16498.0 17130.3 17291.6 17298.2 17311.2 15825.8 17889.3 18210.2 18224.6 18234.6 870.4 888.3 893.1 893.8 894.3 888.2 888.6 890.6 891.6 895.1 933.2 941.9 942.2 943.7 943.7 939.3 939.9 939.9 939.9 940.3 692.1 693.1 695.3 697.3 697.7 14654.7 17786.8 17951.3 17982.5 18082.7 14931.2 16017.9 16301.4 16595.6 17174.1 13369.1 13400.1 13487.6 13641.4 13662.7 4044.4 4055.8 4087.9 4113.2 4169.2 82002.6 82222.2 84087.0 87617.6 89441.0 12084.6 12404.9 12542.4 12564.0 12739.9 22805.9 22862.9 22900.0 22903.0 22923.4 8568.3 8785.3 8803.6 8825.2 8919.6 4939.5 5127.4 5223.0 5259.2 5315.0 3585.7 3708.8 3709.0 3709.4 3719.7 636.8 662.7 665.4 672.6 687.8 670.5 712.6 773.1 782.1 796.3 9727.9 10342.5 10638.7 10661.7 11079.0 546.5 644.1 644.2 675.3 706.1 5098.7 5101.8 5126.6 5132.7 5191.2 133124.0 136290.0 139142.0 140092.0 142767.3 70818.0 72306.6 75116.0 115356.4 117716.4 77793.0 96288.0 96566.0 96822.0 98785.5 10132.9 10386.2 10728.4 10739.8 10789.9 53542.0 53976.7 54093.2 54375.2 54391.2 76070.6 81810.0 84918.7 85506.6 88198.0 12623.3 12931.7 13036.6 13171.0 13231.2 15095.7 15148.8 15385.8 15896.6 16488.3 14434.6 14530.0 15464.8 15612.0 15671.9 13582.9 13872.5 13909.2 14025.4 14038.6 12903.4 12931.5 13043.9 13286.7 13367.1 10775.2 11116.2 11189.3 11212.4 11642.1 11633.8 12244.5 12250.6 12478.9 12703.4 16:04:01 INFO - PROCESS | 17438 | -------- Summary: end -------- 16:04:01 INFO - PROCESS | 17438 | 16:04:01 INFO - TEST-INFO | 17438: exit 0 16:04:01 INFO - TEST-OK | dromaeo_css | took 1114640ms 16:04:01 INFO - TEST-START | kraken 16:04:01 INFO - operating with platform_type : linux_ 16:04:01 INFO - Initialising browser for kraken test... 16:04:01 INFO - TEST-INFO | started process 17758 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpJE776V/profile http://localhost:43182/getInfo.html) 16:04:06 INFO - PROCESS | 17758 | __metrics Screen width/height:1600/1200 16:04:06 INFO - PROCESS | 17758 | colorDepth:24 16:04:06 INFO - PROCESS | 17758 | Browser inner width/height: 1024/697 16:04:06 INFO - PROCESS | 17758 | __metrics 16:04:07 INFO - PROCESS | 17758 | 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" 16:04:07 INFO - PROCESS | 17758 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:04:08 INFO - PROCESS | 17758 | console.error: PushService: 16:04:08 INFO - PROCESS | 17758 | stateChangeProcessEnqueue: Error transitioning state 16:04:08 INFO - PROCESS | 17758 | UnknownError 16:04:08 INFO - TEST-INFO | 17758: exit 0 16:04:08 INFO - Browser initialized. 16:04:08 INFO - Running cycle 1/1 for kraken test... 16:04:08 INFO - TEST-INFO | started process 17851 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpJE776V/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:04:20 INFO - PROCESS | 17851 | RSS: Main: 144891904 16:04:20 INFO - PROCESS | 17851 | 16:04:23 INFO - PROCESS | 17851 | Cycle 1(1): loaded http://localhost:43182/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:43182/tests/kraken/driver.html?testName=audio-beat-detection) 16:04:24 INFO - PROCESS | 17851 | RSS: Main: 186421248 16:04:24 INFO - PROCESS | 17851 | 16:04:26 INFO - PROCESS | 17851 | Cycle 1(1): loaded http://localhost:43182/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:43182/tests/kraken/driver.html?testName=audio-dft) 16:04:27 INFO - PROCESS | 17851 | RSS: Main: 175722496 16:04:27 INFO - PROCESS | 17851 | 16:04:29 INFO - PROCESS | 17851 | Cycle 1(1): loaded http://localhost:43182/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:43182/tests/kraken/driver.html?testName=audio-fft) 16:04:30 INFO - PROCESS | 17851 | RSS: Main: 267235328 16:04:30 INFO - PROCESS | 17851 | 16:04:32 INFO - PROCESS | 17851 | Cycle 1(1): loaded http://localhost:43182/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:43182/tests/kraken/driver.html?testName=audio-oscillator) 16:04:33 INFO - PROCESS | 17851 | RSS: Main: 173129728 16:04:33 INFO - PROCESS | 17851 | 16:04:35 INFO - PROCESS | 17851 | Cycle 1(1): loaded http://localhost:43182/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:43182/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:04:35 INFO - PROCESS | 17851 | RSS: Main: 173924352 16:04:35 INFO - PROCESS | 17851 | 16:04:38 INFO - PROCESS | 17851 | Cycle 1(1): loaded http://localhost:43182/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:43182/tests/kraken/driver.html?testName=imaging-darkroom) 16:04:39 INFO - PROCESS | 17851 | RSS: Main: 210501632 16:04:39 INFO - PROCESS | 17851 | 16:04:42 INFO - PROCESS | 17851 | Cycle 1(1): loaded http://localhost:43182/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:43182/tests/kraken/driver.html?testName=imaging-desaturate) 16:04:43 INFO - PROCESS | 17851 | RSS: Main: 209276928 16:04:43 INFO - PROCESS | 17851 | 16:04:46 INFO - PROCESS | 17851 | Cycle 1(1): loaded http://localhost:43182/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:43182/tests/kraken/driver.html?testName=json-parse-financial) 16:04:46 INFO - PROCESS | 17851 | RSS: Main: 208650240 16:04:46 INFO - PROCESS | 17851 | 16:04:48 INFO - PROCESS | 17851 | Cycle 1(1): loaded http://localhost:43182/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:43182/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:04:48 INFO - PROCESS | 17851 | RSS: Main: 171212800 16:04:48 INFO - PROCESS | 17851 | 16:04:50 INFO - PROCESS | 17851 | Cycle 1(1): loaded http://localhost:43182/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:43182/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:04:51 INFO - PROCESS | 17851 | RSS: Main: 177606656 16:04:51 INFO - PROCESS | 17851 | 16:04:53 INFO - PROCESS | 17851 | Cycle 1(1): loaded http://localhost:43182/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:43182/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:04:53 INFO - PROCESS | 17851 | RSS: Main: 183717888 16:04:53 INFO - PROCESS | 17851 | 16:04:56 INFO - PROCESS | 17851 | Cycle 1(1): loaded http://localhost:43182/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:43182/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:04:56 INFO - PROCESS | 17851 | RSS: Main: 184037376 16:04:56 INFO - PROCESS | 17851 | 16:04:59 INFO - PROCESS | 17851 | Cycle 1(1): loaded http://localhost:43182/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:43182/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:05:00 INFO - PROCESS | 17851 | RSS: Main: 182239232 16:05:00 INFO - PROCESS | 17851 | 16:05:01 INFO - PROCESS | 17851 | Cycle 1(1): loaded http://localhost:43182/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:43182/tests/kraken/driver.html?testName=ai-astar) 16:05:01 INFO - PROCESS | 17851 | RSS: Main: 234270720 16:05:01 INFO - PROCESS | 17851 | 16:05:01 INFO - PROCESS | 17851 | __start_tp_report 16:05:01 INFO - PROCESS | 17851 | _x_x_mozilla_page_load 16:05:01 INFO - PROCESS | 17851 | _x_x_mozilla_page_load_details 16:05:01 INFO - PROCESS | 17851 | |i|pagename|runs| 16:05:01 INFO - PROCESS | 17851 | |0;ai-astar;112;111;112;111;112;112;112;110;113;110 16:05:01 INFO - PROCESS | 17851 | |1;audio-beat-detection;148;117;115;158;120;122;117;120;149;117 16:05:01 INFO - PROCESS | 17851 | |2;audio-dft;160;160;160;162;162;163;161;161;160;161 16:05:01 INFO - PROCESS | 17851 | |3;audio-fft;75;76;75;112;75;75;74;75;98;76 16:05:01 INFO - PROCESS | 17851 | |4;audio-oscillator;82;82;115;82;81;81;101;80;81;110 16:05:01 INFO - PROCESS | 17851 | |5;imaging-gaussian-blur;117;117;116;117;116;116;117;116;116;116 16:05:01 INFO - PROCESS | 17851 | |6;imaging-darkroom;146;145;146;145;146;145;145;146;146;145 16:05:01 INFO - PROCESS | 17851 | |7;imaging-desaturate;95;95;94;95;95;96;96;96;95;95 16:05:01 INFO - PROCESS | 17851 | |8;json-parse-financial;50;49;49;48;48;48;48;49;49;49 16:05:01 INFO - PROCESS | 17851 | |9;json-stringify-tinderbox;59;60;60;62;61;59;60;61;61;60 16:05:01 INFO - PROCESS | 17851 | |10;stanford-crypto-aes;73;72;73;72;73;72;74;78;81;73 16:05:01 INFO - PROCESS | 17851 | |11;stanford-crypto-ccm;118;131;126;125;123;119;119;117;154;135 16:05:01 INFO - PROCESS | 17851 | |12;stanford-crypto-pbkdf2;157;153;152;153;152;152;153;150;156;154 16:05:01 INFO - PROCESS | 17851 | |13;stanford-crypto-sha256-iterative;62;62;61;61;61;61;62;61;60;60 16:05:01 INFO - PROCESS | 17851 | __end_tp_report 16:05:01 INFO - PROCESS | 17851 | __start_cc_report 16:05:01 INFO - PROCESS | 17851 | _x_x_mozilla_cycle_collect,1553 16:05:01 INFO - PROCESS | 17851 | __end_cc_report 16:05:01 INFO - PROCESS | 17851 | __startTimestamp1474499101941__endTimestamp 16:05:01 INFO - PROCESS | 17851 | 16:05:01 INFO - PROCESS | 17851 | ------- Summary: start ------- 16:05:01 INFO - PROCESS | 17851 | Number of tests: 14 16:05:01 INFO - PROCESS | 17851 | 16:05:01 INFO - PROCESS | 17851 | [#0] ai-astar Cycles:10 Average:111.50 Median:112.00 stddev:0.97 (0.9%) stddev-sans-first:1.01 16:05:01 INFO - PROCESS | 17851 | Values: 112.0 111.0 112.0 111.0 112.0 112.0 112.0 110.0 113.0 110.0 16:05:01 INFO - PROCESS | 17851 | 16:05:01 INFO - PROCESS | 17851 | [#1] audio-beat-detection Cycles:10 Average:128.30 Median:121.00 stddev:16.45 (13.6%) stddev-sans-first:15.83 16:05:01 INFO - PROCESS | 17851 | Values: 148.0 117.0 115.0 158.0 120.0 122.0 117.0 120.0 149.0 117.0 16:05:01 INFO - PROCESS | 17851 | 16:05:01 INFO - PROCESS | 17851 | [#2] audio-dft Cycles:10 Average:161.00 Median:161.00 stddev:1.05 (0.7%) stddev-sans-first:1.05 16:05:01 INFO - PROCESS | 17851 | Values: 160.0 160.0 160.0 162.0 162.0 163.0 161.0 161.0 160.0 161.0 16:05:01 INFO - PROCESS | 17851 | 16:05:01 INFO - PROCESS | 17851 | [#3] audio-fft Cycles:10 Average:81.10 Median:75.00 stddev:13.03 (17.4%) stddev-sans-first:13.64 16:05:01 INFO - PROCESS | 17851 | Values: 75.0 76.0 75.0 112.0 75.0 75.0 74.0 75.0 98.0 76.0 16:05:01 INFO - PROCESS | 17851 | 16:05:01 INFO - PROCESS | 17851 | [#4] audio-oscillator Cycles:10 Average:89.50 Median:81.00 stddev:13.66 (16.9%) stddev-sans-first:14.21 16:05:01 INFO - PROCESS | 17851 | Values: 82.0 82.0 115.0 82.0 81.0 81.0 101.0 80.0 81.0 110.0 16:05:01 INFO - PROCESS | 17851 | 16:05:01 INFO - PROCESS | 17851 | [#5] imaging-gaussian-blur Cycles:10 Average:116.40 Median:116.50 stddev:0.52 (0.4%) stddev-sans-first:0.50 16:05:01 INFO - PROCESS | 17851 | Values: 117.0 117.0 116.0 117.0 116.0 116.0 117.0 116.0 116.0 116.0 16:05:01 INFO - PROCESS | 17851 | 16:05:01 INFO - PROCESS | 17851 | [#6] imaging-darkroom Cycles:10 Average:145.50 Median:146.00 stddev:0.53 (0.4%) stddev-sans-first:0.53 16:05:01 INFO - PROCESS | 17851 | Values: 146.0 145.0 146.0 145.0 146.0 145.0 145.0 146.0 146.0 145.0 16:05:01 INFO - PROCESS | 17851 | 16:05:01 INFO - PROCESS | 17851 | [#7] imaging-desaturate Cycles:10 Average:95.20 Median:95.00 stddev:0.63 (0.7%) stddev-sans-first:0.67 16:05:01 INFO - PROCESS | 17851 | Values: 95.0 95.0 94.0 95.0 95.0 96.0 96.0 96.0 95.0 95.0 16:05:01 INFO - PROCESS | 17851 | 16:05:01 INFO - PROCESS | 17851 | [#8] json-parse-financial Cycles:10 Average:48.70 Median:49.00 stddev:0.67 (1.4%) stddev-sans-first:0.53 16:05:01 INFO - PROCESS | 17851 | Values: 50.0 49.0 49.0 48.0 48.0 48.0 48.0 49.0 49.0 49.0 16:05:01 INFO - PROCESS | 17851 | 16:05:01 INFO - PROCESS | 17851 | [#9] json-stringify-tinderbox Cycles:10 Average:60.30 Median:60.50 stddev:0.95 (1.6%) stddev-sans-first:0.88 16:05:01 INFO - PROCESS | 17851 | Values: 59.0 60.0 60.0 62.0 61.0 59.0 60.0 61.0 61.0 60.0 16:05:01 INFO - PROCESS | 17851 | 16:05:01 INFO - PROCESS | 17851 | [#10] stanford-crypto-aes Cycles:10 Average:74.10 Median:73.00 stddev:3.00 (4.1%) stddev-sans-first:3.15 16:05:01 INFO - PROCESS | 17851 | Values: 73.0 72.0 73.0 72.0 73.0 72.0 74.0 78.0 81.0 73.0 16:05:01 INFO - PROCESS | 17851 | 16:05:01 INFO - PROCESS | 17851 | [#11] stanford-crypto-ccm Cycles:10 Average:126.70 Median:125.50 stddev:11.25 (9.0%) stddev-sans-first:11.48 16:05:01 INFO - PROCESS | 17851 | Values: 118.0 131.0 126.0 125.0 123.0 119.0 119.0 117.0 154.0 135.0 16:05:01 INFO - PROCESS | 17851 | 16:05:01 INFO - PROCESS | 17851 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:153.20 Median:153.00 stddev:2.04 (1.3%) stddev-sans-first:1.64 16:05:01 INFO - PROCESS | 17851 | Values: 157.0 153.0 152.0 153.0 152.0 152.0 153.0 150.0 156.0 154.0 16:05:01 INFO - PROCESS | 17851 | 16:05:01 INFO - PROCESS | 17851 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.10 Median:61.00 stddev:0.74 (1.2%) stddev-sans-first:0.71 16:05:01 INFO - PROCESS | 17851 | Values: 62.0 62.0 61.0 61.0 61.0 61.0 62.0 61.0 60.0 60.0 16:05:01 INFO - PROCESS | 17851 | -------- Summary: end -------- 16:05:01 INFO - PROCESS | 17851 | 16:05:02 INFO - TEST-INFO | 17851: exit 0 16:05:03 INFO - TEST-OK | kraken | took 61027ms 16:05:03 INFO - SUITE-END | took 1175s 16:05:03 INFO - Completed test suite (00:19:35) 16:05:03 INFO - javascript benchmark 16:05:03 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3065.3562751822114, 3099.7985130966485, 3104.3636054608805, 3122.3286993296438, 3151.732102771264, 5505.889882202354, 5590.077637189869, 5595.076812325961, 5612.803551875685, 5632.943670563298, 5542.0855558832845, 5576.191452239433, 5584.553235741141, 5613.578981576388, 5615.775368985236, 5014.097462456747, 5049.798008079672, 5072.771725272353, 5114.462981386957, 5130.666506677073, 4852.393450818648, 5084.008618319429, 5145.125328694131, 5152.124138896378, 5152.845414637567, 4821.951780482203, 4988.725620090893, 4998.2752500887345, 5015.7492125393765, 5029.2456131580375, 5017.7491125443585, 5043.899122017558, 5045.192769156917, 5109.6167787415725, 5130.9230361544605, 4962.727050012248, 5036.924446133311, 5042.218456139304, 5063.898722025576, 5087.79648814047, 5331.173668081415, 5376.569874410038, 5385.434529374398, 5451.454854514556, 5482.0406428875185, 5786.132080187964, 5796.97101514494, 5802.680852553108, 5887.234659494281, 5891.617044892068, 5865.20819689345, 5874.530037597025, 5884.6763428011445, 5887.0286402743495, 5917.644941303535, 5419.214213938957, 5452.672839629635, 5467.289252397202, 5490.313710786152, 5517.889642207133, 6034.064719968412, 6050.304215015241, 6065.059915713072, 6066.241719785027, 6066.363031881677, 5824.534037277008, 5826.1552074948895, 5828.067509198508, 5828.504577110993, 5832.562557808182, 5707.857303567444, 5782.739776710058, 5800.88398232033, 5870.706464676835, 5879.2944846618675, 5873.148393482921, 5887.970560147171, 5893.233879595625, 5894.793682221101, 5918.378570250148, 5842.591018628656, 5897.380775018647, 5901.3213480450295, 5905.0, 5925.703714814328, 4418.8453404130705, 4449.065696203768, 4526.841560545365, 4561.429821272341, 4563.247064234431, 4035.9394609080887, 4054.2094291612852, 4066.877993660195, 4086.223617512663, 4107.301758701028, 5686.800961966312, 5687.886242275132, 5690.516306113944, 5692.430756924357, 5700.515456186271, 5762.510186634099, 5776.653400795965, 5894.911576326359, 6015.639061656315, 6016.338202797689, 5652.519535839499, 5656.575756818255, 5679.0, 5682.147677848356, 5688.601797874192, 5153.536181743586, 5157.896842063138, 5164.2770012198325, 5166.0, 5199.714015729096, 5792.0732682771395, 5946.0, 6153.2000839890525, 6153.815385538441, 6160.260768707783, 5909.0, 5968.194293770285, 5975.940240597538, 5991.760329586942, 6044.274687037584, 6128.754849805958, 6150.600210986271, 6153.569250152446, 6153.66154861469, 6198.194234749454, 5624.493795558419, 5843.853903652443, 5847.502962248256, 5850.151727999331, 5850.561207909339, 5687.857803554944, 5890.764369425175, 5941.1385349125, 5950.137230101697, 6000.8799824004145, 5780.566457515619, 5848.181254624271, 5849.444302791228, 5854.736536855767, 5865.648061116347, 5775.422457754191, 5825.475707186373, 5826.41735826414, 5827.3881242470625, 5829.53363730892, 5557.861053473695, 5572.526335261548, 5651.88696226065, 5678.886422271614, 5702.315722113207, 5806.303243610793, 5806.767729290781, 5808.244928159313, 5828.213191219131, 5845.064789633809, 5460.972695136499, 5530.198121272473, 5665.3201615806365, 5669.319681638065, 5669.518090962314], "value": 5446.781882225894, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [561.1274468201945, 591.4204080001598, 621.9067139929011, 624.0701354981077, 645.9580127291729, 35884.64115358849, 39996.400053999176, 47223.055538889246, 47806.0, 48350.032999339994, 48442.51557484424, 48774.51225487744, 48815.0, 48895.75552122243, 48939.26591101127, 9274.814503709938, 9420.717378478657, 9422.717318480456, 9428.151466368026, 9429.057094290585, 8544.060153383123, 8758.912410875906, 11078.169137314693, 11328.830067548994, 11339.433028348549, 11477.368744719037, 11563.710907227345, 11571.421428928563, 11579.421028948562, 11579.53681852725, 11274.210805243636, 11335.0, 11357.091432685365, 11360.60237891674, 11362.522872026624, 11064.944675276653, 11086.944565277203, 11136.387498687569, 11136.721581960435, 11157.832632510519, 46876.765616172044, 46997.76501117473, 47193.29210061852, 47198.528014719755, 47235.5276447238, 3732.1789206374556, 4000.119973605802, 4225.577442255781, 4269.87190384289, 4581.4502259728715, 35598.644013559795, 35794.0, 35877.28245435077, 35920.0, 36091.81954090239, 4665.393498845162, 4736.668433209677, 4741.668083234175, 4761.9761751223305, 4816.807327706888, 368775.0, 369648.0, 369974.0, 370553.0, 371618.0, 66943.66528167328, 67339.66330168318, 67374.32625673681, 67412.662936685, 67422.66288668623, 41595.7920210397, 42916.0, 42953.78523107364, 44809.0, 44919.77540112279, 18694.0, 18858.434246972614, 18913.05434728258, 18914.527136821413, 18932.24271029171, 11698.590549330902, 11966.940165299118, 14185.503507377192, 14387.064840785315, 14394.856051439561, 20687.0, 21814.672779908313, 21987.560248795253, 22111.33665990023, 22114.115435382726, 7649.541027538366, 7763.573003484749, 7774.0, 7775.961120194363, 7934.166912474223, 1003.2776400991274, 1029.8867124616286, 1030.1655658916302, 1072.0673014477454, 1125.763589646167, 34553.65446345555, 34918.30163396768, 34982.82508587441, 34987.30025399478, 35099.0, 1134.3704244144578, 1161.8547681539808, 1166.912481563886, 1237.294741997071, 1255.3974092435683, 18225.362112326005, 18457.723134153, 18613.7207941881, 18632.44102676922, 18644.34744783926, 613385.0, 616714.0, 620250.0, 620744.0, 621291.0, 64228.67885660542, 64537.677311613144, 64667.0, 64850.351496485375, 64854.351456485776, 1299.0127503097715, 1334.8865346445466, 1420.5667271482298, 1447.6525633848012, 1469.6619777451247, 31144.0, 36228.27543449169, 36396.27207455783, 36692.81653591715, 36842.8157859209, 57589.42410576008, 58091.70954145202, 58428.4157158423, 58437.4156258432, 58452.0, 453396.0, 456870.0, 457561.0, 458697.0, 458709.0, 31697.8415107923, 32500.0, 32932.83533582317, 32974.5053824188, 34080.0, 52804.73597631987, 53207.46792532025, 53679.19481207707, 53872.73063634657, 54391.0, 43529.56470435382, 43808.78095609502, 44362.334564980905, 44666.776666116464, 45522.54477455183, 43010.35484467673, 43065.354019690356, 43109.0, 44894.3265851006, 44895.77552112219, 44677.0, 44768.55231447644, 45421.54578454305, 45518.54481455143, 46011.3098303519, 37244.627553724116, 39801.312932131426, 39958.60041399665, 40207.59792402155, 40415.1916961665, 30173.54739678863, 30899.382012359176, 31893.809519090555, 32055.0, 32061.839690801397], "value": 22047.357194655993, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [391.4958403566962, 414.2522746442672, 427.96790240731946, 429.3495354537875, 440.79723327269454, 15345.846541534582, 19398.0, 21173.68239476405, 21276.89361553194, 21479.46301342465, 19545.12046957887, 20205.696914546257, 20301.289454869082, 20675.483112922186, 20715.964201789924, 5784.103463963072, 5800.593958422923, 6003.819885403423, 6011.158437818709, 6011.75952961881, 5843.18195452637, 5885.20549725788, 5914.585978981473, 5932.940670593303, 5935.436133567304, 5767.653940763567, 5800.564957628174, 5889.941100589003, 5895.852603684899, 5897.144913987469, 5827.854303642401, 5839.941600584004, 5850.707464626794, 5873.618214816023, 5906.793262235801, 5228.895422091575, 5284.233786101032, 5285.418603953562, 5300.178472336794, 5346.064438723208, 24308.635370469456, 24312.149074782476, 24488.265352039467, 24499.38751531226, 24742.628860567107, 2491.750824917512, 2528.936776580582, 2601.8438893666253, 2613.6994245661804, 2656.3624730064644, 14667.63330916725, 15636.140012299318, 15684.294206760724, 15835.68328633421, 16312.592185195346, 1807.1235450806348, 1833.330834245491, 2004.9599008019904, 2021.5148364392587, 2064.6386882295537, 88544.5572772132, 88781.11218887793, 90415.0, 91965.54017229938, 92376.53811730898, 10640.89359106399, 11767.352795596324, 11895.5725312963, 12035.217710848767, 12346.938265308616, 15795.842041579437, 15891.125988070537, 16673.749893751603, 16704.83295167057, 17369.392071277445, 8735.563221838882, 8832.205101540892, 9003.604441311607, 9096.90903090974, 9100.408473449204, 8684.652613895374, 8740.519271440131, 8751.343649226334, 8793.768872357876, 8971.50656713874, 5106.591472682177, 5178.404483484447, 5195.220716892496, 5223.582113430916, 5247.737613119405, 2008.8292495138078, 2125.4473836802545, 2143.7213162288804, 2150.290404166621, 2158.6222411077997, 947.7677968897664, 1019.4749703902496, 1039.3140527252128, 1051.3429106808244, 1082.9620963266248, 19775.40673779789, 20473.0, 20477.692834607493, 20645.070971806304, 20742.58514829695, 1014.1785154025263, 1031.3244824639874, 1046.9371837689764, 1055.36677993205, 1071.8231491803922, 10634.46827658614, 11582.36297003656, 11590.343367896894, 11615.477303521362, 11736.76526469483, 513775.0, 515060.0, 523285.0, 527810.0, 532235.0, 66123.33876661268, 90745.0, 92095.53952230196, 109125.0, 112631.4368428169, 1036.383351905615, 1046.9162467002623, 1089.5206109311878, 1090.4275255490772, 1098.549594666183, 18345.81654183441, 18490.44528664142, 18736.159087387325, 18938.905305473385, 19226.134823933236, 45865.54134458746, 46227.306590401844, 46398.07203855836, 46733.532664672915, 46992.29511557261, 59187.0, 60697.0, 63530.0, 64446.35553644591, 64634.353656464715, 7424.406047516294, 8279.95860020696, 8513.659453621785, 9164.404313719588, 9676.12914837668, 9287.442753434816, 13070.01974851871, 13303.46786128544, 13368.866311336762, 13403.128796628189, 11436.0, 11790.587329443428, 11865.762684746083, 12194.634160975185, 12393.318367489514, 11332.206745528063, 11471.369074700971, 11685.298882067103, 11698.532058717556, 12066.879331206575, 10937.726556836144, 11079.5568177272, 11522.423878806194, 11811.822822657496, 12235.388230588613, 8108.75673729789, 8586.914130858611, 8752.78118047054, 8815.118488151134, 8911.50986695712, 10218.846717299099, 11282.94358528235, 11387.373694446886, 11404.429778511207, 11549.076073914235], "value": 9729.360594745194, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1361.5302720561403, 1546.6983938132062, 1573.05616630022, 1733.3846484498004, 1840.8803427777186, 8008.439409241349, 8880.777980550489, 9165.495897725623, 9175.128362805539, 9451.196648284904, 9242.26061915047, 9344.345895787297, 9348.906510934889, 9353.438793672385, 9353.485558294291, 2513.610390389488, 2538.0482319130324, 2544.567423537994, 2544.656471376359, 2545.732698066704, 2461.5569197544437, 2491.6636254105647, 2508.24752574227, 2515.346010037385, 2515.949681006388, 2479.814013948952, 2493.7381574934643, 2527.696676398835, 2528.645989561463, 2532.189699296226, 2449.963250551243, 2462.495188486356, 2470.4441500662388, 2487.4900645367657, 2491.1405565080017, 2429.79346755526, 2447.339218411019, 2461.88921498533, 2461.90152393904, 2464.383904023994, 8966.327525435618, 9110.908890911072, 9300.953495232481, 9308.0, 9312.534373281376, 5572.3034620672415, 5697.515711164556, 5716.771329146829, 5728.828135155952, 5736.713164341808, 3622.2212224371624, 3632.854685812564, 3657.3416784978695, 3674.0447483654175, 3676.8345424455965, 1248.1762037055591, 1251.7246205834701, 1256.0767835640888, 1291.4511332683571, 1297.6820678933723, 12555.309457979807, 12637.17858339205, 12650.683732906658, 12676.873231267662, 12701.618951431472, 4730.763461826895, 4785.504876248944, 4799.136155491975, 4899.265110233494, 4949.851504454872, 10064.899351006396, 10084.69745907624, 10089.697309080739, 10095.444750538643, 10121.595136194617, 5551.639143455663, 5554.77780888768, 5562.471565201299, 5562.888742225133, 5568.192612071227, 4584.083183363341, 4735.573798358164, 5039.420466646383, 5150.48495150482, 5183.611229157828, 4064.4309796628495, 4090.652294554937, 4161.479815023122, 4163.97918010408, 4208.873733787991, 1217.9634610961684, 1228.2139430764319, 1234.6419538333782, 1240.3612139748036, 1243.055277988735, 958.0850287974968, 974.5663179884883, 978.2418625565244, 982.8525721141743, 991.8710567626163, 5848.64908105515, 5941.702914854326, 6033.728482218311, 6078.817635470943, 6131.356207598227, 958.836997710391, 980.9656662016795, 994.5623925472769, 1000.979980400388, 1024.1601886453186, 3837.213371258885, 4075.959240407558, 4077.91844163121, 4111.280525908014, 4127.33962565994, 11398.94300528492, 11934.104942129376, 11944.283342999448, 11948.940255298668, 11958.16292859509, 11788.528458861549, 11878.227915185485, 11879.88120118805, 11897.70255743596, 11899.286042837457, 8227.506349619041, 8242.835143297101, 8322.334213262924, 8322.70870519529, 8672.8699069514, 5961.52307815365, 5996.730147143302, 6087.512998959984, 6153.815385538441, 6184.072389141665, 10704.09015233714, 10731.624393146203, 10755.677329680122, 10764.515596798006, 10771.730706732395, 10336.844947325946, 10558.0, 10811.243212975016, 10944.343339399662, 10961.287516311733, 7017.2631873652535, 7274.127104747253, 7524.0, 7734.2265773422205, 7940.880886786818, 8796.648134074821, 8836.602352894264, 8842.778930526789, 8867.955660221658, 8871.955640221757, 7916.881246781188, 7930.643121059451, 7963.243491868263, 7982.0, 8200.26197642215, 7730.45886787942, 7748.8450230996195, 7767.53394796314, 7805.960970195113, 7850.842983140419, 6777.627230502172, 6857.759978400932, 6859.142607174103, 6867.484938629723, 6871.072405225231, 4928.507149285043, 4934.605231581393, 4937.308776771327, 4950.702957822542, 5170.146925757208, 5245.895082098413, 5273.28810610578, 5291.3385826771655, 5329.973350133225, 5442.945570544244], "value": 4822.2345591098365, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [345.55941175001874, 349.57526605174706, 353.67634640412484, 360.59613233178845, 361.2359858898429, 5458.399576046631, 5536.778528858841, 5537.335519737627, 5567.972160139194, 5578.386377498477, 5506.669599824003, 5510.366307874596, 5516.338039435263, 5529.474699903502, 5530.340897730684, 4065.26825171469, 4374.715643483163, 4377.6716746244, 4378.248700519785, 4380.649548036149, 3892.610738926113, 4248.511421186557, 4345.391645169679, 4349.608535231844, 4351.521332653406, 4316.935245971313, 4327.5070100815165, 4333.068390296085, 4335.43639326887, 4340.218760623086, 4169.145325208356, 4330.826766929319, 4348.412964249817, 4354.303311470149, 4357.9782101089295, 3718.572364178127, 3829.7127715421457, 3865.536135663738, 3870.651641352292, 3889.630485103911, 5336.91994620081, 5361.2226227197225, 5367.75845086972, 5398.703071331075, 5400.837974860721, 4717.834875779331, 4969.7515124244, 4994.700317980933, 4999.700017998895, 5002.0, 5767.452092051249, 5795.391483894215, 5804.187413762077, 5807.767689292423, 5811.186433899257, 4239.894002649928, 4240.109576988836, 4242.5121111072, 4245.617894389489, 4253.21315556623, 5846.941530584682, 5988.251468566473, 6032.547558933054, 6068.362821903681, 6071.785642871443, 4221.472315960505, 4225.133847561243, 4227.471566054243, 4230.2174097792, 4231.597998190228, 4280.250956082673, 4284.421603083606, 4287.256156060124, 4287.585096918009, 4288.914221715549, 4784.497627749036, 4909.288153217834, 4923.384576927884, 4924.532169443897, 4930.654854160246, 5747.224124743189, 5794.768209271666, 5796.275465566804, 5812.244408226903, 5827.388124246893, 1487.7247709173837, 1492.843251458603, 1500.1074360755333, 1508.8566586174295, 1509.9924500377647, 928.7538802217417, 971.3297824501213, 974.35205588283, 981.5828272984096, 986.3835103060587, 4953.356063711766, 4993.70037797726, 5006.123928312531, 5009.348784657971, 5026.4973502649445, 5217.8173763918085, 5247.475252474798, 5266.97366513165, 5273.6572122811895, 5280.759021629914, 5019.6737212081825, 5022.924656130161, 5034.546890779848, 5308.840734777963, 5702.942970570324, 3156.6369867465537, 3178.968210317867, 3181.8886338978023, 3184.7611429142908, 3195.8242296673907, 6021.6989150542295, 6123.4488895999575, 6142.78500252498, 6170.136180934673, 6175.876482470326, 6086.573939824259, 6101.420365065312, 6145.324014358417, 6148.231471066117, 6148.784792532329, 5894.614765530097, 6020.277566692025, 6070.423309785563, 6188.514756458508, 6188.731310081423, 5945.10823376497, 5995.111539864929, 6001.519878409804, 6004.849878753066, 6010.33886272501, 5635.126555383922, 5917.408259174048, 5923.111533270044, 5928.170056192223, 5958.612690175125, 6239.625622462667, 6242.313345531897, 6246.656433896179, 6248.343923887926, 6250.468710159687, 4360.498542667634, 4367.235733746518, 4368.650507959419, 4378.255696531661, 4379.802908869045, 5765.8558536036935, 5914.852128696817, 5967.791127310524, 5987.940120598738, 5988.221531200916, 5865.384134665969, 5878.178474332259, 5897.5576831736935, 5899.321578018411, 5918.763249469973, 5690.886182276248, 5791.333996590446, 5809.6347358371595, 5887.8233652990475, 5908.615939963888, 5801.0, 5815.476607105381, 5816.621919575214, 5824.767009319749, 5825.592208545361, 5894.6757928314355, 5903.852403689943, 5909.852253693692, 5925.140854575976, 5928.407159284037, 5459.181122831607, 5712.400197979152, 5797.594168408171, 5802.796902108406, 5803.172000819728], "value": 4409.868760575667, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [793.7261644732565, 839.1776059461731, 890.3678388344275, 906.5234824448239, 909.7407238936904, 16498.0, 17130.314787408504, 17291.567710807238, 17298.221580028898, 17311.22099505522, 15825.84174158258, 17889.28442862284, 18210.180541875616, 18224.63550728988, 18234.635307293847, 870.4429165334191, 888.2938064238913, 893.0622846011694, 893.7586851550078, 894.2979760887705, 888.1961824548762, 888.6223355074068, 890.6348397157167, 891.6255172827396, 895.1361935732017, 933.1788026536639, 941.8869735631733, 942.194423767679, 943.6555657185147, 943.6697155995415, 939.2532936315646, 939.8825146856609, 939.8872135343769, 939.891912430069, 940.3464592108487, 692.0518889121885, 693.0505207865207, 695.2664938489891, 697.285282585354, 697.6999890047277, 14654.706905861824, 17786.822131778645, 17951.281948722037, 17982.460526184237, 18082.728759068625, 14931.178785166812, 16017.919910400373, 16301.429449969195, 16595.585110372336, 17174.055426951512, 13369.131006484547, 13400.128991615515, 13487.595372138852, 13641.386137623831, 13662.726745465035, 4044.3933409988445, 4055.776932268724, 4087.9386809197886, 4113.1568028554075, 4169.207850508394, 82002.58998705087, 82222.17777822205, 84087.0, 87617.56191219066, 89441.0, 12084.637460876187, 12404.937975310246, 12542.435590398454, 12564.0, 12739.936300318439, 22805.88597057004, 22862.885685571797, 22900.0, 22903.0, 22923.426914327276, 8568.271696905833, 8785.253253473398, 8803.603837827313, 8825.161609647073, 8919.554022298858, 4939.456659767423, 5127.359080114986, 5222.973885130626, 5259.211118332204, 5314.97342513285, 3585.6952159066773, 3708.7774733516076, 3709.0356507307756, 3709.4435834624483, 3719.683826874692, 636.7771280051945, 662.6752891083335, 665.3778716899715, 672.6199697171082, 687.7936619014275, 670.5473805181483, 712.6436781609195, 773.1340898194059, 782.1162086841832, 796.2674339607574, 9727.902720972841, 10342.482875856327, 10638.680839574825, 10661.733456663645, 11079.0, 546.5409056392649, 644.1465058797113, 644.2140588482044, 675.3314218923231, 706.1208795050195, 5098.719570423588, 5101.821436249714, 5126.5898728101665, 5132.666376685504, 5191.2472691459525, 133124.0, 136290.0, 139142.0, 140092.0, 142767.2861635706, 70818.0, 72306.63846680733, 75116.0, 115356.42321788338, 117716.41141794236, 77793.0, 96288.0, 96566.0, 96822.0, 98785.50607247061, 10132.933533233383, 10386.221033422373, 10728.409937453514, 10739.785204296026, 10789.892101079202, 53542.0, 53976.730116349165, 54093.18860217021, 54375.184372233656, 54391.18413223884, 76070.61964690141, 81810.0, 84918.71480093803, 85506.57246713726, 88198.0, 12623.30571818522, 12931.676708082374, 13036.608901732963, 13171.012174087173, 13231.206127632373, 15095.698086037997, 15148.848511514743, 15385.769213461583, 15896.602584935468, 16488.25802838851, 14434.566962991126, 14530.0, 15464.768028479357, 15612.0, 15671.921640391725, 13582.93208533951, 13872.514461993782, 13909.165450073027, 14025.438982440995, 14038.578842634737, 12903.419346129635, 12931.482740690642, 13043.934780326037, 13286.734265314446, 13367.06430549891, 10775.191860610328, 11116.21635119619, 11189.328640281608, 11212.37420573996, 11642.068634509389, 11633.827652134274, 12244.510219591117, 12250.571230006906, 12478.937605311916, 12703.42834572428], "value": 8134.651951471859, "unit": "score"}], "name": "dromaeo_css", "value": 7660.784988691557, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 111.0, 112.0, 111.0, 112.0, 112.0, 112.0, 110.0, 113.0, 110.0], "value": 111.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 117.0, 115.0, 158.0, 120.0, 122.0, 117.0, 120.0, 149.0, 117.0], "value": 128.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 160.0, 160.0, 162.0, 162.0, 163.0, 161.0, 161.0, 160.0, 161.0], "value": 161.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 75.0, 112.0, 75.0, 75.0, 74.0, 75.0, 98.0, 76.0], "value": 81.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 115.0, 82.0, 81.0, 81.0, 101.0, 80.0, 81.0, 110.0], "value": 89.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 116.0, 117.0, 116.0, 116.0, 117.0, 116.0, 116.0, 116.0], "value": 116.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 145.0, 146.0, 145.0, 146.0, 145.0, 145.0, 146.0, 146.0, 145.0], "value": 145.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 94.0, 95.0, 95.0, 96.0, 96.0, 96.0, 95.0, 95.0], "value": 95.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 49.0, 49.0, 48.0, 48.0, 48.0, 48.0, 49.0, 49.0, 49.0], "value": 48.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 60.0, 60.0, 62.0, 61.0, 59.0, 60.0, 61.0, 61.0, 60.0], "value": 60.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 72.0, 73.0, 72.0, 73.0, 72.0, 74.0, 78.0, 81.0, 73.0], "value": 74.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 131.0, 126.0, 125.0, 123.0, 119.0, 119.0, 117.0, 154.0, 135.0], "value": 126.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 153.0, 152.0, 153.0, 152.0, 152.0, 153.0, 150.0, 156.0, 154.0], "value": 153.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 62.0, 61.0, 61.0, 61.0, 61.0, 62.0, 61.0, 60.0, 60.0], "value": 61.1, "unit": "score"}], "name": "kraken", "value": 1452.6, "alertThreshold": 2.0}]} 16:05:03 INFO - Return code: 0 16:05:03 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:05:03 INFO - # TBPL SUCCESS # 16:05:03 INFO - Running post-action listener: _resource_record_post_action 16:05:03 INFO - [mozharness: 2016-09-21 23:05:03.261053Z] Finished run-tests step (success) 16:05:03 INFO - Running post-run listener: _resource_record_post_run 16:05:03 INFO - Total resource usage - Wall time: 1192s; CPU: 13.0%; Read bytes: 4096; Write bytes: 368943104; Read time: 80; Write time: 577312 16:05:03 INFO - TinderboxPrint: CPU usage
13.0% 16:05:03 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 80 16:05:03 INFO - TinderboxPrint: I/O write bytes / time
368,943,104 / 577,312 16:05:03 INFO - TinderboxPrint: CPU idle
8,276.5 (87.0%) 16:05:03 INFO - TinderboxPrint: CPU user
1,183.8 (12.4%) 16:05:03 INFO - TinderboxPrint: Swap in / out
0 / 0 16:05:03 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 120512512; Read time: 0; Write time: 213032 16:05:03 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 0; Write bytes: 248430592; Read time: 0; Write time: 364280 16:05:03 INFO - Running post-run listener: _upload_blobber_files 16:05:03 INFO - Blob upload gear active. 16:05:03 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:05:03 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 16:05:03 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'] 16:05:03 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 16:05:04 INFO - (blobuploader) - INFO - Open directory for files ... 16:05:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:05:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:05:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:05:08 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:05:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:05:08 INFO - (blobuploader) - INFO - Done attempting. 16:05:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:05:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:05:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:05:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:05:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:05:09 INFO - (blobuploader) - INFO - Done attempting. 16:05:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:05:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:05:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:05:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:05:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:05:10 INFO - (blobuploader) - INFO - Done attempting. 16:05:10 INFO - (blobuploader) - INFO - Iteration through files over. 16:05:10 INFO - Return code: 0 16:05:10 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:05:10 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:05:10 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/addae32abc9608de1a814d4fe50fbda00e55813b91bc6e1cf340d69241638561d57f307acb1244dfbe5c9d28ba8e024b21ab8706381faa4d3ad563d499c56a92", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c701f298d3c74aa459e833b1d4539fa6debf3055d46ac571977c1c1a1d23bc0f2bc0c8327f3b8615f950550b0e765b29279818d5919a3ee60edea61fd577ca0d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ae961d19167a4c18465b9174613b91ce6a8e286602443bf3f0ca5571b3df6106e0aadc0479471cb8519d03b94ed4d4531bb5d3489f6aa98f12948494f9d6d219"} 16:05:10 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:05:10 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:05:10 INFO - Contents: 16:05:10 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/addae32abc9608de1a814d4fe50fbda00e55813b91bc6e1cf340d69241638561d57f307acb1244dfbe5c9d28ba8e024b21ab8706381faa4d3ad563d499c56a92", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c701f298d3c74aa459e833b1d4539fa6debf3055d46ac571977c1c1a1d23bc0f2bc0c8327f3b8615f950550b0e765b29279818d5919a3ee60edea61fd577ca0d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ae961d19167a4c18465b9174613b91ce6a8e286602443bf3f0ca5571b3df6106e0aadc0479471cb8519d03b94ed4d4531bb5d3489f6aa98f12948494f9d6d219"} 16:05:10 INFO - Running post-run listener: copy_logs_to_upload_dir 16:05:10 INFO - Copying logs to upload dir... 16:05:10 INFO - mkdir: /builds/slave/test/build/upload/logs 16:05:10 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1235.174791 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 35 secs) (at 2016-09-21 16:05:10.027808) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-21 16:05:10.031518) ========= 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=df17445c3590ff7897617a0e00000225-1474496057.196679-798627917 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/addae32abc9608de1a814d4fe50fbda00e55813b91bc6e1cf340d69241638561d57f307acb1244dfbe5c9d28ba8e024b21ab8706381faa4d3ad563d499c56a92", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c701f298d3c74aa459e833b1d4539fa6debf3055d46ac571977c1c1a1d23bc0f2bc0c8327f3b8615f950550b0e765b29279818d5919a3ee60edea61fd577ca0d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ae961d19167a4c18465b9174613b91ce6a8e286602443bf3f0ca5571b3df6106e0aadc0479471cb8519d03b94ed4d4531bb5d3489f6aa98f12948494f9d6d219"} build_url:https://queue.taskcluster.net/v1/task/FAFL-AgiTsWl9dkPR4yY7A/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011422 build_url: 'https://queue.taskcluster.net/v1/task/FAFL-AgiTsWl9dkPR4yY7A/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/addae32abc9608de1a814d4fe50fbda00e55813b91bc6e1cf340d69241638561d57f307acb1244dfbe5c9d28ba8e024b21ab8706381faa4d3ad563d499c56a92", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c701f298d3c74aa459e833b1d4539fa6debf3055d46ac571977c1c1a1d23bc0f2bc0c8327f3b8615f950550b0e765b29279818d5919a3ee60edea61fd577ca0d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ae961d19167a4c18465b9174613b91ce6a8e286602443bf3f0ca5571b3df6106e0aadc0479471cb8519d03b94ed4d4531bb5d3489f6aa98f12948494f9d6d219"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-21 16:05:10.056559) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-21 16:05:10.056847) ========= 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=df17445c3590ff7897617a0e00000225-1474496057.196679-798627917 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004406 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-21 16:05:10.140826) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-21 16:05:10.141134) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-21 16:05:10.141413) ========= ========= Total master_lag: 0.19 =========