builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-061 starttime: 1474153269.44 results: success (0) buildid: 20160917133006 builduid: 383ded90a04c45b689a4d4230af1bc59 revision: 8d211b6a94f603ef81ffcdee3a2b484c2ecd21cd ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-17 16:01:09.437306) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-17 16:01:09.438042) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-17 16:01:09.438530) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-17 16:01:09.602394) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 16:01:09.602909) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=516f8d7ca085b4d087ab0a71000001a4-1474152371.346314-1419243930 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-17 16:01:10-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 138M=0s 2016-09-17 16:01:10 (138 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.138725 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 16:01:09.767658) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 16:01:09.768016) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=516f8d7ca085b4d087ab0a71000001a4-1474152371.346314-1419243930 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014725 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 16:01:09.825530) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 16:01:09.825862) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 8d211b6a94f603ef81ffcdee3a2b484c2ecd21cd --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 8d211b6a94f603ef81ffcdee3a2b484c2ecd21cd --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=516f8d7ca085b4d087ab0a71000001a4-1474152371.346314-1419243930 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-17 16:01:10,593 truncating revision to first 12 chars 2016-09-17 16:01:10,593 Setting DEBUG logging. 2016-09-17 16:01:10,593 attempt 1/10 2016-09-17 16:01:10,593 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/8d211b6a94f6?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-17 16:01:10,896 unpacking tar archive at: mozilla-central-8d211b6a94f6/testing/mozharness/ program finished with exit code 0 elapsedTime=0.653232 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 16:01:10.492666) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-17 16:01:10.492993) ========= script_repo_revision: 8d211b6a94f603ef81ffcdee3a2b484c2ecd21cd ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-17 16:01:10.493403) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-17 16:01:10.493687) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-17 16:01:10.507365) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2016-09-17 16:01:10.507756) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=516f8d7ca085b4d087ab0a71000001a4-1474152371.346314-1419243930 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:01:11 INFO - MultiFileLogger online at 20160917 16:01:11 in /builds/slave/test 16:01:11 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 16:01:11 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:01:11 INFO - {'append_to_log': False, 16:01:11 INFO - 'base_work_dir': '/builds/slave/test', 16:01:11 INFO - 'blob_upload_branch': 'Firefox', 16:01:11 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:01:11 INFO - 'branch': 'Firefox', 16:01:11 INFO - 'buildbot_json_path': 'buildprops.json', 16:01:11 INFO - 'config_files': ('talos/linux_config.py',), 16:01:11 INFO - 'default_actions': ('clobber', 16:01:11 INFO - 'read-buildbot-config', 16:01:11 INFO - 'download-and-extract', 16:01:11 INFO - 'populate-webroot', 16:01:11 INFO - 'create-virtualenv', 16:01:11 INFO - 'install', 16:01:11 INFO - 'run-tests'), 16:01:11 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:01:11 INFO - 'download_minidump_stackwalk': True, 16:01:11 INFO - 'download_symbols': 'ondemand', 16:01:11 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:01:11 INFO - 'tooltool.py': '/tools/tooltool.py', 16:01:11 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:01:11 INFO - '/tools/misc-python/virtualenv.py')}, 16:01:11 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:01:11 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:01:11 INFO - 'installer_path': 'installer.exe', 16:01:11 INFO - 'log_level': 'info', 16:01:11 INFO - 'log_name': 'talos', 16:01:11 INFO - 'log_to_console': True, 16:01:11 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:01:11 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:01:11 INFO - 'opt_config_files': (), 16:01:11 INFO - 'pip_index': False, 16:01:11 INFO - 'sps_profile': False, 16:01:11 INFO - 'sps_profile_interval': 0, 16:01:11 INFO - 'suite': 'dromaeojs', 16:01:11 INFO - 'system_bits': '32', 16:01:11 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:01:11 INFO - 'title': 'talos-linux64-ix-061', 16:01:11 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:01:11 INFO - 'use_talos_json': True, 16:01:11 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:01:11 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:01:11 INFO - 'work_dir': 'build'} 16:01:11 INFO - [mozharness: 2016-09-17 23:01:11.373871Z] Running clobber step. 16:01:11 INFO - Running pre-action listener: _resource_record_pre_action 16:01:11 INFO - Running main action method: clobber 16:01:11 INFO - rmtree: /builds/slave/test/build 16:01:11 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:01:12 INFO - Running post-action listener: _resource_record_post_action 16:01:12 INFO - [mozharness: 2016-09-17 23:01:12.349660Z] Finished clobber step (success) 16:01:12 INFO - [mozharness: 2016-09-17 23:01:12.349760Z] Running read-buildbot-config step. 16:01:12 INFO - Running pre-action listener: _resource_record_pre_action 16:01:12 INFO - Running main action method: read_buildbot_config 16:01:12 INFO - Using buildbot properties: 16:01:12 INFO - { 16:01:12 INFO - "project": "", 16:01:12 INFO - "product": "firefox", 16:01:12 INFO - "script_repo_revision": "production", 16:01:12 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 16:01:12 INFO - "repository": "", 16:01:12 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 16:01:12 INFO - "buildid": "20160917133006", 16:01:12 INFO - "pgo_build": "True", 16:01:12 INFO - "basedir": "/builds/slave/test", 16:01:12 INFO - "buildnumber": 879, 16:01:12 INFO - "slavename": "talos-linux64-ix-061", 16:01:12 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 16:01:12 INFO - "platform": "ubuntu64_hw", 16:01:12 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 16:01:12 INFO - "branch": "mozilla-central", 16:01:12 INFO - "repo_path": "mozilla-central", 16:01:12 INFO - "revision": "8d211b6a94f603ef81ffcdee3a2b484c2ecd21cd", 16:01:12 INFO - "stage_platform": "linux64-pgo", 16:01:12 INFO - "builduid": "383ded90a04c45b689a4d4230af1bc59", 16:01:12 INFO - "slavebuilddir": "test" 16:01:12 INFO - } 16:01:12 INFO - Found installer url https://queue.taskcluster.net/v1/task/IQzmNtvaSAKlXq9f9rHYWw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 16:01:12 INFO - Running post-action listener: _resource_record_post_action 16:01:12 INFO - [mozharness: 2016-09-17 23:01:12.352207Z] Finished read-buildbot-config step (success) 16:01:12 INFO - [mozharness: 2016-09-17 23:01:12.352297Z] Running download-and-extract step. 16:01:12 INFO - Running pre-action listener: _resource_record_pre_action 16:01:12 INFO - Running main action method: download_and_extract 16:01:12 INFO - mkdir: /builds/slave/test/build/tests 16:01:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:01:12 INFO - trying https://queue.taskcluster.net/v1/task/IQzmNtvaSAKlXq9f9rHYWw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 16:01:12 INFO - Downloading https://queue.taskcluster.net/v1/task/IQzmNtvaSAKlXq9f9rHYWw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 16:01:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IQzmNtvaSAKlXq9f9rHYWw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 16:01:12 INFO - Downloaded 1361 bytes. 16:01:12 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 16:01:12 INFO - Using the following test package requirements: 16:01:12 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 16:01:12 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 16:01:12 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 16:01:12 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 16:01:12 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 16:01:12 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 16:01:12 INFO - u'jsshell-linux-x86_64.zip'], 16:01:12 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 16:01:12 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 16:01:12 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 16:01:12 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 16:01:12 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 16:01:12 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 16:01:12 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 16:01:12 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 16:01:12 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 16:01:12 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 16:01:12 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 16:01:12 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 16:01:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IQzmNtvaSAKlXq9f9rHYWw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 16:01:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IQzmNtvaSAKlXq9f9rHYWw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 16:01:12 INFO - Fetch https://queue.taskcluster.net/v1/task/IQzmNtvaSAKlXq9f9rHYWw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip into memory 16:01:13 INFO - Expected file size: 21936771 16:01:17 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 16:01:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IQzmNtvaSAKlXq9f9rHYWw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 16:01:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IQzmNtvaSAKlXq9f9rHYWw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 16:01:17 INFO - Fetch https://queue.taskcluster.net/v1/task/IQzmNtvaSAKlXq9f9rHYWw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip into memory 16:01:18 INFO - Expected file size: 21936771 16:01:23 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IQzmNtvaSAKlXq9f9rHYWw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 16:01:23 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IQzmNtvaSAKlXq9f9rHYWw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 16:01:23 INFO - Fetch https://queue.taskcluster.net/v1/task/IQzmNtvaSAKlXq9f9rHYWw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip into memory 16:01:23 INFO - Expected file size: 14414048 16:01:24 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')]} 16:01:24 INFO - trying https://queue.taskcluster.net/v1/task/IQzmNtvaSAKlXq9f9rHYWw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 16:01:24 INFO - Downloading https://queue.taskcluster.net/v1/task/IQzmNtvaSAKlXq9f9rHYWw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 16:01:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IQzmNtvaSAKlXq9f9rHYWw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 16:01:26 INFO - Downloaded 59525512 bytes. 16:01:26 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/IQzmNtvaSAKlXq9f9rHYWw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 16:01:26 INFO - mkdir: /builds/slave/test/properties 16:01:26 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:01:26 INFO - Writing to file /builds/slave/test/properties/build_url 16:01:26 INFO - Contents: 16:01:26 INFO - build_url:https://queue.taskcluster.net/v1/task/IQzmNtvaSAKlXq9f9rHYWw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 16:01:26 INFO - Running post-action listener: _resource_record_post_action 16:01:26 INFO - Running post-action listener: set_extra_try_arguments 16:01:26 INFO - [mozharness: 2016-09-17 23:01:26.787197Z] Finished download-and-extract step (success) 16:01:26 INFO - [mozharness: 2016-09-17 23:01:26.787425Z] Running populate-webroot step. 16:01:26 INFO - Running pre-action listener: _resource_record_pre_action 16:01:26 INFO - Running main action method: populate_webroot 16:01:26 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:01:26 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:01:26 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:01:26 INFO - '--tppagecycles', 16:01:26 INFO - '7'], 16:01:26 INFO - 'tests': ['tsvgm']}}, 16:01:26 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:01:26 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:01:26 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:01:26 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:01:26 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:01:26 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:01:26 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:01:26 INFO - 'talos_options': ['--disable-e10s'], 16:01:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:01:26 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:01:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:01:26 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:01:26 INFO - 'talos_options': ['--disable-e10s'], 16:01:26 INFO - 'tests': ['damp', 'tps']}, 16:01:26 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:01:26 INFO - 'tests': ['damp', 'tps']}, 16:01:26 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:01:26 INFO - 'tests': ['dromaeo_dom']}, 16:01:26 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:01:26 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:01:26 INFO - 'tests': ['basic_compositor_video']}, 16:01:26 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:01:26 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:01:26 INFO - 'tests': ['a11yr', 16:01:26 INFO - 'ts_paint', 16:01:26 INFO - 'tpaint', 16:01:26 INFO - 'sessionrestore', 16:01:26 INFO - 'sessionrestore_no_auto_restore', 16:01:26 INFO - 'tabpaint']}, 16:01:26 INFO - 'other-e10s': {'tests': ['a11yr', 16:01:26 INFO - 'ts_paint', 16:01:26 INFO - 'tpaint', 16:01:26 INFO - 'sessionrestore', 16:01:26 INFO - 'sessionrestore_no_auto_restore', 16:01:26 INFO - 'tabpaint']}, 16:01:26 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:01:26 INFO - 'ts_paint', 16:01:26 INFO - 'tpaint', 16:01:26 INFO - 'sessionrestore', 16:01:26 INFO - 'sessionrestore_no_auto_restore', 16:01:26 INFO - 'tabpaint']}, 16:01:26 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:01:26 INFO - 'ts_paint', 16:01:26 INFO - 'tpaint', 16:01:26 INFO - 'sessionrestore', 16:01:26 INFO - 'sessionrestore_no_auto_restore', 16:01:26 INFO - 'tabpaint']}, 16:01:26 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:01:26 INFO - 'tests': ['a11yr', 16:01:26 INFO - 'ts_paint', 16:01:26 INFO - 'tpaint', 16:01:26 INFO - 'sessionrestore', 16:01:26 INFO - 'sessionrestore_no_auto_restore', 16:01:26 INFO - 'tabpaint']}, 16:01:26 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:01:26 INFO - 'tests': ['a11yr', 16:01:26 INFO - 'ts_paint', 16:01:26 INFO - 'tpaint', 16:01:26 INFO - 'sessionrestore', 16:01:26 INFO - 'sessionrestore_no_auto_restore', 16:01:26 INFO - 'tabpaint']}, 16:01:26 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:01:26 INFO - 'tests': ['tsvgx', 16:01:26 INFO - 'tsvgr_opacity', 16:01:26 INFO - 'tart', 16:01:26 INFO - 'tscrollx', 16:01:26 INFO - 'cart']}, 16:01:26 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:01:26 INFO - 'tsvgr_opacity', 16:01:26 INFO - 'tart', 16:01:26 INFO - 'tscrollx', 16:01:26 INFO - 'cart']}, 16:01:26 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:01:26 INFO - 'talos_options': ['--disable-e10s'], 16:01:26 INFO - 'tests': ['tp5o']}, 16:01:26 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:01:26 INFO - 'tests': ['tp5o']}, 16:01:26 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:01:26 INFO - 'talos_options': ['--disable-e10s', 16:01:26 INFO - '--xperf_path', 16:01:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:01:26 INFO - 'tests': ['tp5n']}, 16:01:26 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:01:26 INFO - 'talos_options': ['--xperf_path', 16:01:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:01:26 INFO - 'tests': ['tp5n']}}, 16:01:26 INFO - 'talos.zip': {'path': '', 16:01:26 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:01:26 INFO - Running post-action listener: _resource_record_post_action 16:01:26 INFO - [mozharness: 2016-09-17 23:01:26.803644Z] Finished populate-webroot step (success) 16:01:26 INFO - [mozharness: 2016-09-17 23:01:26.803734Z] Running create-virtualenv step. 16:01:26 INFO - Running pre-action listener: _resource_record_pre_action 16:01:26 INFO - Running main action method: create_virtualenv 16:01:26 INFO - Creating virtualenv /builds/slave/test/build/venv 16:01:26 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 16:01:26 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:01:26 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:01:26 INFO - Using real prefix '/usr' 16:01:26 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:01:27 INFO - Installing distribute.............................................................................................................................................................................................done. 16:01:29 INFO - Installing pip.................done. 16:01:29 INFO - Return code: 0 16:01:29 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:01:29 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')]} 16:01:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:01:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:01:29 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')]} 16:01:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:01:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:01:29 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 0x7fc67c084f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14c08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x158c7b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x161b790>, '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 0x161c690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x161cb20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1474152371.346314-1419243930', '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 16:01:29 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 16:01:29 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 16:01:29 INFO - Using env: {'DISPLAY': ':0', 16:01:29 INFO - 'HOME': '/home/cltbld', 16:01:29 INFO - 'LANG': 'en_US.UTF-8', 16:01:29 INFO - 'LANGUAGE': 'en_US:en', 16:01:29 INFO - 'LOGNAME': 'cltbld', 16:01:29 INFO - 'MAIL': '/var/mail/cltbld', 16:01:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:01:29 INFO - 'MOZ_NO_REMOTE': '1', 16:01:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:01:29 INFO - 'NO_EM_RESTART': '1', 16:01:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:01:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:01:29 INFO - 'PWD': '/builds/slave/test', 16:01:29 INFO - 'SHELL': '/bin/bash', 16:01:29 INFO - 'SHLVL': '1', 16:01:29 INFO - 'TERM': 'linux', 16:01:29 INFO - 'TMOUT': '86400', 16:01:29 INFO - 'USER': 'cltbld', 16:01:29 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1474152371.346314-1419243930', 16:01:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:01:29 INFO - '_': '/tools/buildbot/bin/python'} 16:01:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:01:29 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 16:01:29 INFO - Cleaning up... 16:01:29 INFO - Return code: 0 16:01:29 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:01:29 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')]} 16:01:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:01:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:01:29 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')]} 16:01:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:01:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:01:29 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 0x7fc67c084f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14c08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x158c7b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x161b790>, '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 0x161c690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x161cb20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1474152371.346314-1419243930', '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 16:01:29 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 16:01:29 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 16:01:29 INFO - Using env: {'DISPLAY': ':0', 16:01:29 INFO - 'HOME': '/home/cltbld', 16:01:29 INFO - 'LANG': 'en_US.UTF-8', 16:01:29 INFO - 'LANGUAGE': 'en_US:en', 16:01:29 INFO - 'LOGNAME': 'cltbld', 16:01:29 INFO - 'MAIL': '/var/mail/cltbld', 16:01:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:01:29 INFO - 'MOZ_NO_REMOTE': '1', 16:01:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:01:29 INFO - 'NO_EM_RESTART': '1', 16:01:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:01:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:01:29 INFO - 'PWD': '/builds/slave/test', 16:01:29 INFO - 'SHELL': '/bin/bash', 16:01:29 INFO - 'SHLVL': '1', 16:01:29 INFO - 'TERM': 'linux', 16:01:29 INFO - 'TMOUT': '86400', 16:01:29 INFO - 'USER': 'cltbld', 16:01:29 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1474152371.346314-1419243930', 16:01:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:01:29 INFO - '_': '/tools/buildbot/bin/python'} 16:01:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:01:29 INFO - Downloading/unpacking psutil>=3.1.1 16:01:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:01:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:01:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:01:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:01:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:01:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:01:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:01:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:01:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:01:31 INFO - Installing collected packages: psutil 16:01:31 INFO - Running setup.py install for psutil 16:01:31 INFO - building 'psutil._psutil_linux' extension 16:01:31 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 16:01:32 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 16:01:32 INFO - building 'psutil._psutil_posix' extension 16:01:32 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 16:01:32 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 16:01:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:01:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:01:32 INFO - Successfully installed psutil 16:01:32 INFO - Cleaning up... 16:01:32 INFO - Return code: 0 16:01:32 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:01:32 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')]} 16:01:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:01:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:01:32 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')]} 16:01:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:01:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:01:32 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 0x7fc67c084f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14c08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x158c7b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x161b790>, '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 0x161c690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x161cb20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1474152371.346314-1419243930', '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 16:01:32 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 16:01:32 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 16:01:32 INFO - Using env: {'DISPLAY': ':0', 16:01:32 INFO - 'HOME': '/home/cltbld', 16:01:32 INFO - 'LANG': 'en_US.UTF-8', 16:01:32 INFO - 'LANGUAGE': 'en_US:en', 16:01:32 INFO - 'LOGNAME': 'cltbld', 16:01:32 INFO - 'MAIL': '/var/mail/cltbld', 16:01:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:01:32 INFO - 'MOZ_NO_REMOTE': '1', 16:01:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:01:32 INFO - 'NO_EM_RESTART': '1', 16:01:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:01:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:01:32 INFO - 'PWD': '/builds/slave/test', 16:01:32 INFO - 'SHELL': '/bin/bash', 16:01:32 INFO - 'SHLVL': '1', 16:01:32 INFO - 'TERM': 'linux', 16:01:32 INFO - 'TMOUT': '86400', 16:01:32 INFO - 'USER': 'cltbld', 16:01:32 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1474152371.346314-1419243930', 16:01:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:01:32 INFO - '_': '/tools/buildbot/bin/python'} 16:01:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:01:32 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:01:32 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 16:01:32 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 16:01:32 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 16:01:32 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 16:01:32 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 16:01:32 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 16:01:34 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:01:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:01:34 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:01:34 INFO - Installing collected packages: mozsystemmonitor 16:01:34 INFO - Running setup.py install for mozsystemmonitor 16:01:34 INFO - Successfully installed mozsystemmonitor 16:01:34 INFO - Cleaning up... 16:01:34 INFO - Return code: 0 16:01:34 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:01:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:01:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:01:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:01:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:01:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:01:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:01:34 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 0x7fc67c084f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14c08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x158c7b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x161b790>, '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 0x161c690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x161cb20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1474152371.346314-1419243930', '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 16:01:34 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 16:01:34 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 16:01:34 INFO - Using env: {'DISPLAY': ':0', 16:01:34 INFO - 'HOME': '/home/cltbld', 16:01:34 INFO - 'LANG': 'en_US.UTF-8', 16:01:34 INFO - 'LANGUAGE': 'en_US:en', 16:01:34 INFO - 'LOGNAME': 'cltbld', 16:01:34 INFO - 'MAIL': '/var/mail/cltbld', 16:01:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:01:34 INFO - 'MOZ_NO_REMOTE': '1', 16:01:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:01:34 INFO - 'NO_EM_RESTART': '1', 16:01:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:01:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:01:34 INFO - 'PWD': '/builds/slave/test', 16:01:34 INFO - 'SHELL': '/bin/bash', 16:01:34 INFO - 'SHLVL': '1', 16:01:34 INFO - 'TERM': 'linux', 16:01:34 INFO - 'TMOUT': '86400', 16:01:34 INFO - 'USER': 'cltbld', 16:01:34 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1474152371.346314-1419243930', 16:01:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:01:34 INFO - '_': '/tools/buildbot/bin/python'} 16:01:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:01:34 INFO - Downloading/unpacking jsonschema==2.5.1 16:01:34 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 16:01:34 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 16:01:34 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 16:01:34 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 16:01:34 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 16:01:34 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 16:01:36 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:01:36 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:01:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:01:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:01:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:01:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:01:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:01:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:01:36 INFO - Downloading functools32-3.2.3-2.tar.gz 16:01:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:01:37 INFO - warning: no files found matching '*.txt' 16:01:37 INFO - no previously-included directories found matching 'build' 16:01:37 INFO - no previously-included directories found matching 'dist' 16:01:37 INFO - no previously-included directories found matching '.git*' 16:01:37 INFO - Installing collected packages: jsonschema, functools32 16:01:37 INFO - Running setup.py install for functools32 16:01:37 INFO - warning: no files found matching '*.txt' 16:01:37 INFO - no previously-included directories found matching 'build' 16:01:37 INFO - no previously-included directories found matching 'dist' 16:01:37 INFO - no previously-included directories found matching '.git*' 16:01:37 INFO - Successfully installed jsonschema functools32 16:01:37 INFO - Cleaning up... 16:01:37 INFO - Return code: 0 16:01:37 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:01:37 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')]} 16:01:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:01:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:01:37 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')]} 16:01:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:01:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:01:37 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 0x7fc67c084f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14c08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x158c7b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x161b790>, '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 0x161c690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x161cb20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1474152371.346314-1419243930', '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 16:01:37 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 16:01:37 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 16:01:37 INFO - Using env: {'DISPLAY': ':0', 16:01:37 INFO - 'HOME': '/home/cltbld', 16:01:37 INFO - 'LANG': 'en_US.UTF-8', 16:01:37 INFO - 'LANGUAGE': 'en_US:en', 16:01:37 INFO - 'LOGNAME': 'cltbld', 16:01:37 INFO - 'MAIL': '/var/mail/cltbld', 16:01:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:01:37 INFO - 'MOZ_NO_REMOTE': '1', 16:01:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:01:37 INFO - 'NO_EM_RESTART': '1', 16:01:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:01:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:01:37 INFO - 'PWD': '/builds/slave/test', 16:01:37 INFO - 'SHELL': '/bin/bash', 16:01:37 INFO - 'SHLVL': '1', 16:01:37 INFO - 'TERM': 'linux', 16:01:37 INFO - 'TMOUT': '86400', 16:01:37 INFO - 'USER': 'cltbld', 16:01:37 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1474152371.346314-1419243930', 16:01:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:01:37 INFO - '_': '/tools/buildbot/bin/python'} 16:01:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:01:37 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:01:37 INFO - Cleaning up... 16:01:37 INFO - Return code: 0 16:01:37 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:01:37 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')]} 16:01:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:01:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:01:37 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')]} 16:01:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:01:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:01:37 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 0x7fc67c084f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14c08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x158c7b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x161b790>, '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 0x161c690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x161cb20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1474152371.346314-1419243930', '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 16:01:37 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 16:01:37 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 16:01:37 INFO - Using env: {'DISPLAY': ':0', 16:01:37 INFO - 'HOME': '/home/cltbld', 16:01:37 INFO - 'LANG': 'en_US.UTF-8', 16:01:37 INFO - 'LANGUAGE': 'en_US:en', 16:01:37 INFO - 'LOGNAME': 'cltbld', 16:01:37 INFO - 'MAIL': '/var/mail/cltbld', 16:01:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:01:37 INFO - 'MOZ_NO_REMOTE': '1', 16:01:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:01:37 INFO - 'NO_EM_RESTART': '1', 16:01:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:01:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:01:37 INFO - 'PWD': '/builds/slave/test', 16:01:37 INFO - 'SHELL': '/bin/bash', 16:01:37 INFO - 'SHLVL': '1', 16:01:37 INFO - 'TERM': 'linux', 16:01:37 INFO - 'TMOUT': '86400', 16:01:37 INFO - 'USER': 'cltbld', 16:01:37 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1474152371.346314-1419243930', 16:01:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:01:37 INFO - '_': '/tools/buildbot/bin/python'} 16:01:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:01:37 INFO - Downloading/unpacking blobuploader==1.2.4 16:01:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:01:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:01:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:01:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:01:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:01:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:01:39 INFO - Downloading blobuploader-1.2.4.tar.gz 16:01:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:01:39 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:01:39 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 16:01:39 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 16:01:39 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 16:01:39 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 16:01:39 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 16:01:39 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 16:01:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:01:40 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:01:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:01:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:01:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:01:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:01:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:01:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:01:40 INFO - Downloading docopt-0.6.1.tar.gz 16:01:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:01:40 INFO - Installing collected packages: blobuploader, requests, docopt 16:01:40 INFO - Running setup.py install for blobuploader 16:01:40 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:01:40 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:01:40 INFO - Running setup.py install for requests 16:01:40 INFO - Running setup.py install for docopt 16:01:40 INFO - Successfully installed blobuploader requests docopt 16:01:40 INFO - Cleaning up... 16:01:40 INFO - Return code: 0 16:01:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:01:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:01:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:01:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:01:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:01:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:01:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:01:40 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 0x7fc67c084f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14c08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x158c7b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x161b790>, '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 0x161c690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x161cb20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1474152371.346314-1419243930', '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 16:01:40 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 16:01:40 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 16:01:40 INFO - Using env: {'DISPLAY': ':0', 16:01:40 INFO - 'HOME': '/home/cltbld', 16:01:40 INFO - 'LANG': 'en_US.UTF-8', 16:01:40 INFO - 'LANGUAGE': 'en_US:en', 16:01:40 INFO - 'LOGNAME': 'cltbld', 16:01:40 INFO - 'MAIL': '/var/mail/cltbld', 16:01:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:01:40 INFO - 'MOZ_NO_REMOTE': '1', 16:01:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:01:40 INFO - 'NO_EM_RESTART': '1', 16:01:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:01:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:01:40 INFO - 'PWD': '/builds/slave/test', 16:01:40 INFO - 'SHELL': '/bin/bash', 16:01:40 INFO - 'SHLVL': '1', 16:01:40 INFO - 'TERM': 'linux', 16:01:40 INFO - 'TMOUT': '86400', 16:01:40 INFO - 'USER': 'cltbld', 16:01:40 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1474152371.346314-1419243930', 16:01:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:01:40 INFO - '_': '/tools/buildbot/bin/python'} 16:01:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:01:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:01:41 INFO - Running setup.py (path:/tmp/pip-kNyidB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:01:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:01:41 INFO - Running setup.py (path:/tmp/pip-lBigFm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:01:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:01:41 INFO - Running setup.py (path:/tmp/pip-XBUaga-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:01:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:01:41 INFO - Running setup.py (path:/tmp/pip-VtgcV6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:01:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:01:41 INFO - Running setup.py (path:/tmp/pip-P0EsuQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:01:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:01:41 INFO - Running setup.py (path:/tmp/pip-pifWYi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:01:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:01:41 INFO - Running setup.py (path:/tmp/pip-N0gHD2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:01:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:01:41 INFO - Running setup.py (path:/tmp/pip-CcU705-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:01:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:01:41 INFO - Running setup.py (path:/tmp/pip-CER7Y5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:01:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:01:41 INFO - Running setup.py (path:/tmp/pip-lguY9U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:01:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:01:41 INFO - Running setup.py (path:/tmp/pip-6V9tQK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:01:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:01:41 INFO - Running setup.py (path:/tmp/pip-L5H8h4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:01:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:01:41 INFO - Running setup.py (path:/tmp/pip-FZg5vh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:01:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:01:42 INFO - Running setup.py (path:/tmp/pip-9TXkZO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:01:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:01:42 INFO - Running setup.py (path:/tmp/pip-4qhYND-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:01:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:01:42 INFO - Running setup.py (path:/tmp/pip-LecQ7z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:01:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:01:42 INFO - Running setup.py (path:/tmp/pip-fnoi84-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:01:42 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:01:42 INFO - Running setup.py install for manifestparser 16:01:42 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:01:42 INFO - Running setup.py install for mozcrash 16:01:42 INFO - Running setup.py install for mozdebug 16:01:42 INFO - Running setup.py install for mozdevice 16:01:42 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:01:42 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:01:42 INFO - Running setup.py install for mozfile 16:01:42 INFO - Running setup.py install for mozhttpd 16:01:42 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:01:42 INFO - Running setup.py install for mozinfo 16:01:43 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:01:43 INFO - Running setup.py install for mozInstall 16:01:43 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:01:43 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:01:43 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:01:43 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:01:43 INFO - Running setup.py install for mozleak 16:01:43 INFO - Running setup.py install for mozlog 16:01:43 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:01:43 INFO - Running setup.py install for moznetwork 16:01:43 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:01:43 INFO - Running setup.py install for mozprocess 16:01:43 INFO - Running setup.py install for mozprofile 16:01:43 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:01:43 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:01:43 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:01:43 INFO - Running setup.py install for mozrunner 16:01:43 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:01:43 INFO - Running setup.py install for mozscreenshot 16:01:43 INFO - Running setup.py install for moztest 16:01:44 INFO - Running setup.py install for mozversion 16:01:44 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:01:44 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:01:44 INFO - Cleaning up... 16:01:44 INFO - Return code: 0 16:01:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:01:44 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')]} 16:01:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:01:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:01:44 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')]} 16:01:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:01:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:01:44 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 0x7fc67c084f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14c08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x158c7b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x161b790>, '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 0x161c690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x161cb20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1474152371.346314-1419243930', '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 16:01:44 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 16:01:44 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 16:01:44 INFO - Using env: {'DISPLAY': ':0', 16:01:44 INFO - 'HOME': '/home/cltbld', 16:01:44 INFO - 'LANG': 'en_US.UTF-8', 16:01:44 INFO - 'LANGUAGE': 'en_US:en', 16:01:44 INFO - 'LOGNAME': 'cltbld', 16:01:44 INFO - 'MAIL': '/var/mail/cltbld', 16:01:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:01:44 INFO - 'MOZ_NO_REMOTE': '1', 16:01:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:01:44 INFO - 'NO_EM_RESTART': '1', 16:01:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:01:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:01:44 INFO - 'PWD': '/builds/slave/test', 16:01:44 INFO - 'SHELL': '/bin/bash', 16:01:44 INFO - 'SHLVL': '1', 16:01:44 INFO - 'TERM': 'linux', 16:01:44 INFO - 'TMOUT': '86400', 16:01:44 INFO - 'USER': 'cltbld', 16:01:44 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1474152371.346314-1419243930', 16:01:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:01:44 INFO - '_': '/tools/buildbot/bin/python'} 16:01:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:01:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:01:44 INFO - Running setup.py (path:/tmp/pip-zbEh8v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:01:44 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)) 16:01:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:01:44 INFO - Running setup.py (path:/tmp/pip-F56Cl_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:01:44 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)) 16:01:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:01:44 INFO - Running setup.py (path:/tmp/pip-DlIZM5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:01:44 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)) 16:01:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:01:44 INFO - Running setup.py (path:/tmp/pip-fg_05g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:01:44 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)) 16:01:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:01:44 INFO - Running setup.py (path:/tmp/pip-BT1pIr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:01:44 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)) 16:01:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:01:44 INFO - Running setup.py (path:/tmp/pip-zwAcVK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:01:44 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)) 16:01:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:01:44 INFO - Running setup.py (path:/tmp/pip-7bdau5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:01:44 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)) 16:01:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:01:44 INFO - Running setup.py (path:/tmp/pip-qFYMny-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:01:44 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)) 16:01:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:01:44 INFO - Running setup.py (path:/tmp/pip-Vyd76y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:01:45 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)) 16:01:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:01:45 INFO - Running setup.py (path:/tmp/pip-2GZP13-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:01:45 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)) 16:01:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:01:45 INFO - Running setup.py (path:/tmp/pip-rxrAJk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:01:45 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)) 16:01:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:01:45 INFO - Running setup.py (path:/tmp/pip-b_4Sdu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:01:45 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)) 16:01:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:01:45 INFO - Running setup.py (path:/tmp/pip-muINtM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:01:45 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)) 16:01:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:01:45 INFO - Running setup.py (path:/tmp/pip-PeCXJb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:01:45 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)) 16:01:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:01:45 INFO - Running setup.py (path:/tmp/pip-EY4snm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:01:45 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)) 16:01:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:01:45 INFO - Running setup.py (path:/tmp/pip-PEdXP5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:01:45 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)) 16:01:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:01:45 INFO - Running setup.py (path:/tmp/pip-4cHdaF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:01:45 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)) 16:01:45 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)) 16:01:45 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)) 16:01:45 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)) 16:01:45 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)) 16:01:45 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)) 16:01:45 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:01:45 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 16:01:45 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 16:01:45 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 16:01:45 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 16:01:45 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 16:01:45 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 16:01:47 INFO - Downloading blessings-1.6.tar.gz 16:01:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:01:47 INFO - Installing collected packages: blessings 16:01:47 INFO - Running setup.py install for blessings 16:01:47 INFO - Successfully installed blessings 16:01:47 INFO - Cleaning up... 16:01:47 INFO - Return code: 0 16:01:47 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:01:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:01:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:01:47 INFO - Reading from file tmpfile_stdout 16:01:47 INFO - Current package versions: 16:01:47 INFO - argparse == 1.2.1 16:01:47 INFO - blessings == 1.6 16:01:47 INFO - blobuploader == 1.2.4 16:01:47 INFO - docopt == 0.6.1 16:01:47 INFO - functools32 == 3.2.3-2 16:01:47 INFO - jsonschema == 2.5.1 16:01:47 INFO - manifestparser == 1.1 16:01:47 INFO - mozInstall == 1.12 16:01:47 INFO - mozcrash == 0.17 16:01:47 INFO - mozdebug == 0.1 16:01:47 INFO - mozdevice == 0.48 16:01:47 INFO - mozfile == 1.2 16:01:47 INFO - mozhttpd == 0.7 16:01:47 INFO - mozinfo == 0.9 16:01:47 INFO - mozleak == 0.1 16:01:47 INFO - mozlog == 3.4 16:01:47 INFO - moznetwork == 0.27 16:01:47 INFO - mozprocess == 0.23 16:01:47 INFO - mozprofile == 0.28 16:01:47 INFO - mozrunner == 6.12 16:01:47 INFO - mozscreenshot == 0.1 16:01:47 INFO - mozsystemmonitor == 0.3 16:01:47 INFO - moztest == 0.7 16:01:47 INFO - mozversion == 1.4 16:01:47 INFO - psutil == 3.1.1 16:01:47 INFO - requests == 1.2.3 16:01:47 INFO - wsgiref == 0.1.2 16:01:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:01: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')]} 16:01:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:01:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:01: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')]} 16:01:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:01:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:01:47 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 0x7fc67c084f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14c08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x158c7b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x161b790>, '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 0x161c690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x161cb20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1474152371.346314-1419243930', '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 16:01:47 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 16:01:47 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 16:01:47 INFO - Using env: {'DISPLAY': ':0', 16:01:47 INFO - 'HOME': '/home/cltbld', 16:01:47 INFO - 'LANG': 'en_US.UTF-8', 16:01:47 INFO - 'LANGUAGE': 'en_US:en', 16:01:47 INFO - 'LOGNAME': 'cltbld', 16:01:47 INFO - 'MAIL': '/var/mail/cltbld', 16:01:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:01:47 INFO - 'MOZ_NO_REMOTE': '1', 16:01:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:01:47 INFO - 'NO_EM_RESTART': '1', 16:01:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:01:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:01:47 INFO - 'PWD': '/builds/slave/test', 16:01:47 INFO - 'SHELL': '/bin/bash', 16:01:47 INFO - 'SHLVL': '1', 16:01:47 INFO - 'TERM': 'linux', 16:01:47 INFO - 'TMOUT': '86400', 16:01:47 INFO - 'USER': 'cltbld', 16:01:47 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1474152371.346314-1419243930', 16:01:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:01:47 INFO - '_': '/tools/buildbot/bin/python'} 16:01:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:01:48 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)) 16:01:48 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)) 16:01:48 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)) 16:01:48 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)) 16:01:48 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)) 16:01:48 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)) 16:01:48 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)) 16:01:48 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)) 16:01:48 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)) 16:01:48 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)) 16:01:48 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)) 16:01:48 INFO - Cleaning up... 16:01:48 INFO - Return code: 0 16:01:48 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:01:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:01:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:01:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:01:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:01:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:01:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:01:48 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 0x7fc67c084f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14c08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x158c7b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x161b790>, '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 0x161c690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x161cb20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1474152371.346314-1419243930', '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 16:01:48 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 16:01:48 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 16:01:48 INFO - Using env: {'DISPLAY': ':0', 16:01:48 INFO - 'HOME': '/home/cltbld', 16:01:48 INFO - 'LANG': 'en_US.UTF-8', 16:01:48 INFO - 'LANGUAGE': 'en_US:en', 16:01:48 INFO - 'LOGNAME': 'cltbld', 16:01:48 INFO - 'MAIL': '/var/mail/cltbld', 16:01:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:01:48 INFO - 'MOZ_NO_REMOTE': '1', 16:01:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:01:48 INFO - 'NO_EM_RESTART': '1', 16:01:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:01:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:01:48 INFO - 'PWD': '/builds/slave/test', 16:01:48 INFO - 'SHELL': '/bin/bash', 16:01:48 INFO - 'SHLVL': '1', 16:01:48 INFO - 'TERM': 'linux', 16:01:48 INFO - 'TMOUT': '86400', 16:01:48 INFO - 'USER': 'cltbld', 16:01:48 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1474152371.346314-1419243930', 16:01:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:01:48 INFO - '_': '/tools/buildbot/bin/python'} 16:01:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:01:48 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:01:48 INFO - Cleaning up... 16:01:48 INFO - Return code: 0 16:01:48 INFO - Running post-action listener: _resource_record_post_action 16:01:48 INFO - Running post-action listener: _start_resource_monitoring 16:01:48 INFO - Starting resource monitoring. 16:01:48 INFO - [mozharness: 2016-09-17 23:01:48.406203Z] Finished create-virtualenv step (success) 16:01:48 INFO - [mozharness: 2016-09-17 23:01:48.406710Z] Running install step. 16:01:48 INFO - Running pre-action listener: _resource_record_pre_action 16:01:48 INFO - Running main action method: install 16:01:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:01:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:01:48 INFO - Reading from file tmpfile_stdout 16:01:48 INFO - Detecting whether we're running mozinstall >=1.0... 16:01:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:01:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:01:48 INFO - Reading from file tmpfile_stdout 16:01:48 INFO - Output received: 16:01:48 INFO - Usage: mozinstall [options] installer 16:01:48 INFO - Options: 16:01:48 INFO - -h, --help show this help message and exit 16:01:48 INFO - -d DEST, --destination=DEST 16:01:48 INFO - Directory to install application into. [default: 16:01:48 INFO - "/builds/slave/test"] 16:01:48 INFO - --app=APP Application being installed. [default: firefox] 16:01:48 INFO - mkdir: /builds/slave/test/build/application 16:01:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 16:01:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 16:02:05 INFO - Reading from file tmpfile_stdout 16:02:05 INFO - Output received: 16:02:05 INFO - /builds/slave/test/build/application/firefox/firefox 16:02:05 INFO - Running post-action listener: _resource_record_post_action 16:02:05 INFO - [mozharness: 2016-09-17 23:02:05.662222Z] Finished install step (success) 16:02:05 INFO - [mozharness: 2016-09-17 23:02:05.662468Z] Running run-tests step. 16:02:05 INFO - Running pre-action listener: _resource_record_pre_action 16:02:05 INFO - Running main action method: run_tests 16:02:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:02:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:02:05 INFO - Python 2.7.3 16:02:05 INFO - Return code: 0 16:02:05 INFO - grabbing minidump binary from tooltool 16:02:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:02:05 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x161b790>, '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 0x161c690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x161cb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:02:05 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 16:02:05 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 16:02:05 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 16:02:05 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:02:05 INFO - Return code: 0 16:02:05 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:02:05 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:02:05 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:02:05 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:02:05 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:02:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/IQzmNtvaSAKlXq9f9rHYWw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-061', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 16:02:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/IQzmNtvaSAKlXq9f9rHYWw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-061 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 16:02:05 INFO - Using env: {'DISPLAY': ':0', 16:02:05 INFO - 'HOME': '/home/cltbld', 16:02:05 INFO - 'LANG': 'en_US.UTF-8', 16:02:05 INFO - 'LANGUAGE': 'en_US:en', 16:02:05 INFO - 'LOGNAME': 'cltbld', 16:02:05 INFO - 'MAIL': '/var/mail/cltbld', 16:02:05 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:02:05 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:02:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:02:05 INFO - 'MOZ_NO_REMOTE': '1', 16:02:05 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:02:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:02:05 INFO - 'NO_EM_RESTART': '1', 16:02:05 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:02:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:02:05 INFO - 'PWD': '/builds/slave/test', 16:02:05 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:02:05 INFO - 'SHELL': '/bin/bash', 16:02:05 INFO - 'SHLVL': '1', 16:02:05 INFO - 'TERM': 'linux', 16:02:05 INFO - 'TMOUT': '86400', 16:02:05 INFO - 'USER': 'cltbld', 16:02:05 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1474152371.346314-1419243930', 16:02:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:02:05 INFO - '_': '/tools/buildbot/bin/python'} 16:02:05 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/IQzmNtvaSAKlXq9f9rHYWw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-061', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 16:02:05 INFO - mozversion application_buildid: 20160917133006 16:02:05 INFO - mozversion application_changeset: 8d211b6a94f603ef81ffcdee3a2b484c2ecd21cd 16:02:05 INFO - mozversion application_display_name: Nightly 16:02:05 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:02:05 INFO - mozversion application_name: Firefox 16:02:05 INFO - mozversion application_remotingname: firefox 16:02:05 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 16:02:05 INFO - mozversion application_vendor: Mozilla 16:02:05 INFO - mozversion application_version: 51.0a1 16:02:05 INFO - mozversion platform_buildid: 20160917133006 16:02:05 INFO - mozversion platform_changeset: 8d211b6a94f603ef81ffcdee3a2b484c2ecd21cd 16:02:05 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 16:02:05 INFO - mozversion platform_version: 51.0a1 16:02:05 INFO - using testdate: 1474153325 16:02:05 INFO - actual date: 1474153325 16:02:05 INFO - starting webserver on 'localhost:50185' 16:02:05 INFO - SUITE-START | Running 2 tests 16:02:05 INFO - TEST-START | dromaeo_css 16:02:05 INFO - operating with platform_type : linux_ 16:02:05 INFO - Initialising browser for dromaeo_css test... 16:02:05 INFO - TEST-INFO | started process 29199 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpkJv8ey/profile http://localhost:50185/getInfo.html) 16:02:10 INFO - PROCESS | 29199 | __metrics Screen width/height:1600/1200 16:02:10 INFO - PROCESS | 29199 | colorDepth:24 16:02:10 INFO - PROCESS | 29199 | Browser inner width/height: 1024/697 16:02:10 INFO - PROCESS | 29199 | __metrics 16:02:10 INFO - PROCESS | 29199 | 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:02:10 INFO - PROCESS | 29199 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:02:11 INFO - PROCESS | 29199 | console.error: PushService: 16:02:11 INFO - PROCESS | 29199 | stateChangeProcessEnqueue: Error transitioning state 16:02:11 INFO - PROCESS | 29199 | UnknownError 16:02:12 INFO - TEST-INFO | 29199: exit 0 16:02:12 INFO - Browser initialized. 16:02:12 INFO - Running cycle 1/1 for dromaeo_css test... 16:02:12 INFO - TEST-INFO | started process 29281 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpkJv8ey/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:02:24 INFO - PROCESS | 29281 | RSS: Main: 177184768 16:02:24 INFO - PROCESS | 29281 | 16:02:39 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 16:03:14 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 16:03:15 INFO - PROCESS | 29281 | 1474153395694 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] 16:03:22 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.03339 seconds. 16:03:26 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.05009 seconds. 16:03:28 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 16:03:30 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 16:03:39 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.05006 seconds. 16:03:45 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.05003 seconds. 16:03:56 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.05005 seconds. 16:04:04 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.05006 seconds. 16:04:16 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 16:04:17 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 16:04:19 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 16:04:21 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 16:04:35 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.10004 seconds. 16:04:44 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 16:04:57 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.1834 seconds. 16:05:06 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 16:05:07 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 16:05:15 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.88333 seconds. 16:05:25 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 16:05:25 INFO - PROCESS | 29281 | Cycle 1(1): loaded http://localhost:50185/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50185/tests/dromaeo/cssquery-ext.html) 16:05:27 INFO - PROCESS | 29281 | RSS: Main: 272773120 16:05:27 INFO - PROCESS | 29281 | 16:05:30 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.01668 seconds. 16:05:41 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.01675 seconds. 16:05:49 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 16:05:51 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 16:06:03 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 16:06:19 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.03338 seconds. 16:06:20 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 16:06:26 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 16:06:30 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.01674 seconds. 16:06:39 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 16:06:40 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 16:06:44 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.00449 seconds. 16:06:50 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 16:07:22 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 16:07:30 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 16:07:32 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 16:07:48 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.03339 seconds. 16:07:50 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 16:08:03 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 16:08:07 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 16:08:21 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 16:08:25 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 16:08:29 INFO - PROCESS | 29281 | Cycle 1(1): loaded http://localhost:50185/tests/dromaeo/cssquery-ext.html (next: http://localhost:50185/tests/dromaeo/cssquery-jquery.html) 16:08:30 INFO - PROCESS | 29281 | RSS: Main: 279531520 16:08:30 INFO - PROCESS | 29281 | 16:08:36 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.05006 seconds. 16:08:48 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 16:08:52 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 16:09:00 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 16:09:02 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 16:09:15 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.00008 seconds. 16:09:41 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 16:09:49 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 16:09:57 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 16:09:59 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 16:10:01 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 16:10:09 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 16:10:10 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 2.01674 seconds. 16:10:31 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 16:10:55 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 16:11:01 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 16:11:07 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 16:11:18 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 16:11:32 INFO - PROCESS | 29281 | Cycle 1(1): loaded http://localhost:50185/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50185/tests/dromaeo/cssquery-mootools.html) 16:11:32 INFO - PROCESS | 29281 | RSS: Main: 253181952 16:11:32 INFO - PROCESS | 29281 | 16:11:36 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 16:11:40 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 16:11:43 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 16:11:59 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 16:12:11 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 16:12:17 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 16:12:19 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 16:12:23 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 16:12:31 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 16:12:35 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 16:12:43 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.00011 seconds. 16:12:45 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 16:12:51 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 16:12:53 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 16:13:05 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 16:13:12 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.01675 seconds. 16:13:16 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.00009 seconds. 16:13:18 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 16:13:36 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 16:14:02 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 16:14:14 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 16:14:22 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 16:14:28 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.01687 seconds. 16:14:32 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 16:14:34 INFO - PROCESS | 29281 | Cycle 1(1): loaded http://localhost:50185/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50185/tests/dromaeo/cssquery-prototype.html) 16:14:35 INFO - PROCESS | 29281 | RSS: Main: 258945024 16:14:35 INFO - PROCESS | 29281 | 16:14:42 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 16:14:51 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 16:14:52 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 16:15:10 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 16:15:22 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 16:15:26 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 16:15:28 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 16:15:32 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 16:15:50 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.05007 seconds. 16:15:52 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 16:16:17 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 16:16:25 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 16:16:32 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 16:16:33 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 16:16:39 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 16:16:43 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 16:16:46 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 16:16:47 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 16:16:54 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 16:17:05 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 16:17:07 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 16:17:13 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.03336 seconds. 16:17:26 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 16:17:30 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 16:17:34 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 16:17:38 INFO - PROCESS | 29281 | Cycle 1(1): loaded http://localhost:50185/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50185/tests/dromaeo/cssquery-yui.html) 16:17:38 INFO - PROCESS | 29281 | RSS: Main: 215973888 16:17:38 INFO - PROCESS | 29281 | 16:18:31 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 16:18:37 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 16:18:46 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 16:18:55 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 16:18:56 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 16:19:01 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 16:19:02 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 16:19:06 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 16:19:22 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 16:19:24 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.01674 seconds. 16:19:26 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.01666 seconds. 16:19:36 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 16:19:47 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 16:19:50 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 16:20:00 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 16:20:25 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 16:20:26 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 2.05006 seconds. 16:20:32 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 16:20:37 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.00316 seconds. 16:20:39 INFO - PROCESS | 29281 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 16:20:41 INFO - PROCESS | 29281 | Cycle 1(1): loaded http://localhost:50185/tests/dromaeo/cssquery-yui.html (next: http://localhost:50185/tests/dromaeo/cssquery-dojo.html) 16:20:41 INFO - PROCESS | 29281 | RSS: Main: 298991616 16:20:41 INFO - PROCESS | 29281 | 16:20:41 INFO - PROCESS | 29281 | __start_tp_report 16:20:41 INFO - PROCESS | 29281 | _x_x_mozilla_page_load 16:20:41 INFO - PROCESS | 29281 | _x_x_mozilla_page_load_details 16:20:41 INFO - PROCESS | 29281 | |i|pagename|runs| 16:20:41 INFO - PROCESS | 29281 | |0;dojo.html;3019.305559721264;3038.863251153698;3052.633683957925;3063.9846800765995;3090.35102628448;5219.660722053073;5261.76322065507;5325.573954083674;5330.706811125387;5370.865728356793;5310.15037593985;5361.892762144755;5363.54409875161;5385.78456861725;5425.104857698485;4837.814735389835;4905.730184839832;4923.507649235084;4924.064427758733;5021.472745361739;4920.6309526785635;4998.975005124969;5008.9499105008845;5040.621953353495;5121.641485096045;4992.650514463989;5009.148444764399;5028.321176641161;5057.216131499605;5104.566111880495;4770.642201834877;4804.039192161546;4810.879728006793;4842.1526232909455;4903.166461701519;4802.903941921142;4807.567318941312;4819.180739274332;4938.481459446778;4944.9258261126115;5303.443138470444;5373.59698022646;5380.650257733235;5388.245645609618;5438.211459338374;5562.582806289544;5617.073182924853;5759.337676167211;5791.65250084992;5804.1293805929445;5689.9146512802345;5734.025215713297;5739.081746920473;5761.423857614247;5778.42215778423;5345.492178243061;5351.384590772033;5355.866103347408;5355.973220133952;5356.196570514454;5717.714114294268;5880.852978675567;5887.852803679856;5894.263217097863;5899.410058994066;5551.1673249012165;5552.167174923713;5609.663420194802;5619.100943848965;5707.914381284284;5693.629914055573;5699;5703.230063941398;5708.828735137952;5709.543236541067;5648.943510564842;5689.9146512802345;5700.942990570041;5700.9429905701245;5707.315122185364;5537.723113844291;5607.186957891083;5639.802606908821;5650.660960342392;5688.288963879515;4412.404325416028;4417.028253784162;4442.400275962704;4454.153710794939;4463.62059224963;3946.8421263149726;3967.206558688274;3969;4012;4017.4375587417785;5268.762905669254;5442.183672449164;5442.591805614595;5446.2375267462585;5448.37343705471;5624.943750562524;5641.548676105902;5664.830055098354;5666.858328541819;5715.142728590745;5330.440303768049;5352.357717073976;5367.597430192673;5371.140617501181;5371.462853714597;4941.604671626261;4963.379577552806;4978.10394129053;4980.12847751642;4985.725785081855;5855.355910849803;5877.706114694248;5886;5899.705014749331;5943.643381397216;5795.478406943227;5833.970830145822;5834.445727655866;5836.182934389104;5840.883182336414;5963.642181469126;5976.701164941648;5979.1928089707335;5981.431763982415;5983.461488466059;5673.574481913791;5679.801206957736;5680.261565996394;5683.77264909399;5684.289463817023;5627.718614069198;5678.829635110953;5685.431456854281;5724.112762521816;5783.595148339576;5687;5697.601167918206;5709.400512946081;5709.9714501427225;5721.942780572141;5529.972350138223;5532.336119665666;5534.861628459321;5539.390667026704;5544.057510223352;5392.975334686397;5488.17677348401;5491.752871120889;5495.395506494203;5496.258005169411;5513.062779327443;5539.446055394428;5544.251526043932;5561.6663000220115;5625.831225063254;5440.918386224289;5485.533729633026;5486.231927530229;5488.121900495901;5500.339959204921 16:20:41 INFO - PROCESS | 29281 | |1;ext.html;586.0593747036007;618.2488276743757;632.1339764522605;635.4725577770452;643.0482885329715;31817.204569885736;32648.183795405126;35522.46716299257;35537.46693799595;35566.11084722883;34416.31167376651;42460.787696061474;43113.35329970053;43302.783486082524;46589;8772.649094036231;8911.197992180703;8913.77715557113;8914.751934729144;8917.509536975464;8759.562021898912;8842.248408885253;8846.894138232721;8849.159329863685;8855.291576673882;8060.637271322805;8231.0534288557;10503.527341269622;10748.355098694103;10774.299670521392;10749.62376316829;10793.190510711727;10801.67594972152;10820;10820.13438924884;7980.680772769082;10476.423796691179;10557.313774604627;10568.947155264252;10572.735681607945;32179;39891.8005409974;40339.394909076385;40369.59630403688;43972;3627.782333060025;3731.71255916711;3792.0140763401437;3920.2551515212017;4083.3262511685534;28553.714462855314;29017.12948611545;31209.531857022164;33755.324893501995;33797.6620233797;4275.700700950934;4286.849960251377;4331.718438301532;4425.116652699751;4571.839985600522;347061;353159;353608;354017;354588;62832.48670053238;62903.37096629067;63662.36337636657;63743;63756;66607.33392666109;67193.66403167952;67257.27427257235;67275;67315.61475081982;14674.119552826865;15176.317065732068;15297.6175595611;16126.516204513884;17043.659126817394;13086.280254586089;13447.46210151583;13450.798238026437;13467.595972120853;13474.393652285671;17293.827061729473;17615.21272926327;19817.20731170737;19990.50023749389;20069.698954515694;7271.818204544823;7313.780586582411;7416.233498657017;7443;7740.651670674833;847.7965288330879;909.9954500227589;915.4049867586123;926.9119433653792;941.8210539997378;30520;31413.37173256522;31867.681323186935;31987.36025279528;31990.403376737017;1059.9205059620406;1086.6902932664152;1102.5314241447513;1117.0951529261324;1172.3083380805194;14837.480688176078;16723;16784.832151678573;16800.74798878018;16843.446535495954;544063;558130;558513;558908;558991;57330.426695733346;57784;57983.42016579865;58050.709746451;58101.4189858096;1255.378587599126;1272.338384040294;1332.1208002718354;1370.8149399831293;1375.2229990055787;30950.845245773628;31869.521957170196;32881;32909.34181316408;33387.66612333846;50298.49701502938;50876.236856447926;51068;51077;51134.488655112975;391050;392231;393321;393867;393888;41729;42365.36451953161;42853.571464284956;45197.1110591955;46153.76923115363;46937.53062469468;47358.52641473679;47938;48239;48483.75758121187;40027.79986100051;40348.193036138524;40606;40876.18247634971;42044;39710.80144599258;40186.19627607373;40274;41522;41886.371704425066;39291.60708392879;39598.60401396064;39735.20529589334;39796.602033980445;40670.18659626732;34602.65397345994;36364.454533181495;36452.45321320235;37107.814460927526;37450.81274593718;27757.306067348476;27873.16380508588;27912.581311280752;27970;28104.43791124207 16:20:41 INFO - PROCESS | 29281 | |2;jquery.html;382.3652736457478;400.14968192590743;410.7104491333609;411.1284077755159;416.91453252083323;13379.79930301045;15140.621484462894;18651.067446627683;19065.809341906577;19478.610427791435;17350.3564251507;17597.824021759778;18420.72368914464;18896.244150233975;18997.62004759904;5662.943370566303;5733.254676891998;5943.494802941756;5943.613665111754;5947.464728174464;5729.140628905656;5818.5345172386105;5830.387809280007;5830.82507524775;5839.708014599253;5658.858528536799;5712.857178570527;5784.045632470637;5809.419058094178;5809.854753631151;5753.223314852483;5819.505342045953;5822.359540450547;5824.0390335594575;5836.766529338822;5046.293518907356;5181.766820493087;5225.77194359329;5232.189010703347;5233.345831771028;22527.662085068736;22691.205807796814;22818.201362952383;22935.426614334607;22950.311490655306;2353.7646235376496;2370.7510711375403;2373.2524254859836;2440.987795061031;3075.492543730282;14326.856731432761;14631.487897923627;14712.852871471256;14808.851911480962;14895.180765057918;1867.5891303913118;1872.9216683318157;1930.8165724256173;1939.5829896572304;2001.8999050047585;79691.6015419925;80161.59919200424;81252.59373703094;82585.58707206546;84174;10322.535485903152;10473.68578942633;10660.893391066145;10770.24608277413;11113.611023614134;14752.63118422048;14764.37591864894;14773.409063637335;14790.482333118289;14875.553733388017;7917.802054948557;8279.296259817984;8385.664573417116;8589.699360522476;8603.612837422332;7943.563104029218;8012.198780121941;8029.277365037175;8194.631241594143;8246.876296855551;6421.454176395058;6445;6496.902546461807;6548.755736410067;6668.066470694107;1878.4364690592768;1884.5959759978903;1923.9038048097539;1924.6728056230195;1966.8229859312444;846.5597889097635;890.6620122179279;946.432140715579;964.7539877331376;1005.9688818960624;18252.254413852228;19464;19665.213391464466;19909.217248722696;20049.398518044483;983.306768728045;996.5465713100667;999.3554157568328;1039.4698703661188;1048.8374301983206;10351.741206469747;10856.480092787016;10861.511231994578;10920.836187457195;11150.721231969266;359861;363477;493239;497860;505586;89665;94368;95501;96646.51676741571;96774;996.7657600463886;1014.3508154781093;1049.7847941172042;1051.2115913065202;1055.6516349604765;16594.75107873359;17074.48776536706;17091.829081709024;17325.56686082858;17410.129493525525;40110.79944600258;40906.1818763629;41296;41439.37840932449;41534.79232603818;54692.45307546874;55190.72404637951;57496;58119;58801;7341.155767086853;7569.280918312751;8101.351891848756;8229.547374894439;8655.48067115975;12263.754724905273;13062.934685326512;13184.802227966396;13259.867401325862;13382.799258010942;11043.83434248503;11386.601468948547;11523;11578.421078946187;11883.76232475363;6516.869662606816;8089.3528517719615;11442.141839362248;11460.885391145981;11692.181547291608;10037.648682296085;10342.017508336696;11146.88853111491;11460.770784584094;12227.75544489123;7803.414743894345;7875.566843823644;7883.724069657764;8001.159878212937;8112.83774324522;7756.457048006585;9686.564104615169;9991.550380233053;10087.596496140366;10355.689329320132 16:20:41 INFO - PROCESS | 29281 | |3;mootools.html;1310.862359452257;1441.3153751967814;1498.940042398304;1609.3160406827096;1765.8234176582343;7752.8837067443965;8673.262772664324;8774.990876049249;8846.424982376157;9161.862572061407;8961.91038089619;9012.864807027883;9027.864582031274;9040.502772347518;9040.819183616324;2372.715274167098;2396.712394512661;2400.9039638414442;2401.411654144737;2403.615421532555;2371.6561098640686;2391.473875747333;2405.4707964247837;2409.518096380722;2411.131992482705;2358.0332063853825;2365.5150694107665;2378.108209421467;2388.916387926423;2393.234165067179;2334.3580515358244;2364.397078744926;2366.0890557135453;2372.750861159571;2373.5134297469062;2276.8292378071546;2299.988500057506;2305.5388922215457;2309.976900230993;2313.144136669426;8915.331350148765;8980.595873185723;9005.32460065491;9009.729708108767;9014.098590140999;5166.43169251382;5361.839144825662;5362.383325917531;5400.270963419927;5406.864828379283;3340.498925161221;3458.87893923714;3504.351694936445;3533.0460775590445;3535.292941411728;1115.8382034605017;1126.8929451702156;1139.4245905817509;1156.3871148291419;1168.9473973671206;11851.525938962432;11877.287362758176;11909.10681698876;11934.343611101385;11971.700707482296;4342.804573794187;4467.084247729208;4529.143051348931;4670.906581868344;4682.391289132459;9374.250059995184;9383.812323753624;9390.483523406278;9405.106514881076;9406.858897116548;5100.540951314325;5103.362079740033;5104.668196567211;5114.4629813869005;5115.462876397924;4404.581564751344;4531.252343363308;4768.260919557474;4799.736014519235;4843.8304659337455;3718.3678774608384;3831.367824308958;3846.1730727893637;3884.7863367515056;4040;1195.7488927325185;1224.9632511024683;1227.4783217132683;1231.6181983585118;1256.6669832494397;728.2280782370598;855.6748435594559;878.0999475537625;897.5063714956748;931.9161275485238;5741.311042674823;5756.510696590837;5762.481376676035;5863.208466857005;6082.787102451482;921.6267411698273;944.4899754132691;952.8237276103943;971.8493633486821;985.556499575194;3929;4023.657989070962;4053.3116892758426;4076.5461515071743;4175.164967006611;10724.195685323632;11100.167487438475;11136.220464567565;11143.442827858737;11186.664400068012;10992.890071099346;11011.28426652281;11028.062614677681;11029.503672334764;11037.889621103686;8254.463459875089;8379.539125348165;8393.328533717287;8394.664213431395;8399.664013439517;5452.509274165344;5573.5819813513335;5712.228849105318;5819.854503637273;5896.852578685567;10147.036031576989;10198.43908585035;10212.795744085226;10542.631007914686;10575.418351990407;10090.798184036425;10254.58981640726;10268.486575671335;10334.121599664113;10416.635417760343;6717.361850624183;6877.931220687929;6902.378785909092;7431.4054875608745;7609.6575654096805;8025.317847982987;8320.251177394064;8573.65705371803;8616.181462761027;8623.784405389915;7852.882206766789;7969.72105976288;8013.439059265796;8052.516848989079;8273;7888.408369372205;7995.480293780885;8003.559804210591;8006.359491240571;8107.513549187068;6948.513604047668;6984.196817366068;7125.144982602121;7127.037849890406;7146.071010768567;5024.57291130258;5054.7978080876355;5235.240890070842;5245.475452454724;5341.359036915595;5397.595180361567;5404.405515393382;5424.511793938565;5447.509724124848;5475.315585551806 16:20:41 INFO - PROCESS | 29281 | |4;prototype.html;334.6168636910737;339.8936461816786;345.89450217683594;347.6037317458097;349.7167294491462;5089.592832573395;5163.122269214233;5233.241180028894;5294.391145018324;5312.973435132823;5104.285400043997;5203.869903252411;5240.895182096366;5254.973725131378;5255.159174532076;4177.958220417788;4188.602082802129;4199.076203235283;4202.789860506978;4233.132207897389;4001.4197941298494;4170.270202714528;4213.768242746663;4224.767637779921;4225.704200705952;4061.1877624475223;4110.17796440713;4145.875623731278;4151.5640857709805;4156.251874662575;4104.40486129511;4106.979465102685;4116.691248156385;4117.341225403921;4159.126583417486;3653.1962968146963;3658.1160702238367;3659.688926441229;3661.359262129117;3669.4495825626104;5059.7856514436635;5130.563902068328;5143.819966301199;5146.974265128688;5148.922766158511;4717.952820471785;4719.787609557544;4731.864352555396;4734.2425211966265;4738.3129446230105;5374.48942350476;5489.176623506466;5495.258140151109;5497.175423686439;5512.310961129859;3974.1654252606986;3980.4029395590605;3995.40068989651;3999.960000400017;4000.399940008993;5686.147077938301;5710.857828434288;5715.457031581993;5715.942840571583;5716.57125715573;4061.735987160825;4070.4911886014247;4101.343784994387;4112.794360281974;4132.607402296777;4067.8372865085657;4102.487189101363;4132.752034877917;4135.531886180363;4149.589139692519;4672.415948006499;4696.295555666609;4698.271767875985;4707.929381059287;4712.50518695532;5478.808241711601;5504.862378440491;5514.393416724157;5555.250096219758;5573.498385145356;1319.2480286236753;1333.9466421343232;1419.6592817723879;1421.8435972043274;1434.4620767212296;885.9512726799959;914.3096961793804;921.2767977137914;927.7634203278121;933.7898972731081;4686.695364801277;4710.458297295786;4725.8582242532775;4727.64542659302;4744.7627618618935;4918.360613120271;4980.431164183271;4980.77586508608;4998.125328067574;5016.849494515098;4524.619990902806;4687;4693.741844198534;4714.481407045222;4717.905641887143;2654.389490417215;2669.666291713536;2670.4630600929195;2673.70589235184;2681.825681330707;5027.321311622957;5032.421271553818;5052.120291433847;5139.665921715151;5144.845654630367;5019;5037.330214986063;5049.343585333883;5077.796888124508;5579.2746942897165;5868.471837534642;5881.617694849821;5917.130191857032;5921.171036055042;5921.526277897758;5706.258186435737;5725.541956643375;5729.570282228933;5748.655080695172;5760.683162425939;5476.945230547803;5668.178114173339;5685.573581981285;5694.686792226467;5716.342620598684;5917.467427931507;5921.289445266596;5921.733521991435;5925.496332811759;5929.258842644669;4069.308217602955;4102.220578090153;4103.384492326056;4108.198901214235;4110.958890411058;5435;5526.364468086061;5675.801346953002;5698.57535616096;5712.943105525491;5365.517103460707;5477.315335583052;5561.165825126263;5561.972190139185;5566.721663916869;5532.944670553243;5560.249366335493;5562.91655625174;5566.220729097849;5576.414476480073;5311.336082989626;5455.18172274162;5461.74379892619;5507.311586051744;5517.972410137923;5487.176923461513;5526.336839579276;5534.817479948965;5537.335519737658;5538.556915446835;5406.972965135149;5414.864628384322;5438;5444.591655625764;5460.208269800777 16:20:41 INFO - PROCESS | 29281 | |5;yui.html;743.5241445474894;784.5684873319676;788.3776279254267;816.3265306122448;837.5226121110966;14826.629334266636;15624;15750.606234844134;15850.128242946634;16177.19114044296;14811.259437028159;14962.40150393983;14967.625809354771;14990.325435355408;14992.025518341328;655.7278729327323;669.3340126574042;786.2962648429652;789.2265579731866;803.9437239393245;796.6932730898617;797.8404319136167;798.1699033005667;799.1369321133162;799.1968072087544;844.025150950653;845.2899564365931;846.0693237438799;846.8179341441589;846.9957650211741;684.7329541478828;701.6561884676523;839.3243439031577;845.9450135741157;867.8264347130599;621.2731104607575;625.3746253746253;630.9936900631027;631.9810405687837;632.1402892066785;15226.46707365237;15243;15257.61855953599;15265.313060912285;15273.61815954599;14562.635934101627;15010.024348417319;15012;15200.923995379952;15349.386024559004;12042.277463352139;12094.87741678292;12192.329421881794;12263.141580089397;12377.69055773604;3826.8086595670384;3857.498525191735;3863.5750067492554;3888.591697871739;3930.724849260553;78245;79453;79490;80920;83597.58201208955;11073.280236784583;11113.055390291755;11375.886241137649;11386.202965792316;11482.368469734078;21188.78811211868;21850.890745546487;21892.89053554722;22992;23002.654960175612;8303.460275082221;8431.915680843236;8616.655333786704;8955.149260820212;9153.267738580898;4654.325122857167;4698.201305778026;4754.381930349102;4812.831550895702;4915.410150781929;3736.551613806361;3868.6518213360932;3869.1101046759404;3872.7482713623526;3876.476675648751;625.3402660193449;625.7465726380823;638.8626445314187;647.0262255305829;654.499308029358;570.2358839155469;607.4957785038407;646.5409559213011;669.9665016749143;704.6300692136567;9612.663556775618;9795.902040979641;9965.252606054575;10061.051520312405;10111.797764044824;549.598792881199;622.7602373086304;634.2927635686177;693.3724978894109;708.5713143548182;4128.488467895393;4155.335146376565;4653.139169253699;4802.303665968485;4831.855044348676;103335.4833225829;103883.4805825966;104459.477702611;107926.46036769765;108117.43177977466;84599;90723;91639;93274.07170390592;93745;85696;87337;88608.670869937;88693;89017.10982890218;8628.52543110135;8901.287896968357;8931.19619234272;9242.306826987868;9331.633110511357;50841.49158508368;51631.48368516267;51954.74022629863;52189.47810521998;53533;70393.64803175951;76186;76953.61523192348;78264;80513.91006573943;11563.479643416234;11609.30344179352;11697.2981621103;11795.410229488663;12134;12657.746845062862;12686.936565317113;12738.172018818746;13292.601221963356;14036.78944815849;11875.881241187477;11994.700132496408;12448.564300249447;12898.80651790205;13025.934870325587;11546.76906461883;12567.622971310875;12672;12883.549075782303;12906.741865162832;11787.233829801036;11911.166218364631;12868.09923305397;12915.483380665044;13042.347882605642;9692.51537423112;9804.950975245078;9812.215022798302;9978.351407158512;10226.130778883879;10415;10768.577070159152;11125.777484450104;11160;11163 16:20:41 INFO - PROCESS | 29281 | __end_tp_report 16:20:41 INFO - PROCESS | 29281 | __start_cc_report 16:20:41 INFO - PROCESS | 29281 | _x_x_mozilla_cycle_collect,1154 16:20:41 INFO - PROCESS | 29281 | __end_cc_report 16:20:41 INFO - PROCESS | 29281 | __startTimestamp1474154441839__endTimestamp 16:20:41 INFO - PROCESS | 29281 | 16:20:41 INFO - PROCESS | 29281 | ------- Summary: start ------- 16:20:41 INFO - PROCESS | 29281 | Number of tests: 6 16:20:41 INFO - PROCESS | 29281 | 16:20:41 INFO - PROCESS | 29281 | [#0] dojo.html Cycles:165 Average:5324.26 Median:5495.40 stddev:591.29 (10.8%) stddev-sans-first:564.78 16:20:41 INFO - PROCESS | 29281 | Values: 3019.3 3038.9 3052.6 3064.0 3090.4 5219.7 5261.8 5325.6 5330.7 5370.9 5310.2 5361.9 5363.5 5385.8 5425.1 4837.8 4905.7 4923.5 4924.1 5021.5 4920.6 4999.0 5008.9 5040.6 5121.6 4992.7 5009.1 5028.3 5057.2 5104.6 4770.6 4804.0 4810.9 4842.2 4903.2 4802.9 4807.6 4819.2 4938.5 4944.9 5303.4 5373.6 5380.7 5388.2 5438.2 5562.6 5617.1 5759.3 5791.7 5804.1 5689.9 5734.0 5739.1 5761.4 5778.4 5345.5 5351.4 5355.9 5356.0 5356.2 5717.7 5880.9 5887.9 5894.3 5899.4 5551.2 5552.2 5609.7 5619.1 5707.9 5693.6 5699.0 5703.2 5708.8 5709.5 5648.9 5689.9 5700.9 5700.9 5707.3 5537.7 5607.2 5639.8 5650.7 5688.3 4412.4 4417.0 4442.4 4454.2 4463.6 3946.8 3967.2 3969.0 4012.0 4017.4 5268.8 5442.2 5442.6 5446.2 5448.4 5624.9 5641.5 5664.8 5666.9 5715.1 5330.4 5352.4 5367.6 5371.1 5371.5 4941.6 4963.4 4978.1 4980.1 4985.7 5855.4 5877.7 5886.0 5899.7 5943.6 5795.5 5834.0 5834.4 5836.2 5840.9 5963.6 5976.7 5979.2 5981.4 5983.5 5673.6 5679.8 5680.3 5683.8 5684.3 5627.7 5678.8 5685.4 5724.1 5783.6 5687.0 5697.6 5709.4 5710.0 5721.9 5530.0 5532.3 5534.9 5539.4 5544.1 5393.0 5488.2 5491.8 5495.4 5496.3 5513.1 5539.4 5544.3 5561.7 5625.8 5440.9 5485.5 5486.2 5488.1 5500.3 16:20:41 INFO - PROCESS | 29281 | 16:20:41 INFO - PROCESS | 29281 | [#1] ext.html Cycles:180 Average:60011.12 Median:39723.00 stddev:117307.55 (295.3%) stddev-sans-first:117551.77 16:20:41 INFO - PROCESS | 29281 | Values: 586.1 618.2 632.1 635.5 643.0 31817.2 32648.2 35522.5 35537.5 35566.1 34416.3 42460.8 43113.4 43302.8 46589.0 8772.6 8911.2 8913.8 8914.8 8917.5 8759.6 8842.2 8846.9 8849.2 8855.3 8060.6 8231.1 10503.5 10748.4 10774.3 10749.6 10793.2 10801.7 10820.0 10820.1 7980.7 10476.4 10557.3 10568.9 10572.7 32179.0 39891.8 40339.4 40369.6 43972.0 3627.8 3731.7 3792.0 3920.3 4083.3 28553.7 29017.1 31209.5 33755.3 33797.7 4275.7 4286.8 4331.7 4425.1 4571.8 347061.0 353159.0 353608.0 354017.0 354588.0 62832.5 62903.4 63662.4 63743.0 63756.0 66607.3 67193.7 67257.3 67275.0 67315.6 14674.1 15176.3 15297.6 16126.5 17043.7 13086.3 13447.5 13450.8 13467.6 13474.4 17293.8 17615.2 19817.2 19990.5 20069.7 7271.8 7313.8 7416.2 7443.0 7740.7 847.8 910.0 915.4 926.9 941.8 30520.0 31413.4 31867.7 31987.4 31990.4 1059.9 1086.7 1102.5 1117.1 1172.3 14837.5 16723.0 16784.8 16800.7 16843.4 544063.0 558130.0 558513.0 558908.0 558991.0 57330.4 57784.0 57983.4 58050.7 58101.4 1255.4 1272.3 1332.1 1370.8 1375.2 30950.8 31869.5 32881.0 32909.3 33387.7 50298.5 50876.2 51068.0 51077.0 51134.5 391050.0 392231.0 393321.0 393867.0 393888.0 41729.0 42365.4 42853.6 45197.1 46153.8 46937.5 47358.5 47938.0 48239.0 48483.8 40027.8 40348.2 40606.0 40876.2 42044.0 39710.8 40186.2 40274.0 41522.0 41886.4 39291.6 39598.6 39735.2 39796.6 40670.2 34602.7 36364.5 36452.5 37107.8 37450.8 27757.3 27873.2 27912.6 27970.0 28104.4 16:20:41 INFO - PROCESS | 29281 | 16:20:41 INFO - PROCESS | 29281 | [#2] jquery.html Cycles:180 Average:27660.82 Median:20255.47 stddev:74416.98 (367.4%) stddev-sans-first:74597.55 16:20:41 INFO - PROCESS | 29281 | Values: 382.4 400.1 410.7 411.1 416.9 13379.8 15140.6 18651.1 19065.8 19478.6 17350.4 17597.8 18420.7 18896.2 18997.6 5662.9 5733.3 5943.5 5943.6 5947.5 5729.1 5818.5 5830.4 5830.8 5839.7 5658.9 5712.9 5784.0 5809.4 5809.9 5753.2 5819.5 5822.4 5824.0 5836.8 5046.3 5181.8 5225.8 5232.2 5233.3 22527.7 22691.2 22818.2 22935.4 22950.3 2353.8 2370.8 2373.3 2441.0 3075.5 14326.9 14631.5 14712.9 14808.9 14895.2 1867.6 1872.9 1930.8 1939.6 2001.9 79691.6 80161.6 81252.6 82585.6 84174.0 10322.5 10473.7 10660.9 10770.2 11113.6 14752.6 14764.4 14773.4 14790.5 14875.6 7917.8 8279.3 8385.7 8589.7 8603.6 7943.6 8012.2 8029.3 8194.6 8246.9 6421.5 6445.0 6496.9 6548.8 6668.1 1878.4 1884.6 1923.9 1924.7 1966.8 846.6 890.7 946.4 964.8 1006.0 18252.3 19464.0 19665.2 19909.2 20049.4 983.3 996.5 999.4 1039.5 1048.8 10351.7 10856.5 10861.5 10920.8 11150.7 359861.0 363477.0 493239.0 497860.0 505586.0 89665.0 94368.0 95501.0 96646.5 96774.0 996.8 1014.4 1049.8 1051.2 1055.7 16594.8 17074.5 17091.8 17325.6 17410.1 40110.8 40906.2 41296.0 41439.4 41534.8 54692.5 55190.7 57496.0 58119.0 58801.0 7341.2 7569.3 8101.4 8229.5 8655.5 12263.8 13062.9 13184.8 13259.9 13382.8 11043.8 11386.6 11523.0 11578.4 11883.8 6516.9 8089.4 11442.1 11460.9 11692.2 10037.6 10342.0 11146.9 11460.8 12227.8 7803.4 7875.6 7883.7 8001.2 8112.8 7756.5 9686.6 9991.6 10087.6 10355.7 16:20:41 INFO - PROCESS | 29281 | 16:20:41 INFO - PROCESS | 29281 | [#3] mootools.html Cycles:180 Average:5787.83 Median:5039.69 stddev:3281.93 (65.1%) stddev-sans-first:3273.88 16:20:41 INFO - PROCESS | 29281 | Values: 1310.9 1441.3 1498.9 1609.3 1765.8 7752.9 8673.3 8775.0 8846.4 9161.9 8961.9 9012.9 9027.9 9040.5 9040.8 2372.7 2396.7 2400.9 2401.4 2403.6 2371.7 2391.5 2405.5 2409.5 2411.1 2358.0 2365.5 2378.1 2388.9 2393.2 2334.4 2364.4 2366.1 2372.8 2373.5 2276.8 2300.0 2305.5 2310.0 2313.1 8915.3 8980.6 9005.3 9009.7 9014.1 5166.4 5361.8 5362.4 5400.3 5406.9 3340.5 3458.9 3504.4 3533.0 3535.3 1115.8 1126.9 1139.4 1156.4 1168.9 11851.5 11877.3 11909.1 11934.3 11971.7 4342.8 4467.1 4529.1 4670.9 4682.4 9374.3 9383.8 9390.5 9405.1 9406.9 5100.5 5103.4 5104.7 5114.5 5115.5 4404.6 4531.3 4768.3 4799.7 4843.8 3718.4 3831.4 3846.2 3884.8 4040.0 1195.7 1225.0 1227.5 1231.6 1256.7 728.2 855.7 878.1 897.5 931.9 5741.3 5756.5 5762.5 5863.2 6082.8 921.6 944.5 952.8 971.8 985.6 3929.0 4023.7 4053.3 4076.5 4175.2 10724.2 11100.2 11136.2 11143.4 11186.7 10992.9 11011.3 11028.1 11029.5 11037.9 8254.5 8379.5 8393.3 8394.7 8399.7 5452.5 5573.6 5712.2 5819.9 5896.9 10147.0 10198.4 10212.8 10542.6 10575.4 10090.8 10254.6 10268.5 10334.1 10416.6 6717.4 6877.9 6902.4 7431.4 7609.7 8025.3 8320.3 8573.7 8616.2 8623.8 7852.9 7969.7 8013.4 8052.5 8273.0 7888.4 7995.5 8003.6 8006.4 8107.5 6948.5 6984.2 7125.1 7127.0 7146.1 5024.6 5054.8 5235.2 5245.5 5341.4 5397.6 5404.4 5424.5 5447.5 5475.3 16:20:41 INFO - PROCESS | 29281 | 16:20:41 INFO - PROCESS | 29281 | [#4] prototype.html Cycles:180 Average:4568.67 Median:5055.95 stddev:1338.00 (26.5%) stddev-sans-first:1303.47 16:20:41 INFO - PROCESS | 29281 | Values: 334.6 339.9 345.9 347.6 349.7 5089.6 5163.1 5233.2 5294.4 5313.0 5104.3 5203.9 5240.9 5255.0 5255.2 4178.0 4188.6 4199.1 4202.8 4233.1 4001.4 4170.3 4213.8 4224.8 4225.7 4061.2 4110.2 4145.9 4151.6 4156.3 4104.4 4107.0 4116.7 4117.3 4159.1 3653.2 3658.1 3659.7 3661.4 3669.4 5059.8 5130.6 5143.8 5147.0 5148.9 4718.0 4719.8 4731.9 4734.2 4738.3 5374.5 5489.2 5495.3 5497.2 5512.3 3974.2 3980.4 3995.4 4000.0 4000.4 5686.1 5710.9 5715.5 5715.9 5716.6 4061.7 4070.5 4101.3 4112.8 4132.6 4067.8 4102.5 4132.8 4135.5 4149.6 4672.4 4696.3 4698.3 4707.9 4712.5 5478.8 5504.9 5514.4 5555.3 5573.5 1319.2 1333.9 1419.7 1421.8 1434.5 886.0 914.3 921.3 927.8 933.8 4686.7 4710.5 4725.9 4727.6 4744.8 4918.4 4980.4 4980.8 4998.1 5016.8 4524.6 4687.0 4693.7 4714.5 4717.9 2654.4 2669.7 2670.5 2673.7 2681.8 5027.3 5032.4 5052.1 5139.7 5144.8 5019.0 5037.3 5049.3 5077.8 5579.3 5868.5 5881.6 5917.1 5921.2 5921.5 5706.3 5725.5 5729.6 5748.7 5760.7 5476.9 5668.2 5685.6 5694.7 5716.3 5917.5 5921.3 5921.7 5925.5 5929.3 4069.3 4102.2 4103.4 4108.2 4111.0 5435.0 5526.4 5675.8 5698.6 5712.9 5365.5 5477.3 5561.2 5562.0 5566.7 5532.9 5560.2 5562.9 5566.2 5576.4 5311.3 5455.2 5461.7 5507.3 5518.0 5487.2 5526.3 5534.8 5537.3 5538.6 5407.0 5414.9 5438.0 5444.6 5460.2 16:20:41 INFO - PROCESS | 29281 | 16:20:41 INFO - PROCESS | 29281 | [#5] yui.html Cycles:180 Average:20384.56 Median:4822.34 stddev:29049.47 (602.4%) stddev-sans-first:29093.53 16:20:41 INFO - PROCESS | 29281 | Values: 743.5 784.6 788.4 816.3 837.5 14826.6 15624.0 15750.6 15850.1 16177.2 14811.3 14962.4 14967.6 14990.3 14992.0 655.7 669.3 786.3 789.2 803.9 796.7 797.8 798.2 799.1 799.2 844.0 845.3 846.1 846.8 847.0 684.7 701.7 839.3 845.9 867.8 621.3 625.4 631.0 632.0 632.1 15226.5 15243.0 15257.6 15265.3 15273.6 14562.6 15010.0 15012.0 15200.9 15349.4 12042.3 12094.9 12192.3 12263.1 12377.7 3826.8 3857.5 3863.6 3888.6 3930.7 78245.0 79453.0 79490.0 80920.0 83597.6 11073.3 11113.1 11375.9 11386.2 11482.4 21188.8 21850.9 21892.9 22992.0 23002.7 8303.5 8431.9 8616.7 8955.1 9153.3 4654.3 4698.2 4754.4 4812.8 4915.4 3736.6 3868.7 3869.1 3872.7 3876.5 625.3 625.7 638.9 647.0 654.5 570.2 607.5 646.5 670.0 704.6 9612.7 9795.9 9965.3 10061.1 10111.8 549.6 622.8 634.3 693.4 708.6 4128.5 4155.3 4653.1 4802.3 4831.9 103335.5 103883.5 104459.5 107926.5 108117.4 84599.0 90723.0 91639.0 93274.1 93745.0 85696.0 87337.0 88608.7 88693.0 89017.1 8628.5 8901.3 8931.2 9242.3 9331.6 50841.5 51631.5 51954.7 52189.5 53533.0 70393.6 76186.0 76953.6 78264.0 80513.9 11563.5 11609.3 11697.3 11795.4 12134.0 12657.7 12686.9 12738.2 13292.6 14036.8 11875.9 11994.7 12448.6 12898.8 13025.9 11546.8 12567.6 12672.0 12883.5 12906.7 11787.2 11911.2 12868.1 12915.5 13042.3 9692.5 9805.0 9812.2 9978.4 10226.1 10415.0 10768.6 11125.8 11160.0 11163.0 16:20:41 INFO - PROCESS | 29281 | -------- Summary: end -------- 16:20:41 INFO - PROCESS | 29281 | 16:20:42 INFO - TEST-INFO | 29281: exit 0 16:20:42 INFO - TEST-OK | dromaeo_css | took 1116795ms 16:20:42 INFO - TEST-START | kraken 16:20:42 INFO - operating with platform_type : linux_ 16:20:42 INFO - Initialising browser for kraken test... 16:20:42 INFO - TEST-INFO | started process 29587 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpoNRYCZ/profile http://localhost:50185/getInfo.html) 16:20:47 INFO - PROCESS | 29587 | __metrics Screen width/height:1600/1200 16:20:47 INFO - PROCESS | 29587 | colorDepth:24 16:20:47 INFO - PROCESS | 29587 | Browser inner width/height: 1024/697 16:20:47 INFO - PROCESS | 29587 | __metrics 16:20:47 INFO - PROCESS | 29587 | 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:20:47 INFO - PROCESS | 29587 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:20:48 INFO - PROCESS | 29587 | console.error: PushService: 16:20:48 INFO - PROCESS | 29587 | stateChangeProcessEnqueue: Error transitioning state 16:20:48 INFO - PROCESS | 29587 | UnknownError 16:20:49 INFO - TEST-INFO | 29587: exit 0 16:20:49 INFO - Browser initialized. 16:20:49 INFO - Running cycle 1/1 for kraken test... 16:20:49 INFO - TEST-INFO | started process 29668 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpoNRYCZ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:21:01 INFO - PROCESS | 29668 | RSS: Main: 172879872 16:21:01 INFO - PROCESS | 29668 | 16:21:03 INFO - PROCESS | 29668 | Cycle 1(1): loaded http://localhost:50185/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:50185/tests/kraken/driver.html?testName=audio-beat-detection) 16:21:04 INFO - PROCESS | 29668 | RSS: Main: 232189952 16:21:04 INFO - PROCESS | 29668 | 16:21:07 INFO - PROCESS | 29668 | Cycle 1(1): loaded http://localhost:50185/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:50185/tests/kraken/driver.html?testName=audio-dft) 16:21:07 INFO - PROCESS | 29668 | RSS: Main: 216686592 16:21:07 INFO - PROCESS | 29668 | 16:21:10 INFO - PROCESS | 29668 | Cycle 1(1): loaded http://localhost:50185/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:50185/tests/kraken/driver.html?testName=audio-fft) 16:21:11 INFO - PROCESS | 29668 | RSS: Main: 298004480 16:21:11 INFO - PROCESS | 29668 | 16:21:13 INFO - PROCESS | 29668 | Cycle 1(1): loaded http://localhost:50185/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:50185/tests/kraken/driver.html?testName=audio-oscillator) 16:21:14 INFO - PROCESS | 29668 | RSS: Main: 207785984 16:21:14 INFO - PROCESS | 29668 | 16:21:16 INFO - PROCESS | 29668 | Cycle 1(1): loaded http://localhost:50185/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:50185/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:21:16 INFO - PROCESS | 29668 | RSS: Main: 211976192 16:21:16 INFO - PROCESS | 29668 | 16:21:19 INFO - PROCESS | 29668 | Cycle 1(1): loaded http://localhost:50185/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:50185/tests/kraken/driver.html?testName=imaging-darkroom) 16:21:20 INFO - PROCESS | 29668 | RSS: Main: 248213504 16:21:20 INFO - PROCESS | 29668 | 16:21:23 INFO - PROCESS | 29668 | Cycle 1(1): loaded http://localhost:50185/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:50185/tests/kraken/driver.html?testName=imaging-desaturate) 16:21:24 INFO - PROCESS | 29668 | RSS: Main: 247758848 16:21:24 INFO - PROCESS | 29668 | 16:21:27 INFO - PROCESS | 29668 | Cycle 1(1): loaded http://localhost:50185/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:50185/tests/kraken/driver.html?testName=json-parse-financial) 16:21:27 INFO - PROCESS | 29668 | RSS: Main: 247238656 16:21:27 INFO - PROCESS | 29668 | 16:21:29 INFO - PROCESS | 29668 | Cycle 1(1): loaded http://localhost:50185/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:50185/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:21:29 INFO - PROCESS | 29668 | RSS: Main: 209657856 16:21:29 INFO - PROCESS | 29668 | 16:21:31 INFO - PROCESS | 29668 | Cycle 1(1): loaded http://localhost:50185/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:50185/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:21:32 INFO - PROCESS | 29668 | RSS: Main: 216584192 16:21:32 INFO - PROCESS | 29668 | 16:21:34 INFO - PROCESS | 29668 | Cycle 1(1): loaded http://localhost:50185/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:50185/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:21:34 INFO - PROCESS | 29668 | RSS: Main: 221392896 16:21:34 INFO - PROCESS | 29668 | 16:21:37 INFO - PROCESS | 29668 | Cycle 1(1): loaded http://localhost:50185/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:50185/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:21:38 INFO - PROCESS | 29668 | RSS: Main: 222064640 16:21:38 INFO - PROCESS | 29668 | 16:21:40 INFO - PROCESS | 29668 | Cycle 1(1): loaded http://localhost:50185/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:50185/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:21:41 INFO - PROCESS | 29668 | RSS: Main: 220860416 16:21:41 INFO - PROCESS | 29668 | 16:21:43 INFO - PROCESS | 29668 | Cycle 1(1): loaded http://localhost:50185/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:50185/tests/kraken/driver.html?testName=ai-astar) 16:21:43 INFO - PROCESS | 29668 | RSS: Main: 272183296 16:21:43 INFO - PROCESS | 29668 | 16:21:43 INFO - PROCESS | 29668 | __start_tp_report 16:21:43 INFO - PROCESS | 29668 | _x_x_mozilla_page_load 16:21:43 INFO - PROCESS | 29668 | _x_x_mozilla_page_load_details 16:21:43 INFO - PROCESS | 29668 | |i|pagename|runs| 16:21:43 INFO - PROCESS | 29668 | |0;ai-astar;109;111;113;110;113;112;111;109;112;111 16:21:43 INFO - PROCESS | 29668 | |1;audio-beat-detection;151;119;118;162;121;121;122;118;157;119 16:21:43 INFO - PROCESS | 29668 | |2;audio-dft;162;167;160;163;162;162;162;163;162;162 16:21:43 INFO - PROCESS | 29668 | |3;audio-fft;75;76;75;106;76;77;76;76;100;77 16:21:43 INFO - PROCESS | 29668 | |4;audio-oscillator;82;82;125;82;81;81;100;82;81;123 16:21:43 INFO - PROCESS | 29668 | |5;imaging-gaussian-blur;118;118;117;117;118;117;118;118;117;118 16:21:43 INFO - PROCESS | 29668 | |6;imaging-darkroom;148;147;148;147;146;148;147;147;146;146 16:21:43 INFO - PROCESS | 29668 | |7;imaging-desaturate;95;94;95;95;95;95;96;94;95;95 16:21:43 INFO - PROCESS | 29668 | |8;json-parse-financial;53;52;53;53;53;54;54;53;54;53 16:21:43 INFO - PROCESS | 29668 | |9;json-stringify-tinderbox;63;62;60;62;61;61;61;62;62;62 16:21:43 INFO - PROCESS | 29668 | |10;stanford-crypto-aes;78;80;78;79;82;85;78;85;81;78 16:21:43 INFO - PROCESS | 29668 | |11;stanford-crypto-ccm;124;124;132;132;122;125;124;123;162;133 16:21:43 INFO - PROCESS | 29668 | |12;stanford-crypto-pbkdf2;167;162;164;162;163;161;165;164;161;161 16:21:43 INFO - PROCESS | 29668 | |13;stanford-crypto-sha256-iterative;70;66;65;64;64;67;67;63;63;64 16:21:43 INFO - PROCESS | 29668 | __end_tp_report 16:21:43 INFO - PROCESS | 29668 | __start_cc_report 16:21:43 INFO - PROCESS | 29668 | _x_x_mozilla_cycle_collect,1601 16:21:43 INFO - PROCESS | 29668 | __end_cc_report 16:21:43 INFO - PROCESS | 29668 | __startTimestamp1474154503382__endTimestamp 16:21:43 INFO - PROCESS | 29668 | 16:21:43 INFO - PROCESS | 29668 | ------- Summary: start ------- 16:21:43 INFO - PROCESS | 29668 | Number of tests: 14 16:21:43 INFO - PROCESS | 29668 | 16:21:43 INFO - PROCESS | 29668 | [#0] ai-astar Cycles:10 Average:111.10 Median:111.50 stddev:1.45 (1.3%) stddev-sans-first:1.32 16:21:43 INFO - PROCESS | 29668 | Values: 109.0 111.0 113.0 110.0 113.0 112.0 111.0 109.0 112.0 111.0 16:21:43 INFO - PROCESS | 29668 | 16:21:43 INFO - PROCESS | 29668 | [#1] audio-beat-detection Cycles:10 Average:130.80 Median:121.50 stddev:18.08 (14.9%) stddev-sans-first:17.64 16:21:43 INFO - PROCESS | 29668 | Values: 151.0 119.0 118.0 162.0 121.0 121.0 122.0 118.0 157.0 119.0 16:21:43 INFO - PROCESS | 29668 | 16:21:43 INFO - PROCESS | 29668 | [#2] audio-dft Cycles:10 Average:162.50 Median:162.00 stddev:1.78 (1.1%) stddev-sans-first:1.88 16:21:43 INFO - PROCESS | 29668 | Values: 162.0 167.0 160.0 163.0 162.0 162.0 162.0 163.0 162.0 162.0 16:21:43 INFO - PROCESS | 29668 | 16:21:43 INFO - PROCESS | 29668 | [#3] audio-fft Cycles:10 Average:81.40 Median:76.00 stddev:11.49 (15.1%) stddev-sans-first:11.95 16:21:43 INFO - PROCESS | 29668 | Values: 75.0 76.0 75.0 106.0 76.0 77.0 76.0 76.0 100.0 77.0 16:21:43 INFO - PROCESS | 29668 | 16:21:43 INFO - PROCESS | 29668 | [#4] audio-oscillator Cycles:10 Average:91.90 Median:81.50 stddev:17.88 (21.9%) stddev-sans-first:18.60 16:21:43 INFO - PROCESS | 29668 | Values: 82.0 82.0 125.0 82.0 81.0 81.0 100.0 82.0 81.0 123.0 16:21:43 INFO - PROCESS | 29668 | 16:21:43 INFO - PROCESS | 29668 | [#5] imaging-gaussian-blur Cycles:10 Average:117.60 Median:118.00 stddev:0.52 (0.4%) stddev-sans-first:0.53 16:21:43 INFO - PROCESS | 29668 | Values: 118.0 118.0 117.0 117.0 118.0 117.0 118.0 118.0 117.0 118.0 16:21:43 INFO - PROCESS | 29668 | 16:21:43 INFO - PROCESS | 29668 | [#6] imaging-darkroom Cycles:10 Average:147.00 Median:147.00 stddev:0.82 (0.6%) stddev-sans-first:0.78 16:21:43 INFO - PROCESS | 29668 | Values: 148.0 147.0 148.0 147.0 146.0 148.0 147.0 147.0 146.0 146.0 16:21:43 INFO - PROCESS | 29668 | 16:21:43 INFO - PROCESS | 29668 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 16:21:43 INFO - PROCESS | 29668 | Values: 95.0 94.0 95.0 95.0 95.0 95.0 96.0 94.0 95.0 95.0 16:21:43 INFO - PROCESS | 29668 | 16:21:43 INFO - PROCESS | 29668 | [#8] json-parse-financial Cycles:10 Average:53.20 Median:53.00 stddev:0.63 (1.2%) stddev-sans-first:0.67 16:21:43 INFO - PROCESS | 29668 | Values: 53.0 52.0 53.0 53.0 53.0 54.0 54.0 53.0 54.0 53.0 16:21:43 INFO - PROCESS | 29668 | 16:21:43 INFO - PROCESS | 29668 | [#9] json-stringify-tinderbox Cycles:10 Average:61.60 Median:62.00 stddev:0.84 (1.4%) stddev-sans-first:0.73 16:21:43 INFO - PROCESS | 29668 | Values: 63.0 62.0 60.0 62.0 61.0 61.0 61.0 62.0 62.0 62.0 16:21:43 INFO - PROCESS | 29668 | 16:21:43 INFO - PROCESS | 29668 | [#10] stanford-crypto-aes Cycles:10 Average:80.40 Median:80.50 stddev:2.80 (3.5%) stddev-sans-first:2.83 16:21:43 INFO - PROCESS | 29668 | Values: 78.0 80.0 78.0 79.0 82.0 85.0 78.0 85.0 81.0 78.0 16:21:43 INFO - PROCESS | 29668 | 16:21:43 INFO - PROCESS | 29668 | [#11] stanford-crypto-ccm Cycles:10 Average:130.10 Median:128.50 stddev:11.96 (9.3%) stddev-sans-first:12.48 16:21:43 INFO - PROCESS | 29668 | Values: 124.0 124.0 132.0 132.0 122.0 125.0 124.0 123.0 162.0 133.0 16:21:43 INFO - PROCESS | 29668 | 16:21:43 INFO - PROCESS | 29668 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:163.00 Median:163.50 stddev:2.00 (1.2%) stddev-sans-first:1.51 16:21:43 INFO - PROCESS | 29668 | Values: 167.0 162.0 164.0 162.0 163.0 161.0 165.0 164.0 161.0 161.0 16:21:43 INFO - PROCESS | 29668 | 16:21:43 INFO - PROCESS | 29668 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.30 Median:65.50 stddev:2.21 (3.4%) stddev-sans-first:1.56 16:21:43 INFO - PROCESS | 29668 | Values: 70.0 66.0 65.0 64.0 64.0 67.0 67.0 63.0 63.0 64.0 16:21:43 INFO - PROCESS | 29668 | -------- Summary: end -------- 16:21:43 INFO - PROCESS | 29668 | 16:21:44 INFO - TEST-INFO | 29668: exit 0 16:21:44 INFO - TEST-OK | kraken | took 61775ms 16:21:44 INFO - SUITE-END | took 1178s 16:21:44 INFO - Completed test suite (00:19:38) 16:21:44 INFO - javascript benchmark 16:21:44 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3019.305559721264, 3038.863251153698, 3052.633683957925, 3063.9846800765995, 3090.35102628448, 5219.660722053073, 5261.76322065507, 5325.573954083674, 5330.706811125387, 5370.865728356793, 5310.15037593985, 5361.892762144755, 5363.54409875161, 5385.78456861725, 5425.104857698485, 4837.814735389835, 4905.730184839832, 4923.507649235084, 4924.064427758733, 5021.472745361739, 4920.6309526785635, 4998.975005124969, 5008.9499105008845, 5040.621953353495, 5121.641485096045, 4992.650514463989, 5009.148444764399, 5028.321176641161, 5057.216131499605, 5104.566111880495, 4770.642201834877, 4804.039192161546, 4810.879728006793, 4842.1526232909455, 4903.166461701519, 4802.903941921142, 4807.567318941312, 4819.180739274332, 4938.481459446778, 4944.9258261126115, 5303.443138470444, 5373.59698022646, 5380.650257733235, 5388.245645609618, 5438.211459338374, 5562.582806289544, 5617.073182924853, 5759.337676167211, 5791.65250084992, 5804.1293805929445, 5689.9146512802345, 5734.025215713297, 5739.081746920473, 5761.423857614247, 5778.42215778423, 5345.492178243061, 5351.384590772033, 5355.866103347408, 5355.973220133952, 5356.196570514454, 5717.714114294268, 5880.852978675567, 5887.852803679856, 5894.263217097863, 5899.410058994066, 5551.1673249012165, 5552.167174923713, 5609.663420194802, 5619.100943848965, 5707.914381284284, 5693.629914055573, 5699.0, 5703.230063941398, 5708.828735137952, 5709.543236541067, 5648.943510564842, 5689.9146512802345, 5700.942990570041, 5700.9429905701245, 5707.315122185364, 5537.723113844291, 5607.186957891083, 5639.802606908821, 5650.660960342392, 5688.288963879515, 4412.404325416028, 4417.028253784162, 4442.400275962704, 4454.153710794939, 4463.62059224963, 3946.8421263149726, 3967.206558688274, 3969.0, 4012.0, 4017.4375587417785, 5268.762905669254, 5442.183672449164, 5442.591805614595, 5446.2375267462585, 5448.37343705471, 5624.943750562524, 5641.548676105902, 5664.830055098354, 5666.858328541819, 5715.142728590745, 5330.440303768049, 5352.357717073976, 5367.597430192673, 5371.140617501181, 5371.462853714597, 4941.604671626261, 4963.379577552806, 4978.10394129053, 4980.12847751642, 4985.725785081855, 5855.355910849803, 5877.706114694248, 5886.0, 5899.705014749331, 5943.643381397216, 5795.478406943227, 5833.970830145822, 5834.445727655866, 5836.182934389104, 5840.883182336414, 5963.642181469126, 5976.701164941648, 5979.1928089707335, 5981.431763982415, 5983.461488466059, 5673.574481913791, 5679.801206957736, 5680.261565996394, 5683.77264909399, 5684.289463817023, 5627.718614069198, 5678.829635110953, 5685.431456854281, 5724.112762521816, 5783.595148339576, 5687.0, 5697.601167918206, 5709.400512946081, 5709.9714501427225, 5721.942780572141, 5529.972350138223, 5532.336119665666, 5534.861628459321, 5539.390667026704, 5544.057510223352, 5392.975334686397, 5488.17677348401, 5491.752871120889, 5495.395506494203, 5496.258005169411, 5513.062779327443, 5539.446055394428, 5544.251526043932, 5561.6663000220115, 5625.831225063254, 5440.918386224289, 5485.533729633026, 5486.231927530229, 5488.121900495901, 5500.339959204921], "value": 5284.651612437396, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [586.0593747036007, 618.2488276743757, 632.1339764522605, 635.4725577770452, 643.0482885329715, 31817.204569885736, 32648.183795405126, 35522.46716299257, 35537.46693799595, 35566.11084722883, 34416.31167376651, 42460.787696061474, 43113.35329970053, 43302.783486082524, 46589.0, 8772.649094036231, 8911.197992180703, 8913.77715557113, 8914.751934729144, 8917.509536975464, 8759.562021898912, 8842.248408885253, 8846.894138232721, 8849.159329863685, 8855.291576673882, 8060.637271322805, 8231.0534288557, 10503.527341269622, 10748.355098694103, 10774.299670521392, 10749.62376316829, 10793.190510711727, 10801.67594972152, 10820.0, 10820.13438924884, 7980.680772769082, 10476.423796691179, 10557.313774604627, 10568.947155264252, 10572.735681607945, 32179.0, 39891.8005409974, 40339.394909076385, 40369.59630403688, 43972.0, 3627.782333060025, 3731.71255916711, 3792.0140763401437, 3920.2551515212017, 4083.3262511685534, 28553.714462855314, 29017.12948611545, 31209.531857022164, 33755.324893501995, 33797.6620233797, 4275.700700950934, 4286.849960251377, 4331.718438301532, 4425.116652699751, 4571.839985600522, 347061.0, 353159.0, 353608.0, 354017.0, 354588.0, 62832.48670053238, 62903.37096629067, 63662.36337636657, 63743.0, 63756.0, 66607.33392666109, 67193.66403167952, 67257.27427257235, 67275.0, 67315.61475081982, 14674.119552826865, 15176.317065732068, 15297.6175595611, 16126.516204513884, 17043.659126817394, 13086.280254586089, 13447.46210151583, 13450.798238026437, 13467.595972120853, 13474.393652285671, 17293.827061729473, 17615.21272926327, 19817.20731170737, 19990.50023749389, 20069.698954515694, 7271.818204544823, 7313.780586582411, 7416.233498657017, 7443.0, 7740.651670674833, 847.7965288330879, 909.9954500227589, 915.4049867586123, 926.9119433653792, 941.8210539997378, 30520.0, 31413.37173256522, 31867.681323186935, 31987.36025279528, 31990.403376737017, 1059.9205059620406, 1086.6902932664152, 1102.5314241447513, 1117.0951529261324, 1172.3083380805194, 14837.480688176078, 16723.0, 16784.832151678573, 16800.74798878018, 16843.446535495954, 544063.0, 558130.0, 558513.0, 558908.0, 558991.0, 57330.426695733346, 57784.0, 57983.42016579865, 58050.709746451, 58101.4189858096, 1255.378587599126, 1272.338384040294, 1332.1208002718354, 1370.8149399831293, 1375.2229990055787, 30950.845245773628, 31869.521957170196, 32881.0, 32909.34181316408, 33387.66612333846, 50298.49701502938, 50876.236856447926, 51068.0, 51077.0, 51134.488655112975, 391050.0, 392231.0, 393321.0, 393867.0, 393888.0, 41729.0, 42365.36451953161, 42853.571464284956, 45197.1110591955, 46153.76923115363, 46937.53062469468, 47358.52641473679, 47938.0, 48239.0, 48483.75758121187, 40027.79986100051, 40348.193036138524, 40606.0, 40876.18247634971, 42044.0, 39710.80144599258, 40186.19627607373, 40274.0, 41522.0, 41886.371704425066, 39291.60708392879, 39598.60401396064, 39735.20529589334, 39796.602033980445, 40670.18659626732, 34602.65397345994, 36364.454533181495, 36452.45321320235, 37107.814460927526, 37450.81274593718, 27757.306067348476, 27873.16380508588, 27912.581311280752, 27970.0, 28104.43791124207], "value": 20381.332210879715, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [382.3652736457478, 400.14968192590743, 410.7104491333609, 411.1284077755159, 416.91453252083323, 13379.79930301045, 15140.621484462894, 18651.067446627683, 19065.809341906577, 19478.610427791435, 17350.3564251507, 17597.824021759778, 18420.72368914464, 18896.244150233975, 18997.62004759904, 5662.943370566303, 5733.254676891998, 5943.494802941756, 5943.613665111754, 5947.464728174464, 5729.140628905656, 5818.5345172386105, 5830.387809280007, 5830.82507524775, 5839.708014599253, 5658.858528536799, 5712.857178570527, 5784.045632470637, 5809.419058094178, 5809.854753631151, 5753.223314852483, 5819.505342045953, 5822.359540450547, 5824.0390335594575, 5836.766529338822, 5046.293518907356, 5181.766820493087, 5225.77194359329, 5232.189010703347, 5233.345831771028, 22527.662085068736, 22691.205807796814, 22818.201362952383, 22935.426614334607, 22950.311490655306, 2353.7646235376496, 2370.7510711375403, 2373.2524254859836, 2440.987795061031, 3075.492543730282, 14326.856731432761, 14631.487897923627, 14712.852871471256, 14808.851911480962, 14895.180765057918, 1867.5891303913118, 1872.9216683318157, 1930.8165724256173, 1939.5829896572304, 2001.8999050047585, 79691.6015419925, 80161.59919200424, 81252.59373703094, 82585.58707206546, 84174.0, 10322.535485903152, 10473.68578942633, 10660.893391066145, 10770.24608277413, 11113.611023614134, 14752.63118422048, 14764.37591864894, 14773.409063637335, 14790.482333118289, 14875.553733388017, 7917.802054948557, 8279.296259817984, 8385.664573417116, 8589.699360522476, 8603.612837422332, 7943.563104029218, 8012.198780121941, 8029.277365037175, 8194.631241594143, 8246.876296855551, 6421.454176395058, 6445.0, 6496.902546461807, 6548.755736410067, 6668.066470694107, 1878.4364690592768, 1884.5959759978903, 1923.9038048097539, 1924.6728056230195, 1966.8229859312444, 846.5597889097635, 890.6620122179279, 946.432140715579, 964.7539877331376, 1005.9688818960624, 18252.254413852228, 19464.0, 19665.213391464466, 19909.217248722696, 20049.398518044483, 983.306768728045, 996.5465713100667, 999.3554157568328, 1039.4698703661188, 1048.8374301983206, 10351.741206469747, 10856.480092787016, 10861.511231994578, 10920.836187457195, 11150.721231969266, 359861.0, 363477.0, 493239.0, 497860.0, 505586.0, 89665.0, 94368.0, 95501.0, 96646.51676741571, 96774.0, 996.7657600463886, 1014.3508154781093, 1049.7847941172042, 1051.2115913065202, 1055.6516349604765, 16594.75107873359, 17074.48776536706, 17091.829081709024, 17325.56686082858, 17410.129493525525, 40110.79944600258, 40906.1818763629, 41296.0, 41439.37840932449, 41534.79232603818, 54692.45307546874, 55190.72404637951, 57496.0, 58119.0, 58801.0, 7341.155767086853, 7569.280918312751, 8101.351891848756, 8229.547374894439, 8655.48067115975, 12263.754724905273, 13062.934685326512, 13184.802227966396, 13259.867401325862, 13382.799258010942, 11043.83434248503, 11386.601468948547, 11523.0, 11578.421078946187, 11883.76232475363, 6516.869662606816, 8089.3528517719615, 11442.141839362248, 11460.885391145981, 11692.181547291608, 10037.648682296085, 10342.017508336696, 11146.88853111491, 11460.770784584094, 12227.75544489123, 7803.414743894345, 7875.566843823644, 7883.724069657764, 8001.159878212937, 8112.83774324522, 7756.457048006585, 9686.564104615169, 9991.550380233053, 10087.596496140366, 10355.689329320132], "value": 9173.512478339862, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1310.862359452257, 1441.3153751967814, 1498.940042398304, 1609.3160406827096, 1765.8234176582343, 7752.8837067443965, 8673.262772664324, 8774.990876049249, 8846.424982376157, 9161.862572061407, 8961.91038089619, 9012.864807027883, 9027.864582031274, 9040.502772347518, 9040.819183616324, 2372.715274167098, 2396.712394512661, 2400.9039638414442, 2401.411654144737, 2403.615421532555, 2371.6561098640686, 2391.473875747333, 2405.4707964247837, 2409.518096380722, 2411.131992482705, 2358.0332063853825, 2365.5150694107665, 2378.108209421467, 2388.916387926423, 2393.234165067179, 2334.3580515358244, 2364.397078744926, 2366.0890557135453, 2372.750861159571, 2373.5134297469062, 2276.8292378071546, 2299.988500057506, 2305.5388922215457, 2309.976900230993, 2313.144136669426, 8915.331350148765, 8980.595873185723, 9005.32460065491, 9009.729708108767, 9014.098590140999, 5166.43169251382, 5361.839144825662, 5362.383325917531, 5400.270963419927, 5406.864828379283, 3340.498925161221, 3458.87893923714, 3504.351694936445, 3533.0460775590445, 3535.292941411728, 1115.8382034605017, 1126.8929451702156, 1139.4245905817509, 1156.3871148291419, 1168.9473973671206, 11851.525938962432, 11877.287362758176, 11909.10681698876, 11934.343611101385, 11971.700707482296, 4342.804573794187, 4467.084247729208, 4529.143051348931, 4670.906581868344, 4682.391289132459, 9374.250059995184, 9383.812323753624, 9390.483523406278, 9405.106514881076, 9406.858897116548, 5100.540951314325, 5103.362079740033, 5104.668196567211, 5114.4629813869005, 5115.462876397924, 4404.581564751344, 4531.252343363308, 4768.260919557474, 4799.736014519235, 4843.8304659337455, 3718.3678774608384, 3831.367824308958, 3846.1730727893637, 3884.7863367515056, 4040.0, 1195.7488927325185, 1224.9632511024683, 1227.4783217132683, 1231.6181983585118, 1256.6669832494397, 728.2280782370598, 855.6748435594559, 878.0999475537625, 897.5063714956748, 931.9161275485238, 5741.311042674823, 5756.510696590837, 5762.481376676035, 5863.208466857005, 6082.787102451482, 921.6267411698273, 944.4899754132691, 952.8237276103943, 971.8493633486821, 985.556499575194, 3929.0, 4023.657989070962, 4053.3116892758426, 4076.5461515071743, 4175.164967006611, 10724.195685323632, 11100.167487438475, 11136.220464567565, 11143.442827858737, 11186.664400068012, 10992.890071099346, 11011.28426652281, 11028.062614677681, 11029.503672334764, 11037.889621103686, 8254.463459875089, 8379.539125348165, 8393.328533717287, 8394.664213431395, 8399.664013439517, 5452.509274165344, 5573.5819813513335, 5712.228849105318, 5819.854503637273, 5896.852578685567, 10147.036031576989, 10198.43908585035, 10212.795744085226, 10542.631007914686, 10575.418351990407, 10090.798184036425, 10254.58981640726, 10268.486575671335, 10334.121599664113, 10416.635417760343, 6717.361850624183, 6877.931220687929, 6902.378785909092, 7431.4054875608745, 7609.6575654096805, 8025.317847982987, 8320.251177394064, 8573.65705371803, 8616.181462761027, 8623.784405389915, 7852.882206766789, 7969.72105976288, 8013.439059265796, 8052.516848989079, 8273.0, 7888.408369372205, 7995.480293780885, 8003.559804210591, 8006.359491240571, 8107.513549187068, 6948.513604047668, 6984.196817366068, 7125.144982602121, 7127.037849890406, 7146.071010768567, 5024.57291130258, 5054.7978080876355, 5235.240890070842, 5245.475452454724, 5341.359036915595, 5397.595180361567, 5404.405515393382, 5424.511793938565, 5447.509724124848, 5475.315585551806], "value": 4625.694498724232, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [334.6168636910737, 339.8936461816786, 345.89450217683594, 347.6037317458097, 349.7167294491462, 5089.592832573395, 5163.122269214233, 5233.241180028894, 5294.391145018324, 5312.973435132823, 5104.285400043997, 5203.869903252411, 5240.895182096366, 5254.973725131378, 5255.159174532076, 4177.958220417788, 4188.602082802129, 4199.076203235283, 4202.789860506978, 4233.132207897389, 4001.4197941298494, 4170.270202714528, 4213.768242746663, 4224.767637779921, 4225.704200705952, 4061.1877624475223, 4110.17796440713, 4145.875623731278, 4151.5640857709805, 4156.251874662575, 4104.40486129511, 4106.979465102685, 4116.691248156385, 4117.341225403921, 4159.126583417486, 3653.1962968146963, 3658.1160702238367, 3659.688926441229, 3661.359262129117, 3669.4495825626104, 5059.7856514436635, 5130.563902068328, 5143.819966301199, 5146.974265128688, 5148.922766158511, 4717.952820471785, 4719.787609557544, 4731.864352555396, 4734.2425211966265, 4738.3129446230105, 5374.48942350476, 5489.176623506466, 5495.258140151109, 5497.175423686439, 5512.310961129859, 3974.1654252606986, 3980.4029395590605, 3995.40068989651, 3999.960000400017, 4000.399940008993, 5686.147077938301, 5710.857828434288, 5715.457031581993, 5715.942840571583, 5716.57125715573, 4061.735987160825, 4070.4911886014247, 4101.343784994387, 4112.794360281974, 4132.607402296777, 4067.8372865085657, 4102.487189101363, 4132.752034877917, 4135.531886180363, 4149.589139692519, 4672.415948006499, 4696.295555666609, 4698.271767875985, 4707.929381059287, 4712.50518695532, 5478.808241711601, 5504.862378440491, 5514.393416724157, 5555.250096219758, 5573.498385145356, 1319.2480286236753, 1333.9466421343232, 1419.6592817723879, 1421.8435972043274, 1434.4620767212296, 885.9512726799959, 914.3096961793804, 921.2767977137914, 927.7634203278121, 933.7898972731081, 4686.695364801277, 4710.458297295786, 4725.8582242532775, 4727.64542659302, 4744.7627618618935, 4918.360613120271, 4980.431164183271, 4980.77586508608, 4998.125328067574, 5016.849494515098, 4524.619990902806, 4687.0, 4693.741844198534, 4714.481407045222, 4717.905641887143, 2654.389490417215, 2669.666291713536, 2670.4630600929195, 2673.70589235184, 2681.825681330707, 5027.321311622957, 5032.421271553818, 5052.120291433847, 5139.665921715151, 5144.845654630367, 5019.0, 5037.330214986063, 5049.343585333883, 5077.796888124508, 5579.2746942897165, 5868.471837534642, 5881.617694849821, 5917.130191857032, 5921.171036055042, 5921.526277897758, 5706.258186435737, 5725.541956643375, 5729.570282228933, 5748.655080695172, 5760.683162425939, 5476.945230547803, 5668.178114173339, 5685.573581981285, 5694.686792226467, 5716.342620598684, 5917.467427931507, 5921.289445266596, 5921.733521991435, 5925.496332811759, 5929.258842644669, 4069.308217602955, 4102.220578090153, 4103.384492326056, 4108.198901214235, 4110.958890411058, 5435.0, 5526.364468086061, 5675.801346953002, 5698.57535616096, 5712.943105525491, 5365.517103460707, 5477.315335583052, 5561.165825126263, 5561.972190139185, 5566.721663916869, 5532.944670553243, 5560.249366335493, 5562.91655625174, 5566.220729097849, 5576.414476480073, 5311.336082989626, 5455.18172274162, 5461.74379892619, 5507.311586051744, 5517.972410137923, 5487.176923461513, 5526.336839579276, 5534.817479948965, 5537.335519737658, 5538.556915446835, 5406.972965135149, 5414.864628384322, 5438.0, 5444.591655625764, 5460.208269800777], "value": 4145.828565541447, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [743.5241445474894, 784.5684873319676, 788.3776279254267, 816.3265306122448, 837.5226121110966, 14826.629334266636, 15624.0, 15750.606234844134, 15850.128242946634, 16177.19114044296, 14811.259437028159, 14962.40150393983, 14967.625809354771, 14990.325435355408, 14992.025518341328, 655.7278729327323, 669.3340126574042, 786.2962648429652, 789.2265579731866, 803.9437239393245, 796.6932730898617, 797.8404319136167, 798.1699033005667, 799.1369321133162, 799.1968072087544, 844.025150950653, 845.2899564365931, 846.0693237438799, 846.8179341441589, 846.9957650211741, 684.7329541478828, 701.6561884676523, 839.3243439031577, 845.9450135741157, 867.8264347130599, 621.2731104607575, 625.3746253746253, 630.9936900631027, 631.9810405687837, 632.1402892066785, 15226.46707365237, 15243.0, 15257.61855953599, 15265.313060912285, 15273.61815954599, 14562.635934101627, 15010.024348417319, 15012.0, 15200.923995379952, 15349.386024559004, 12042.277463352139, 12094.87741678292, 12192.329421881794, 12263.141580089397, 12377.69055773604, 3826.8086595670384, 3857.498525191735, 3863.5750067492554, 3888.591697871739, 3930.724849260553, 78245.0, 79453.0, 79490.0, 80920.0, 83597.58201208955, 11073.280236784583, 11113.055390291755, 11375.886241137649, 11386.202965792316, 11482.368469734078, 21188.78811211868, 21850.890745546487, 21892.89053554722, 22992.0, 23002.654960175612, 8303.460275082221, 8431.915680843236, 8616.655333786704, 8955.149260820212, 9153.267738580898, 4654.325122857167, 4698.201305778026, 4754.381930349102, 4812.831550895702, 4915.410150781929, 3736.551613806361, 3868.6518213360932, 3869.1101046759404, 3872.7482713623526, 3876.476675648751, 625.3402660193449, 625.7465726380823, 638.8626445314187, 647.0262255305829, 654.499308029358, 570.2358839155469, 607.4957785038407, 646.5409559213011, 669.9665016749143, 704.6300692136567, 9612.663556775618, 9795.902040979641, 9965.252606054575, 10061.051520312405, 10111.797764044824, 549.598792881199, 622.7602373086304, 634.2927635686177, 693.3724978894109, 708.5713143548182, 4128.488467895393, 4155.335146376565, 4653.139169253699, 4802.303665968485, 4831.855044348676, 103335.4833225829, 103883.4805825966, 104459.477702611, 107926.46036769765, 108117.43177977466, 84599.0, 90723.0, 91639.0, 93274.07170390592, 93745.0, 85696.0, 87337.0, 88608.670869937, 88693.0, 89017.10982890218, 8628.52543110135, 8901.287896968357, 8931.19619234272, 9242.306826987868, 9331.633110511357, 50841.49158508368, 51631.48368516267, 51954.74022629863, 52189.47810521998, 53533.0, 70393.64803175951, 76186.0, 76953.61523192348, 78264.0, 80513.91006573943, 11563.479643416234, 11609.30344179352, 11697.2981621103, 11795.410229488663, 12134.0, 12657.746845062862, 12686.936565317113, 12738.172018818746, 13292.601221963356, 14036.78944815849, 11875.881241187477, 11994.700132496408, 12448.564300249447, 12898.80651790205, 13025.934870325587, 11546.76906461883, 12567.622971310875, 12672.0, 12883.549075782303, 12906.741865162832, 11787.233829801036, 11911.166218364631, 12868.09923305397, 12915.483380665044, 13042.347882605642, 9692.51537423112, 9804.950975245078, 9812.215022798302, 9978.351407158512, 10226.130778883879, 10415.0, 10768.577070159152, 11125.777484450104, 11160.0, 11163.0], "value": 7387.955161687847, "unit": "score"}], "name": "dromaeo_css", "value": 7205.939734639102, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 111.0, 113.0, 110.0, 113.0, 112.0, 111.0, 109.0, 112.0, 111.0], "value": 111.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 119.0, 118.0, 162.0, 121.0, 121.0, 122.0, 118.0, 157.0, 119.0], "value": 130.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 167.0, 160.0, 163.0, 162.0, 162.0, 162.0, 163.0, 162.0, 162.0], "value": 162.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 75.0, 106.0, 76.0, 77.0, 76.0, 76.0, 100.0, 77.0], "value": 81.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 125.0, 82.0, 81.0, 81.0, 100.0, 82.0, 81.0, 123.0], "value": 91.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 118.0, 117.0, 117.0, 118.0, 117.0, 118.0, 118.0, 117.0, 118.0], "value": 117.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 147.0, 148.0, 147.0, 146.0, 148.0, 147.0, 147.0, 146.0, 146.0], "value": 147.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 95.0, 95.0, 95.0, 95.0, 96.0, 94.0, 95.0, 95.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 52.0, 53.0, 53.0, 53.0, 54.0, 54.0, 53.0, 54.0, 53.0], "value": 53.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 62.0, 60.0, 62.0, 61.0, 61.0, 61.0, 62.0, 62.0, 62.0], "value": 61.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 80.0, 78.0, 79.0, 82.0, 85.0, 78.0, 85.0, 81.0, 78.0], "value": 80.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [124.0, 124.0, 132.0, 132.0, 122.0, 125.0, 124.0, 123.0, 162.0, 133.0], "value": 130.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 162.0, 164.0, 162.0, 163.0, 161.0, 165.0, 164.0, 161.0, 161.0], "value": 163.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 66.0, 65.0, 64.0, 64.0, 67.0, 67.0, 63.0, 63.0, 64.0], "value": 65.3, "unit": "score"}], "name": "kraken", "value": 1490.8, "alertThreshold": 2.0}]} 16:21:44 INFO - Return code: 0 16:21:44 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:21:44 INFO - # TBPL SUCCESS # 16:21:44 INFO - Running post-action listener: _resource_record_post_action 16:21:44 INFO - [mozharness: 2016-09-17 23:21:44.710086Z] Finished run-tests step (success) 16:21:44 INFO - Running post-run listener: _resource_record_post_run 16:21:44 INFO - Total resource usage - Wall time: 1196s; CPU: 13.0%; Read bytes: 0; Write bytes: 393412608; Read time: 0; Write time: 243864 16:21:44 INFO - TinderboxPrint: CPU usage
13.1% 16:21:44 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 16:21:44 INFO - TinderboxPrint: I/O write bytes / time
393,412,608 / 243,864 16:21:44 INFO - TinderboxPrint: CPU idle
8,294.6 (86.9%) 16:21:44 INFO - TinderboxPrint: CPU user
1,193.3 (12.5%) 16:21:44 INFO - TinderboxPrint: Swap in / out
0 / 0 16:21:44 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 103129088; Read time: 0; Write time: 86080 16:21:44 INFO - run-tests - Wall time: 1179s; CPU: 13.0%; Read bytes: 0; Write bytes: 289292288; Read time: 0; Write time: 157784 16:21:45 INFO - Running post-run listener: _upload_blobber_files 16:21:45 INFO - Blob upload gear active. 16:21:45 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:21:45 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:21:45 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:21:45 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:21:45 INFO - (blobuploader) - INFO - Open directory for files ... 16:21:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:21:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:21:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:21:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:21:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:21:46 INFO - (blobuploader) - INFO - Done attempting. 16:21:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:21:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:21:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:21:50 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:21:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:21:50 INFO - (blobuploader) - INFO - Done attempting. 16:21:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:21:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:21:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:21:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:21:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:21:51 INFO - (blobuploader) - INFO - Done attempting. 16:21:51 INFO - (blobuploader) - INFO - Iteration through files over. 16:21:51 INFO - Return code: 0 16:21:51 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:21:51 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:21:51 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a639dca59028c444e7224c980927f5eb6786d7134465bf95b4c13209190acbff2ff5ab2711c24a78f14381d968162df8ef5e4911628c2fd785ea5a582c6fa829", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/54913f4f4a28a5e72caadc2d272ac30dcea3a0c21a3daf668f0e2d989d53608297909a956c14f084cbb48d6cbdca40e994b0f31fcf7f2ecb9248ed6365cdcf75", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1e3c12d8f7c86579692940895b9748c6c4e76fa3a56c34e1232fbd7de4ec6eb2fa9810164a69a4c2f4399523d6a6b58773b29f28f4ca5d85eb9c98f8f7c7498f"} 16:21:51 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:21:51 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:21:51 INFO - Contents: 16:21:51 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a639dca59028c444e7224c980927f5eb6786d7134465bf95b4c13209190acbff2ff5ab2711c24a78f14381d968162df8ef5e4911628c2fd785ea5a582c6fa829", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/54913f4f4a28a5e72caadc2d272ac30dcea3a0c21a3daf668f0e2d989d53608297909a956c14f084cbb48d6cbdca40e994b0f31fcf7f2ecb9248ed6365cdcf75", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1e3c12d8f7c86579692940895b9748c6c4e76fa3a56c34e1232fbd7de4ec6eb2fa9810164a69a4c2f4399523d6a6b58773b29f28f4ca5d85eb9c98f8f7c7498f"} 16:21:51 INFO - Running post-run listener: copy_logs_to_upload_dir 16:21:51 INFO - Copying logs to upload dir... 16:21:51 INFO - mkdir: /builds/slave/test/build/upload/logs 16:21:51 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1239.845085 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2016-09-17 16:21:50.377918) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-17 16:21:50.385151) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=516f8d7ca085b4d087ab0a71000001a4-1474152371.346314-1419243930 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a639dca59028c444e7224c980927f5eb6786d7134465bf95b4c13209190acbff2ff5ab2711c24a78f14381d968162df8ef5e4911628c2fd785ea5a582c6fa829", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/54913f4f4a28a5e72caadc2d272ac30dcea3a0c21a3daf668f0e2d989d53608297909a956c14f084cbb48d6cbdca40e994b0f31fcf7f2ecb9248ed6365cdcf75", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1e3c12d8f7c86579692940895b9748c6c4e76fa3a56c34e1232fbd7de4ec6eb2fa9810164a69a4c2f4399523d6a6b58773b29f28f4ca5d85eb9c98f8f7c7498f"} build_url:https://queue.taskcluster.net/v1/task/IQzmNtvaSAKlXq9f9rHYWw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011220 build_url: 'https://queue.taskcluster.net/v1/task/IQzmNtvaSAKlXq9f9rHYWw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a639dca59028c444e7224c980927f5eb6786d7134465bf95b4c13209190acbff2ff5ab2711c24a78f14381d968162df8ef5e4911628c2fd785ea5a582c6fa829", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/54913f4f4a28a5e72caadc2d272ac30dcea3a0c21a3daf668f0e2d989d53608297909a956c14f084cbb48d6cbdca40e994b0f31fcf7f2ecb9248ed6365cdcf75", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1e3c12d8f7c86579692940895b9748c6c4e76fa3a56c34e1232fbd7de4ec6eb2fa9810164a69a4c2f4399523d6a6b58773b29f28f4ca5d85eb9c98f8f7c7498f"}' ========= master_lag: 0.32 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-17 16:21:50.720974) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 16:21:50.721352) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=516f8d7ca085b4d087ab0a71000001a4-1474152371.346314-1419243930 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004507 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 16:21:50.803862) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-17 16:21:50.804225) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-17 16:21:50.804567) ========= ========= Total master_lag: 0.51 =========