builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-043 starttime: 1482902931.98 results: success (0) buildid: 20161227203236 builduid: 267bb6f0ef5748d3aff1686163db8dc6 revision: a0866731bbe884fffce6f007b851f7ac453013fa ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-27 21:28:51.982502) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-27 21:28:51.982903) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-27 21:28:51.983173) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-27 21:28:51.998261) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-27 21:28:51.998522) ========= 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=d2a8c569965ab8367867b893000001a1-1482899256.628074-202179440 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-12-27 21:28:52-- 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: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 38.3M=0s 2016-12-27 21:28:52 (38.3 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.089983 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-27 21:28:52.103336) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-27 21:28:52.103790) ========= 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=d2a8c569965ab8367867b893000001a1-1482899256.628074-202179440 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018459 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-27 21:28:52.153791) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-27 21:28:52.154137) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev a0866731bbe884fffce6f007b851f7ac453013fa --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 a0866731bbe884fffce6f007b851f7ac453013fa --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=d2a8c569965ab8367867b893000001a1-1482899256.628074-202179440 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-12-27 21:28:52,929 truncating revision to first 12 chars 2016-12-27 21:28:52,929 Setting DEBUG logging. 2016-12-27 21:28:52,929 attempt 1/10 2016-12-27 21:28:52,929 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/a0866731bbe8?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-27 21:28:53,539 unpacking tar archive at: mozilla-central-a0866731bbe8/testing/mozharness/ program finished with exit code 0 elapsedTime=0.926272 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-27 21:28:53.093205) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-27 21:28:53.093474) ========= script_repo_revision: a0866731bbe884fffce6f007b851f7ac453013fa ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-27 21:28:53.093869) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-27 21:28:53.094147) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-27 21:28:53.103889) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2016-12-27 21:28:53.104209) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 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-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] 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=d2a8c569965ab8367867b893000001a1-1482899256.628074-202179440 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 21:28:53 INFO - MultiFileLogger online at 20161227 21:28:53 in /builds/slave/test 21:28:53 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 21:28:53 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 21:28:53 INFO - {'append_to_log': False, 21:28:53 INFO - 'base_work_dir': '/builds/slave/test', 21:28:53 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 21:28:53 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 21:28:53 INFO - 'branch': 'Firefox-Non-PGO', 21:28:53 INFO - 'buildbot_json_path': 'buildprops.json', 21:28:53 INFO - 'config_files': ('talos/linux_config.py',), 21:28:53 INFO - 'default_actions': ('clobber', 21:28:53 INFO - 'read-buildbot-config', 21:28:53 INFO - 'download-and-extract', 21:28:53 INFO - 'populate-webroot', 21:28:53 INFO - 'create-virtualenv', 21:28:53 INFO - 'install', 21:28:53 INFO - 'run-tests'), 21:28:53 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:28:53 INFO - 'download_minidump_stackwalk': True, 21:28:53 INFO - 'download_symbols': 'ondemand', 21:28:53 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:28:53 INFO - 'tooltool.py': '/tools/tooltool.py', 21:28:53 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:28:53 INFO - '/tools/misc-python/virtualenv.py')}, 21:28:53 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:28:53 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:28:53 INFO - 'installer_path': 'installer.exe', 21:28:53 INFO - 'log_level': 'info', 21:28:53 INFO - 'log_name': 'talos', 21:28:53 INFO - 'log_to_console': True, 21:28:53 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 21:28:53 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 21:28:53 INFO - 'opt_config_files': (), 21:28:53 INFO - 'pip_index': False, 21:28:53 INFO - 'sps_profile': False, 21:28:53 INFO - 'sps_profile_interval': 0, 21:28:53 INFO - 'suite': 'dromaeojs', 21:28:53 INFO - 'system_bits': '32', 21:28:53 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:28:53 INFO - 'title': 'talos-linux64-ix-043', 21:28:53 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:28:53 INFO - 'use_talos_json': True, 21:28:53 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 21:28:53 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:28:53 INFO - 'work_dir': 'build'} 21:28:53 INFO - [mozharness: 2016-12-28 05:28:53.972430Z] Running clobber step. 21:28:53 INFO - Running pre-action listener: _resource_record_pre_action 21:28:53 INFO - Running main action method: clobber 21:28:53 INFO - rmtree: /builds/slave/test/build 21:28:53 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 21:28:54 INFO - Running post-action listener: _resource_record_post_action 21:28:54 INFO - [mozharness: 2016-12-28 05:28:54.434869Z] Finished clobber step (success) 21:28:54 INFO - [mozharness: 2016-12-28 05:28:54.434970Z] Running read-buildbot-config step. 21:28:54 INFO - Running pre-action listener: _resource_record_pre_action 21:28:54 INFO - Running main action method: read_buildbot_config 21:28:54 INFO - Using buildbot properties: 21:28:54 INFO - { 21:28:54 INFO - "project": "", 21:28:54 INFO - "product": "firefox", 21:28:54 INFO - "script_repo_revision": "production", 21:28:54 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 21:28:54 INFO - "repository": "", 21:28:54 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 21:28:54 INFO - "buildid": "20161227203236", 21:28:54 INFO - "pgo_build": "False", 21:28:54 INFO - "basedir": "/builds/slave/test", 21:28:54 INFO - "buildnumber": 360, 21:28:54 INFO - "slavename": "talos-linux64-ix-043", 21:28:54 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 21:28:54 INFO - "platform": "ubuntu64_hw", 21:28:54 INFO - "scheduler": "tests-mozilla-central-linux64-talos", 21:28:54 INFO - "branch": "mozilla-central", 21:28:54 INFO - "repo_path": "mozilla-central", 21:28:54 INFO - "revision": "a0866731bbe884fffce6f007b851f7ac453013fa", 21:28:54 INFO - "stage_platform": "linux64", 21:28:54 INFO - "builduid": "267bb6f0ef5748d3aff1686163db8dc6", 21:28:54 INFO - "slavebuilddir": "test" 21:28:54 INFO - } 21:28:54 INFO - Found installer url https://queue.taskcluster.net/v1/task/U2oKbSY9TNaJgz2uM0_NQA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2. 21:28:54 INFO - Running post-action listener: _resource_record_post_action 21:28:54 INFO - [mozharness: 2016-12-28 05:28:54.437410Z] Finished read-buildbot-config step (success) 21:28:54 INFO - [mozharness: 2016-12-28 05:28:54.437499Z] Running download-and-extract step. 21:28:54 INFO - Running pre-action listener: _resource_record_pre_action 21:28:54 INFO - Running main action method: download_and_extract 21:28:54 INFO - mkdir: /builds/slave/test/build/tests 21:28:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:28:54 INFO - trying https://queue.taskcluster.net/v1/task/U2oKbSY9TNaJgz2uM0_NQA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 21:28:54 INFO - Downloading https://queue.taskcluster.net/v1/task/U2oKbSY9TNaJgz2uM0_NQA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 21:28:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/U2oKbSY9TNaJgz2uM0_NQA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 21:28:54 INFO - Downloaded 1361 bytes. 21:28:54 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 21:28:55 INFO - Using the following test package requirements: 21:28:55 INFO - {u'common': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 21:28:55 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 21:28:55 INFO - u'firefox-53.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 21:28:55 INFO - u'gtest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 21:28:55 INFO - u'firefox-53.0a1.en-US.linux-x86_64.gtest.tests.zip'], 21:28:55 INFO - u'jittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 21:28:55 INFO - u'jsshell-linux-x86_64.zip'], 21:28:55 INFO - u'mochitest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 21:28:55 INFO - u'firefox-53.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 21:28:55 INFO - u'mozbase': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 21:28:55 INFO - u'reftest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 21:28:55 INFO - u'firefox-53.0a1.en-US.linux-x86_64.reftest.tests.zip'], 21:28:55 INFO - u'talos': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 21:28:55 INFO - u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'], 21:28:55 INFO - u'web-platform': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 21:28:55 INFO - u'firefox-53.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 21:28:55 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 21:28:55 INFO - u'firefox-53.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 21:28:55 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 21:28:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/U2oKbSY9TNaJgz2uM0_NQA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 21:28:55 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/U2oKbSY9TNaJgz2uM0_NQA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 21:28:55 INFO - Fetch https://queue.taskcluster.net/v1/task/U2oKbSY9TNaJgz2uM0_NQA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 21:28:55 INFO - Expected file size: 20241338 21:28:55 INFO - Obtained file size: 20241338 21:28:59 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 21:28:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/U2oKbSY9TNaJgz2uM0_NQA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 21:28:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/U2oKbSY9TNaJgz2uM0_NQA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 21:28:59 INFO - Fetch https://queue.taskcluster.net/v1/task/U2oKbSY9TNaJgz2uM0_NQA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 21:29:00 INFO - Expected file size: 20241338 21:29:00 INFO - Obtained file size: 20241338 21:29:05 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/U2oKbSY9TNaJgz2uM0_NQA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip 21:29:05 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/U2oKbSY9TNaJgz2uM0_NQA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 21:29:05 INFO - Fetch https://queue.taskcluster.net/v1/task/U2oKbSY9TNaJgz2uM0_NQA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip into memory 21:29:05 INFO - Expected file size: 14409776 21:29:05 INFO - Obtained file size: 14409776 21:29:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:29:06 INFO - trying https://queue.taskcluster.net/v1/task/U2oKbSY9TNaJgz2uM0_NQA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 21:29:06 INFO - Downloading https://queue.taskcluster.net/v1/task/U2oKbSY9TNaJgz2uM0_NQA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 21:29:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/U2oKbSY9TNaJgz2uM0_NQA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 21:29:07 INFO - Downloaded 55899975 bytes. 21:29:07 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/U2oKbSY9TNaJgz2uM0_NQA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 21:29:07 INFO - mkdir: /builds/slave/test/properties 21:29:07 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 21:29:07 INFO - Writing to file /builds/slave/test/properties/build_url 21:29:07 INFO - Contents: 21:29:07 INFO - build_url:https://queue.taskcluster.net/v1/task/U2oKbSY9TNaJgz2uM0_NQA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 21:29:08 INFO - Running post-action listener: _resource_record_post_action 21:29:08 INFO - Running post-action listener: set_extra_try_arguments 21:29:08 INFO - [mozharness: 2016-12-28 05:29:08.601184Z] Finished download-and-extract step (success) 21:29:08 INFO - [mozharness: 2016-12-28 05:29:08.601410Z] Running populate-webroot step. 21:29:08 INFO - Running pre-action listener: _resource_record_pre_action 21:29:08 INFO - Running main action method: populate_webroot 21:29:08 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 21:29:08 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 21:29:08 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 21:29:08 INFO - '--tppagecycles', 21:29:08 INFO - '7'], 21:29:08 INFO - 'tests': ['tsvgm']}}, 21:29:08 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 21:29:08 INFO - 'tests': ['tresize', 'tcanvasmark']}, 21:29:08 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 21:29:08 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 21:29:08 INFO - 'tests': ['dromaeo_css', 'kraken']}, 21:29:08 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 21:29:08 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 21:29:08 INFO - 'talos_options': ['--disable-e10s'], 21:29:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:29:08 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 21:29:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:29:08 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 21:29:08 INFO - 'talos_options': ['--disable-e10s'], 21:29:08 INFO - 'tests': ['damp', 'tps']}, 21:29:08 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 21:29:08 INFO - 'tests': ['damp', 'tps']}, 21:29:08 INFO - 'g3': {'talos_options': ['--disable-e10s'], 21:29:08 INFO - 'tests': ['dromaeo_dom']}, 21:29:08 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:29:08 INFO - 'g4': {'talos_options': ['--disable-e10s'], 21:29:08 INFO - 'tests': ['basic_compositor_video']}, 21:29:08 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 21:29:08 INFO - 'other': {'talos_options': ['--disable-e10s'], 21:29:08 INFO - 'tests': ['a11yr', 21:29:08 INFO - 'ts_paint', 21:29:08 INFO - 'tpaint', 21:29:08 INFO - 'sessionrestore', 21:29:08 INFO - 'sessionrestore_no_auto_restore', 21:29:08 INFO - 'tabpaint']}, 21:29:08 INFO - 'other-e10s': {'tests': ['a11yr', 21:29:08 INFO - 'ts_paint', 21:29:08 INFO - 'tpaint', 21:29:08 INFO - 'sessionrestore', 21:29:08 INFO - 'sessionrestore_no_auto_restore', 21:29:08 INFO - 'tabpaint']}, 21:29:08 INFO - 'other-e10s_l64': {'tests': ['a11yr', 21:29:08 INFO - 'ts_paint', 21:29:08 INFO - 'tpaint', 21:29:08 INFO - 'sessionrestore', 21:29:08 INFO - 'sessionrestore_no_auto_restore', 21:29:08 INFO - 'tabpaint']}, 21:29:08 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 21:29:08 INFO - 'ts_paint', 21:29:08 INFO - 'tpaint', 21:29:08 INFO - 'sessionrestore', 21:29:08 INFO - 'sessionrestore_no_auto_restore', 21:29:08 INFO - 'tabpaint']}, 21:29:08 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 21:29:08 INFO - 'tests': ['a11yr', 21:29:08 INFO - 'ts_paint', 21:29:08 INFO - 'tpaint', 21:29:08 INFO - 'sessionrestore', 21:29:08 INFO - 'sessionrestore_no_auto_restore', 21:29:08 INFO - 'tabpaint']}, 21:29:08 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 21:29:08 INFO - 'tests': ['a11yr', 21:29:08 INFO - 'ts_paint', 21:29:08 INFO - 'tpaint', 21:29:08 INFO - 'sessionrestore', 21:29:08 INFO - 'sessionrestore_no_auto_restore', 21:29:08 INFO - 'tabpaint']}, 21:29:08 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 21:29:08 INFO - 'tests': ['tsvgx', 21:29:08 INFO - 'tsvgr_opacity', 21:29:08 INFO - 'tart', 21:29:08 INFO - 'tscrollx', 21:29:08 INFO - 'cart', 21:29:08 INFO - 'tsvg_static']}, 21:29:08 INFO - 'svgr-e10s': {'tests': ['tsvgx', 21:29:08 INFO - 'tsvgr_opacity', 21:29:08 INFO - 'tart', 21:29:08 INFO - 'tscrollx', 21:29:08 INFO - 'cart', 21:29:08 INFO - 'tsvg_static']}, 21:29:08 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 21:29:08 INFO - 'talos_options': ['--disable-e10s'], 21:29:08 INFO - 'tests': ['tp5o']}, 21:29:08 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 21:29:08 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 21:29:08 INFO - 'talos_options': ['--disable-e10s', 21:29:08 INFO - '--xperf_path', 21:29:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:29:08 INFO - 'tests': ['tp5n']}, 21:29:08 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 21:29:08 INFO - 'talos_options': ['--xperf_path', 21:29:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:29:08 INFO - 'tests': ['tp5n']}}, 21:29:08 INFO - 'talos.zip': {'path': '', 21:29:08 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:29:08 INFO - Running post-action listener: _resource_record_post_action 21:29:08 INFO - [mozharness: 2016-12-28 05:29:08.618255Z] Finished populate-webroot step (success) 21:29:08 INFO - [mozharness: 2016-12-28 05:29:08.618344Z] Running create-virtualenv step. 21:29:08 INFO - Running pre-action listener: _resource_record_pre_action 21:29:08 INFO - Running main action method: create_virtualenv 21:29:08 INFO - Creating virtualenv /builds/slave/test/build/venv 21:29:08 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 21:29:08 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 21:29:08 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:29:08 INFO - Using real prefix '/usr' 21:29:08 INFO - New python executable in /builds/slave/test/build/venv/bin/python 21:29:09 INFO - Installing distribute.............................................................................................................................................................................................done. 21:29:11 INFO - Installing pip.................done. 21:29:11 INFO - Return code: 0 21:29:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 21:29:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 21:29:11 INFO - Reading from file tmpfile_stdout 21:29:11 INFO - Output received: 21:29:11 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 21:29:11 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 21:29:11 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')]} 21:29:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:29:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:29:11 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')]} 21:29:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:29:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:29:11 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 0x255b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x266ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28cd630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2956850>, '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 0x28cb6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28cbb60>, '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': 'd2a8c569965ab8367867b893000001a1-1482899256.628074-202179440', '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 21:29:11 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 21:29:11 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 21:29:11 INFO - Using env: {'DISPLAY': ':0', 21:29:11 INFO - 'HOME': '/home/cltbld', 21:29:11 INFO - 'LANG': 'en_US.UTF-8', 21:29:11 INFO - 'LANGUAGE': 'en_US:en', 21:29:11 INFO - 'LOGNAME': 'cltbld', 21:29:11 INFO - 'MAIL': '/var/mail/cltbld', 21:29:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:29:11 INFO - 'MOZ_NO_REMOTE': '1', 21:29:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:29:11 INFO - 'NO_EM_RESTART': '1', 21:29:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:29:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:29:11 INFO - 'PWD': '/builds/slave/test', 21:29:11 INFO - 'SHELL': '/bin/bash', 21:29:11 INFO - 'SHLVL': '1', 21:29:11 INFO - 'TERM': 'linux', 21:29:11 INFO - 'TMOUT': '86400', 21:29:11 INFO - 'USER': 'cltbld', 21:29:11 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1482899256.628074-202179440', 21:29:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:29:11 INFO - '_': '/tools/buildbot/bin/python'} 21:29:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:29:11 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 21:29:11 INFO - Cleaning up... 21:29:11 INFO - Return code: 0 21:29:11 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 21:29:11 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')]} 21:29:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:29:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:29:11 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')]} 21:29:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:29:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:29:11 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 0x255b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x266ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28cd630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2956850>, '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 0x28cb6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28cbb60>, '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': 'd2a8c569965ab8367867b893000001a1-1482899256.628074-202179440', '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 21:29:11 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 21:29:11 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 21:29:11 INFO - Using env: {'DISPLAY': ':0', 21:29:11 INFO - 'HOME': '/home/cltbld', 21:29:11 INFO - 'LANG': 'en_US.UTF-8', 21:29:11 INFO - 'LANGUAGE': 'en_US:en', 21:29:11 INFO - 'LOGNAME': 'cltbld', 21:29:11 INFO - 'MAIL': '/var/mail/cltbld', 21:29:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:29:11 INFO - 'MOZ_NO_REMOTE': '1', 21:29:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:29:11 INFO - 'NO_EM_RESTART': '1', 21:29:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:29:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:29:11 INFO - 'PWD': '/builds/slave/test', 21:29:11 INFO - 'SHELL': '/bin/bash', 21:29:11 INFO - 'SHLVL': '1', 21:29:11 INFO - 'TERM': 'linux', 21:29:11 INFO - 'TMOUT': '86400', 21:29:11 INFO - 'USER': 'cltbld', 21:29:11 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1482899256.628074-202179440', 21:29:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:29:11 INFO - '_': '/tools/buildbot/bin/python'} 21:29:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:29:11 INFO - Downloading/unpacking psutil>=3.1.1 21:29:11 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 21:29:11 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 21:29:11 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 21:29:11 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 21:29:11 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 21:29:11 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 21:29:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 21:29:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:29:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:29:13 INFO - Installing collected packages: psutil 21:29:13 INFO - Running setup.py install for psutil 21:29:14 INFO - building 'psutil._psutil_linux' extension 21:29:14 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 21:29:14 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 21:29:14 INFO - building 'psutil._psutil_posix' extension 21:29:14 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 21:29:14 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 21:29:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:29:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:29:14 INFO - Successfully installed psutil 21:29:14 INFO - Cleaning up... 21:29:14 INFO - Return code: 0 21:29:14 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 21:29:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:29:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:29:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:29:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:29:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:29:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:29:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x255b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x266ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28cd630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2956850>, '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 0x28cb6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28cbb60>, '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': 'd2a8c569965ab8367867b893000001a1-1482899256.628074-202179440', '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 21:29:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 21:29:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 21:29:14 INFO - Using env: {'DISPLAY': ':0', 21:29:14 INFO - 'HOME': '/home/cltbld', 21:29:14 INFO - 'LANG': 'en_US.UTF-8', 21:29:14 INFO - 'LANGUAGE': 'en_US:en', 21:29:14 INFO - 'LOGNAME': 'cltbld', 21:29:14 INFO - 'MAIL': '/var/mail/cltbld', 21:29:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:29:14 INFO - 'MOZ_NO_REMOTE': '1', 21:29:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:29:14 INFO - 'NO_EM_RESTART': '1', 21:29:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:29:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:29:14 INFO - 'PWD': '/builds/slave/test', 21:29:14 INFO - 'SHELL': '/bin/bash', 21:29:14 INFO - 'SHLVL': '1', 21:29:14 INFO - 'TERM': 'linux', 21:29:14 INFO - 'TMOUT': '86400', 21:29:14 INFO - 'USER': 'cltbld', 21:29:14 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1482899256.628074-202179440', 21:29:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:29:14 INFO - '_': '/tools/buildbot/bin/python'} 21:29:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:29:14 INFO - Downloading/unpacking mozsystemmonitor==0.3 21:29:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:29:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:29:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:29:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:29:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:29:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:29:16 INFO - Downloading mozsystemmonitor-0.3.tar.gz 21:29:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:29:16 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 21:29:16 INFO - Installing collected packages: mozsystemmonitor 21:29:16 INFO - Running setup.py install for mozsystemmonitor 21:29:16 INFO - Successfully installed mozsystemmonitor 21:29:16 INFO - Cleaning up... 21:29:16 INFO - Return code: 0 21:29:16 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 21:29:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:29:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:29:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:29:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:29:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:29:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:29:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x255b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x266ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28cd630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2956850>, '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 0x28cb6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28cbb60>, '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': 'd2a8c569965ab8367867b893000001a1-1482899256.628074-202179440', '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 21:29:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 21:29:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 21:29:16 INFO - Using env: {'DISPLAY': ':0', 21:29:16 INFO - 'HOME': '/home/cltbld', 21:29:16 INFO - 'LANG': 'en_US.UTF-8', 21:29:16 INFO - 'LANGUAGE': 'en_US:en', 21:29:16 INFO - 'LOGNAME': 'cltbld', 21:29:16 INFO - 'MAIL': '/var/mail/cltbld', 21:29:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:29:16 INFO - 'MOZ_NO_REMOTE': '1', 21:29:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:29:16 INFO - 'NO_EM_RESTART': '1', 21:29:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:29:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:29:16 INFO - 'PWD': '/builds/slave/test', 21:29:16 INFO - 'SHELL': '/bin/bash', 21:29:16 INFO - 'SHLVL': '1', 21:29:16 INFO - 'TERM': 'linux', 21:29:16 INFO - 'TMOUT': '86400', 21:29:16 INFO - 'USER': 'cltbld', 21:29:16 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1482899256.628074-202179440', 21:29:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:29:16 INFO - '_': '/tools/buildbot/bin/python'} 21:29:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:29:17 INFO - Downloading/unpacking jsonschema==2.5.1 21:29:17 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 21:29:17 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 21:29:17 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 21:29:17 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 21:29:17 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 21:29:17 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 21:29:19 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:29:19 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 21:29:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:29:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:29:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:29:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:29:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:29:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:29:19 INFO - Downloading functools32-3.2.3-2.tar.gz 21:29:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 21:29:19 INFO - warning: no files found matching '*.txt' 21:29:19 INFO - no previously-included directories found matching 'build' 21:29:19 INFO - no previously-included directories found matching 'dist' 21:29:19 INFO - no previously-included directories found matching '.git*' 21:29:19 INFO - Installing collected packages: jsonschema, functools32 21:29:19 INFO - Running setup.py install for functools32 21:29:19 INFO - warning: no files found matching '*.txt' 21:29:19 INFO - no previously-included directories found matching 'build' 21:29:19 INFO - no previously-included directories found matching 'dist' 21:29:19 INFO - no previously-included directories found matching '.git*' 21:29:19 INFO - Successfully installed jsonschema functools32 21:29:19 INFO - Cleaning up... 21:29:19 INFO - Return code: 0 21:29:19 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 21:29:19 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')]} 21:29:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:29:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:29:19 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')]} 21:29:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:29:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:29:19 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 0x255b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x266ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28cd630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2956850>, '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 0x28cb6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28cbb60>, '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': 'd2a8c569965ab8367867b893000001a1-1482899256.628074-202179440', '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 21:29:19 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 21:29:19 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 21:29:19 INFO - Using env: {'DISPLAY': ':0', 21:29:19 INFO - 'HOME': '/home/cltbld', 21:29:19 INFO - 'LANG': 'en_US.UTF-8', 21:29:19 INFO - 'LANGUAGE': 'en_US:en', 21:29:19 INFO - 'LOGNAME': 'cltbld', 21:29:19 INFO - 'MAIL': '/var/mail/cltbld', 21:29:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:29:19 INFO - 'MOZ_NO_REMOTE': '1', 21:29:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:29:19 INFO - 'NO_EM_RESTART': '1', 21:29:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:29:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:29:19 INFO - 'PWD': '/builds/slave/test', 21:29:19 INFO - 'SHELL': '/bin/bash', 21:29:19 INFO - 'SHLVL': '1', 21:29:19 INFO - 'TERM': 'linux', 21:29:19 INFO - 'TMOUT': '86400', 21:29:19 INFO - 'USER': 'cltbld', 21:29:19 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1482899256.628074-202179440', 21:29:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:29:19 INFO - '_': '/tools/buildbot/bin/python'} 21:29:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:29:19 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 21:29:19 INFO - Cleaning up... 21:29:19 INFO - Return code: 0 21:29:19 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 21:29:19 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')]} 21:29:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:29:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:29:19 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')]} 21:29:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:29:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:29:19 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 0x255b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x266ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28cd630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2956850>, '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 0x28cb6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28cbb60>, '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': 'd2a8c569965ab8367867b893000001a1-1482899256.628074-202179440', '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 21:29:19 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 21:29:19 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 21:29:19 INFO - Using env: {'DISPLAY': ':0', 21:29:19 INFO - 'HOME': '/home/cltbld', 21:29:19 INFO - 'LANG': 'en_US.UTF-8', 21:29:19 INFO - 'LANGUAGE': 'en_US:en', 21:29:19 INFO - 'LOGNAME': 'cltbld', 21:29:19 INFO - 'MAIL': '/var/mail/cltbld', 21:29:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:29:19 INFO - 'MOZ_NO_REMOTE': '1', 21:29:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:29:19 INFO - 'NO_EM_RESTART': '1', 21:29:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:29:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:29:19 INFO - 'PWD': '/builds/slave/test', 21:29:19 INFO - 'SHELL': '/bin/bash', 21:29:19 INFO - 'SHLVL': '1', 21:29:19 INFO - 'TERM': 'linux', 21:29:19 INFO - 'TMOUT': '86400', 21:29:19 INFO - 'USER': 'cltbld', 21:29:19 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1482899256.628074-202179440', 21:29:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:29:19 INFO - '_': '/tools/buildbot/bin/python'} 21:29:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:29:20 INFO - Downloading/unpacking blobuploader==1.2.4 21:29:20 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 21:29:20 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 21:29:20 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 21:29:20 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 21:29:20 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 21:29:20 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 21:29:22 INFO - Downloading blobuploader-1.2.4.tar.gz 21:29:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:29:22 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:29:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:29:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:29:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:29:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:29:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:29:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:29:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 21:29:22 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:29:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:29:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:29:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:29:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:29:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:29:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:29:22 INFO - Downloading docopt-0.6.1.tar.gz 21:29:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 21:29:22 INFO - Installing collected packages: blobuploader, requests, docopt 21:29:22 INFO - Running setup.py install for blobuploader 21:29:23 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:29:23 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 21:29:23 INFO - Running setup.py install for requests 21:29:23 INFO - Running setup.py install for docopt 21:29:23 INFO - Successfully installed blobuploader requests docopt 21:29:23 INFO - Cleaning up... 21:29:23 INFO - Return code: 0 21:29:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:29:23 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')]} 21:29:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:29:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:29:23 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')]} 21:29:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:29:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:29:23 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 0x255b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x266ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28cd630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2956850>, '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 0x28cb6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28cbb60>, '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': 'd2a8c569965ab8367867b893000001a1-1482899256.628074-202179440', '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 21:29:23 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 21:29:23 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 21:29:23 INFO - Using env: {'DISPLAY': ':0', 21:29:23 INFO - 'HOME': '/home/cltbld', 21:29:23 INFO - 'LANG': 'en_US.UTF-8', 21:29:23 INFO - 'LANGUAGE': 'en_US:en', 21:29:23 INFO - 'LOGNAME': 'cltbld', 21:29:23 INFO - 'MAIL': '/var/mail/cltbld', 21:29:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:29:23 INFO - 'MOZ_NO_REMOTE': '1', 21:29:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:29:23 INFO - 'NO_EM_RESTART': '1', 21:29:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:29:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:29:23 INFO - 'PWD': '/builds/slave/test', 21:29:23 INFO - 'SHELL': '/bin/bash', 21:29:23 INFO - 'SHLVL': '1', 21:29:23 INFO - 'TERM': 'linux', 21:29:23 INFO - 'TMOUT': '86400', 21:29:23 INFO - 'USER': 'cltbld', 21:29:23 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1482899256.628074-202179440', 21:29:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:29:23 INFO - '_': '/tools/buildbot/bin/python'} 21:29:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:29:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:29:23 INFO - Running setup.py (path:/tmp/pip-9YUiL8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:29:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:29:23 INFO - Running setup.py (path:/tmp/pip-m88lyn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:29:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:29:23 INFO - Running setup.py (path:/tmp/pip-UXnn0c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:29:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:29:23 INFO - Running setup.py (path:/tmp/pip-OFK9rI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:29:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:29:24 INFO - Running setup.py (path:/tmp/pip-YEOGmt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:29:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:29:24 INFO - Running setup.py (path:/tmp/pip-8wx8ua-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:29:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:29:24 INFO - Running setup.py (path:/tmp/pip-B0yrjK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:29:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:29:24 INFO - Running setup.py (path:/tmp/pip-rkDkuz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:29:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:29:24 INFO - Running setup.py (path:/tmp/pip-IryIGr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:29:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:29:24 INFO - Running setup.py (path:/tmp/pip-kmdWlP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:29:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:29:24 INFO - Running setup.py (path:/tmp/pip-Jrytnb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:29:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:29:24 INFO - Running setup.py (path:/tmp/pip-qi0_2T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:29:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:29:24 INFO - Running setup.py (path:/tmp/pip-ZKb92Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:29:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:29:24 INFO - Running setup.py (path:/tmp/pip-srRglW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:29:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:29:24 INFO - Running setup.py (path:/tmp/pip-2WZVE3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:29:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:29:24 INFO - Running setup.py (path:/tmp/pip-kY_cy0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:29:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:29:24 INFO - Running setup.py (path:/tmp/pip-Oc7fvO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:29:24 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:29:24 INFO - Running setup.py install for manifestparser 21:29:25 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 21:29:25 INFO - Running setup.py install for mozcrash 21:29:25 INFO - Running setup.py install for mozdebug 21:29:25 INFO - Running setup.py install for mozdevice 21:29:25 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 21:29:25 INFO - Installing dm script to /builds/slave/test/build/venv/bin 21:29:25 INFO - Running setup.py install for mozfile 21:29:25 INFO - Running setup.py install for mozhttpd 21:29:25 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 21:29:25 INFO - Running setup.py install for mozinfo 21:29:25 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 21:29:25 INFO - Running setup.py install for mozInstall 21:29:25 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 21:29:25 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 21:29:25 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 21:29:25 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 21:29:25 INFO - Running setup.py install for mozleak 21:29:25 INFO - Running setup.py install for mozlog 21:29:26 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 21:29:26 INFO - Running setup.py install for moznetwork 21:29:26 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 21:29:26 INFO - Running setup.py install for mozprocess 21:29:26 INFO - Running setup.py install for mozprofile 21:29:26 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 21:29:26 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 21:29:26 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 21:29:26 INFO - Running setup.py install for mozrunner 21:29:26 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 21:29:26 INFO - Running setup.py install for mozscreenshot 21:29:26 INFO - Running setup.py install for moztest 21:29:26 INFO - Running setup.py install for mozversion 21:29:26 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 21:29:26 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:29:26 INFO - Cleaning up... 21:29:26 INFO - Return code: 0 21:29:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:29:26 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')]} 21:29:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:29:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:29:26 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')]} 21:29:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:29:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:29:26 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 0x255b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x266ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28cd630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2956850>, '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 0x28cb6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28cbb60>, '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': 'd2a8c569965ab8367867b893000001a1-1482899256.628074-202179440', '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 21:29:26 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 21:29:26 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 21:29:26 INFO - Using env: {'DISPLAY': ':0', 21:29:26 INFO - 'HOME': '/home/cltbld', 21:29:26 INFO - 'LANG': 'en_US.UTF-8', 21:29:26 INFO - 'LANGUAGE': 'en_US:en', 21:29:26 INFO - 'LOGNAME': 'cltbld', 21:29:26 INFO - 'MAIL': '/var/mail/cltbld', 21:29:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:29:26 INFO - 'MOZ_NO_REMOTE': '1', 21:29:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:29:26 INFO - 'NO_EM_RESTART': '1', 21:29:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:29:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:29:26 INFO - 'PWD': '/builds/slave/test', 21:29:26 INFO - 'SHELL': '/bin/bash', 21:29:26 INFO - 'SHLVL': '1', 21:29:26 INFO - 'TERM': 'linux', 21:29:26 INFO - 'TMOUT': '86400', 21:29:26 INFO - 'USER': 'cltbld', 21:29:26 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1482899256.628074-202179440', 21:29:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:29:26 INFO - '_': '/tools/buildbot/bin/python'} 21:29:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:29:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:29:27 INFO - Running setup.py (path:/tmp/pip-3fvnZQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:29:27 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)) 21:29:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:29:27 INFO - Running setup.py (path:/tmp/pip-GSfG9J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:29:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 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)) 21:29:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:29:27 INFO - Running setup.py (path:/tmp/pip-XJQNsJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:29:27 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)) 21:29:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:29:27 INFO - Running setup.py (path:/tmp/pip-JBk4nG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:29:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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)) 21:29:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:29:27 INFO - Running setup.py (path:/tmp/pip-hlV414-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:29:27 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)) 21:29:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:29:27 INFO - Running setup.py (path:/tmp/pip-_puRUJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:29:27 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)) 21:29:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:29:27 INFO - Running setup.py (path:/tmp/pip-pDWtFQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:29:27 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)) 21:29:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:29:27 INFO - Running setup.py (path:/tmp/pip-hBpusY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:29:27 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)) 21:29:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:29:27 INFO - Running setup.py (path:/tmp/pip-2LJbis-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:29:27 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)) 21:29:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:29:27 INFO - Running setup.py (path:/tmp/pip-cQipeZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:29:27 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)) 21:29:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:29:27 INFO - Running setup.py (path:/tmp/pip-1NJXaL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:29:27 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)) 21:29:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:29:27 INFO - Running setup.py (path:/tmp/pip-QoxyB7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:29:27 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)) 21:29:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:29:27 INFO - Running setup.py (path:/tmp/pip-L1EODw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:29:27 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)) 21:29:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:29:27 INFO - Running setup.py (path:/tmp/pip-_XOC6W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:29:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 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)) 21:29:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:29:28 INFO - Running setup.py (path:/tmp/pip-2z44vc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:29:28 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)) 21:29:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:29:28 INFO - Running setup.py (path:/tmp/pip-WToiuE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:29:28 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)) 21:29:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:29:28 INFO - Running setup.py (path:/tmp/pip-cODHMZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:29:28 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)) 21:29:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 21:29:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 21:29:28 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)) 21:29:28 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 21:29:28 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 21:29:28 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:29:28 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 21:29:28 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 21:29:28 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 21:29:28 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 21:29:28 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 21:29:28 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 21:29:30 INFO - Downloading blessings-1.6.tar.gz 21:29:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 21:29:30 INFO - Installing collected packages: blessings 21:29:30 INFO - Running setup.py install for blessings 21:29:30 INFO - Successfully installed blessings 21:29:30 INFO - Cleaning up... 21:29:30 INFO - Return code: 0 21:29:30 INFO - Done creating virtualenv /builds/slave/test/build/venv. 21:29:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:29:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:29:30 INFO - Reading from file tmpfile_stdout 21:29:30 INFO - Current package versions: 21:29:30 INFO - argparse == 1.2.1 21:29:30 INFO - blessings == 1.6 21:29:30 INFO - blobuploader == 1.2.4 21:29:30 INFO - docopt == 0.6.1 21:29:30 INFO - functools32 == 3.2.3-2 21:29:30 INFO - jsonschema == 2.5.1 21:29:30 INFO - manifestparser == 1.1 21:29:30 INFO - mozInstall == 1.12 21:29:30 INFO - mozcrash == 1.0 21:29:30 INFO - mozdebug == 0.1 21:29:30 INFO - mozdevice == 0.49 21:29:30 INFO - mozfile == 1.2 21:29:30 INFO - mozhttpd == 0.7 21:29:30 INFO - mozinfo == 0.9 21:29:30 INFO - mozleak == 0.1 21:29:30 INFO - mozlog == 3.4 21:29:30 INFO - moznetwork == 0.27 21:29:30 INFO - mozprocess == 0.23 21:29:30 INFO - mozprofile == 0.28 21:29:30 INFO - mozrunner == 6.13 21:29:30 INFO - mozscreenshot == 0.1 21:29:30 INFO - mozsystemmonitor == 0.3 21:29:30 INFO - moztest == 0.7 21:29:30 INFO - mozversion == 1.4 21:29:30 INFO - psutil == 3.1.1 21:29:30 INFO - requests == 1.2.3 21:29:30 INFO - wsgiref == 0.1.2 21:29:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:29:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:29:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:29:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:29:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:29:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:29:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:29:30 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 0x255b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x266ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28cd630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2956850>, '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 0x28cb6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28cbb60>, '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': 'd2a8c569965ab8367867b893000001a1-1482899256.628074-202179440', '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 21:29:30 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 21:29:30 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 21:29:30 INFO - Using env: {'DISPLAY': ':0', 21:29:30 INFO - 'HOME': '/home/cltbld', 21:29:30 INFO - 'LANG': 'en_US.UTF-8', 21:29:30 INFO - 'LANGUAGE': 'en_US:en', 21:29:30 INFO - 'LOGNAME': 'cltbld', 21:29:30 INFO - 'MAIL': '/var/mail/cltbld', 21:29:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:29:30 INFO - 'MOZ_NO_REMOTE': '1', 21:29:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:29:30 INFO - 'NO_EM_RESTART': '1', 21:29:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:29:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:29:30 INFO - 'PWD': '/builds/slave/test', 21:29:30 INFO - 'SHELL': '/bin/bash', 21:29:30 INFO - 'SHLVL': '1', 21:29:30 INFO - 'TERM': 'linux', 21:29:30 INFO - 'TMOUT': '86400', 21:29:30 INFO - 'USER': 'cltbld', 21:29:30 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1482899256.628074-202179440', 21:29:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:29:30 INFO - '_': '/tools/buildbot/bin/python'} 21:29:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:29:30 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)) 21:29:30 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)) 21:29:30 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)) 21:29:30 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)) 21:29:30 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)) 21:29:30 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)) 21:29:30 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)) 21:29:30 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)) 21:29:30 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)) 21:29:30 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)) 21:29:30 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)) 21:29:30 INFO - Cleaning up... 21:29:30 INFO - Return code: 0 21:29:30 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 21:29:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:29:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:29:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:29:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:29:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:29:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:29:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x255b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x266ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28cd630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2956850>, '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 0x28cb6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28cbb60>, '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': 'd2a8c569965ab8367867b893000001a1-1482899256.628074-202179440', '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 21:29:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 21:29:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 21:29:30 INFO - Using env: {'DISPLAY': ':0', 21:29:30 INFO - 'HOME': '/home/cltbld', 21:29:30 INFO - 'LANG': 'en_US.UTF-8', 21:29:30 INFO - 'LANGUAGE': 'en_US:en', 21:29:30 INFO - 'LOGNAME': 'cltbld', 21:29:30 INFO - 'MAIL': '/var/mail/cltbld', 21:29:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:29:30 INFO - 'MOZ_NO_REMOTE': '1', 21:29:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:29:30 INFO - 'NO_EM_RESTART': '1', 21:29:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:29:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:29:30 INFO - 'PWD': '/builds/slave/test', 21:29:30 INFO - 'SHELL': '/bin/bash', 21:29:30 INFO - 'SHLVL': '1', 21:29:30 INFO - 'TERM': 'linux', 21:29:30 INFO - 'TMOUT': '86400', 21:29:30 INFO - 'USER': 'cltbld', 21:29:30 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1482899256.628074-202179440', 21:29:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:29:30 INFO - '_': '/tools/buildbot/bin/python'} 21:29:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:29:31 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 21:29:31 INFO - Cleaning up... 21:29:31 INFO - Return code: 0 21:29:31 INFO - Running post-action listener: _resource_record_post_action 21:29:31 INFO - Running post-action listener: _start_resource_monitoring 21:29:31 INFO - Starting resource monitoring. 21:29:31 INFO - [mozharness: 2016-12-28 05:29:31.223445Z] Finished create-virtualenv step (success) 21:29:31 INFO - [mozharness: 2016-12-28 05:29:31.223957Z] Running install step. 21:29:31 INFO - Running pre-action listener: _resource_record_pre_action 21:29:31 INFO - Running main action method: install 21:29:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:29:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:29:31 INFO - Reading from file tmpfile_stdout 21:29:31 INFO - Detecting whether we're running mozinstall >=1.0... 21:29:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 21:29:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 21:29:31 INFO - Reading from file tmpfile_stdout 21:29:31 INFO - Output received: 21:29:31 INFO - Usage: mozinstall [options] installer 21:29:31 INFO - Options: 21:29:31 INFO - -h, --help show this help message and exit 21:29:31 INFO - -d DEST, --destination=DEST 21:29:31 INFO - Directory to install application into. [default: 21:29:31 INFO - "/builds/slave/test"] 21:29:31 INFO - --app=APP Application being installed. [default: firefox] 21:29:31 INFO - mkdir: /builds/slave/test/build/application 21:29:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 21:29:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 21:29:47 INFO - Reading from file tmpfile_stdout 21:29:47 INFO - Output received: 21:29:47 INFO - /builds/slave/test/build/application/firefox/firefox 21:29:47 INFO - Running post-action listener: _resource_record_post_action 21:29:47 INFO - [mozharness: 2016-12-28 05:29:47.593014Z] Finished install step (success) 21:29:47 INFO - [mozharness: 2016-12-28 05:29:47.593258Z] Running run-tests step. 21:29:47 INFO - Running pre-action listener: _resource_record_pre_action 21:29:47 INFO - Running main action method: run_tests 21:29:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 21:29:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 21:29:47 INFO - Python 2.7.3 21:29:47 INFO - Return code: 0 21:29:47 INFO - grabbing minidump binary from tooltool 21:29: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')]} 21:29:47 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 0x2956850>, '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 0x28cb6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28cbb60>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 21:29:47 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 21:29:47 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 21:29:47 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 21:29:47 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:29:47 INFO - Return code: 0 21:29:47 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 21:29:47 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 21:29:47 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 21:29:47 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 21:29:47 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 21:29:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/U2oKbSY9TNaJgz2uM0_NQA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-043', '--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 21:29:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/U2oKbSY9TNaJgz2uM0_NQA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-043 --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 21:29:47 INFO - Using env: {'DISPLAY': ':0', 21:29:47 INFO - 'HOME': '/home/cltbld', 21:29:47 INFO - 'LANG': 'en_US.UTF-8', 21:29:47 INFO - 'LANGUAGE': 'en_US:en', 21:29:47 INFO - 'LOGNAME': 'cltbld', 21:29:47 INFO - 'MAIL': '/var/mail/cltbld', 21:29:47 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:29:47 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 21:29:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:29:47 INFO - 'MOZ_NO_REMOTE': '1', 21:29:47 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:29:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:29:47 INFO - 'NO_EM_RESTART': '1', 21:29:47 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:29:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:29:47 INFO - 'PWD': '/builds/slave/test', 21:29:47 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:29:47 INFO - 'SHELL': '/bin/bash', 21:29:47 INFO - 'SHLVL': '1', 21:29:47 INFO - 'TERM': 'linux', 21:29:47 INFO - 'TMOUT': '86400', 21:29:47 INFO - 'USER': 'cltbld', 21:29:47 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1482899256.628074-202179440', 21:29:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:29:47 INFO - '_': '/tools/buildbot/bin/python'} 21:29:47 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/U2oKbSY9TNaJgz2uM0_NQA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-043', '--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 21:29:47 INFO - mozversion application_buildid: 20161227203236 21:29:47 INFO - mozversion application_changeset: a0866731bbe884fffce6f007b851f7ac453013fa 21:29:47 INFO - mozversion application_display_name: Nightly 21:29:47 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:29:47 INFO - mozversion application_name: Firefox 21:29:47 INFO - mozversion application_remotingname: firefox 21:29:47 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 21:29:47 INFO - mozversion application_vendor: Mozilla 21:29:47 INFO - mozversion application_version: 53.0a1 21:29:47 INFO - mozversion platform_buildid: 20161227203236 21:29:47 INFO - mozversion platform_changeset: a0866731bbe884fffce6f007b851f7ac453013fa 21:29:47 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 21:29:47 INFO - mozversion platform_version: 53.0a1 21:29:47 INFO - using testdate: 1482902987 21:29:47 INFO - actual date: 1482902987 21:29:47 INFO - starting webserver on 'localhost:52616' 21:29:47 INFO - SUITE-START | Running 2 tests 21:29:47 INFO - TEST-START | dromaeo_css 21:29:47 INFO - operating with platform_type : linux_ 21:29:47 INFO - Initialising browser for dromaeo_css test... 21:29:47 INFO - TEST-INFO | started process 30284 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpft4g8K/profile http://localhost:52616/getInfo.html) 21:29:48 INFO - PROCESS | 30284 | 1482902988422 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4986 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3827 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2831 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 21:29:52 INFO - PROCESS | 30284 | __metrics Screen width/height:1600/1200 21:29:52 INFO - PROCESS | 30284 | colorDepth:24 21:29:52 INFO - PROCESS | 30284 | Browser inner width/height: 1024/697 21:29:52 INFO - PROCESS | 30284 | __metrics 21:29:52 INFO - PROCESS | 30284 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 21:29:52 INFO - PROCESS | 30284 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 21:29:53 INFO - PROCESS | 30284 | console.error: PushService: 21:29:53 INFO - PROCESS | 30284 | stateChangeProcessEnqueue: Error transitioning state 21:29:53 INFO - PROCESS | 30284 | UnknownError 21:29:54 INFO - TEST-INFO | 30284: exit 0 21:29:54 INFO - Browser initialized. 21:29:54 INFO - Running cycle 1/1 for dromaeo_css test... 21:29:54 INFO - TEST-INFO | started process 30367 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpft4g8K/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:30:06 INFO - PROCESS | 30367 | RSS: Main: 151371776 21:30:06 INFO - PROCESS | 30367 | 21:33:10 INFO - PROCESS | 30367 | Cycle 1(1): loaded http://localhost:52616/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52616/tests/dromaeo/cssquery-ext.html) 21:33:12 INFO - PROCESS | 30367 | RSS: Main: 284610560 21:33:12 INFO - PROCESS | 30367 | 21:34:54 INFO - PROCESS | 30367 | ************************* 21:34:54 INFO - PROCESS | 30367 | A coding exception was thrown and uncaught in a Task. 21:34:54 INFO - PROCESS | 30367 | 21:34:54 INFO - PROCESS | 30367 | Full message: ReferenceError: fetch is not defined 21:34:54 INFO - PROCESS | 30367 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 21:34:54 INFO - PROCESS | 30367 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 21:34:54 INFO - PROCESS | 30367 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 21:34:54 INFO - PROCESS | 30367 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 21:34:54 INFO - PROCESS | 30367 | TaskImpl@resource://gre/modules/Task.jsm:277:3 21:34:54 INFO - PROCESS | 30367 | asyncFunction@resource://gre/modules/Task.jsm:252:14 21:34:54 INFO - PROCESS | 30367 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 21:34:54 INFO - PROCESS | 30367 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 21:34:54 INFO - PROCESS | 30367 | TaskImpl@resource://gre/modules/Task.jsm:277:3 21:34:54 INFO - PROCESS | 30367 | asyncFunction@resource://gre/modules/Task.jsm:252:14 21:34:54 INFO - PROCESS | 30367 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 21:34:54 INFO - PROCESS | 30367 | 21:34:54 INFO - PROCESS | 30367 | ************************* 21:36:14 INFO - PROCESS | 30367 | Cycle 1(1): loaded http://localhost:52616/tests/dromaeo/cssquery-ext.html (next: http://localhost:52616/tests/dromaeo/cssquery-jquery.html) 21:36:15 INFO - PROCESS | 30367 | RSS: Main: 245977088 21:36:15 INFO - PROCESS | 30367 | 21:39:17 INFO - PROCESS | 30367 | Cycle 1(1): loaded http://localhost:52616/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52616/tests/dromaeo/cssquery-mootools.html) 21:39:18 INFO - PROCESS | 30367 | RSS: Main: 184410112 21:39:18 INFO - PROCESS | 30367 | 21:42:20 INFO - PROCESS | 30367 | Cycle 1(1): loaded http://localhost:52616/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52616/tests/dromaeo/cssquery-prototype.html) 21:42:21 INFO - PROCESS | 30367 | RSS: Main: 182784000 21:42:21 INFO - PROCESS | 30367 | 21:45:24 INFO - PROCESS | 30367 | Cycle 1(1): loaded http://localhost:52616/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52616/tests/dromaeo/cssquery-yui.html) 21:45:25 INFO - PROCESS | 30367 | RSS: Main: 183980032 21:45:25 INFO - PROCESS | 30367 | 21:48:28 INFO - PROCESS | 30367 | Cycle 1(1): loaded http://localhost:52616/tests/dromaeo/cssquery-yui.html (next: http://localhost:52616/tests/dromaeo/cssquery-dojo.html) 21:48:28 INFO - PROCESS | 30367 | RSS: Main: 278573056 21:48:28 INFO - PROCESS | 30367 | 21:48:28 INFO - PROCESS | 30367 | __start_tp_report 21:48:28 INFO - PROCESS | 30367 | _x_x_mozilla_page_load 21:48:28 INFO - PROCESS | 30367 | _x_x_mozilla_page_load_details 21:48:28 INFO - PROCESS | 30367 | |i|pagename|runs| 21:48:28 INFO - PROCESS | 30367 | |0;dojo.html;2543.605741110128;2563.5898256278997;2574.961375579366;2594;2600.1029644772557;4644.326572646964;4682;4780.928286075708;4815.951840481598;4834.540718631729;4722.362481065055;4745;4760.8095676173;4817.301491283762;4859.076775412669;4347.60871521563;4364.847230346939;4397.626201772853;4411.669124815636;4430.401895744066;4043.615856493643;4451.88870278244;4482;4522.7512486813375;4559.9772001139945;4293.506246781629;4439.889002774924;4477.552244775497;4480.283154695249;4565.7717114144325;4316.848910288125;4327.870163895088;4349.434573505456;4368.672349573794;4374.103308821684;4351.8476853309985;4354.433923589913;4367.170237654836;4394.340848872663;4419.690621656486;4713.552212539803;4714.811407543694;4715.952840471586;4723.527647235283;4737.147313483571;4965.652404331699;4999.675021123615;5033.899322013539;5061.038402703512;5078.542931136179;5109.259157422191;5111.361079865017;5115.104856650109;5168.689878607295;5176.482351764831;4781.784819683123;4783.569478746929;4804.95195048052;4806.4712881583;4832.081904438181;5140.563052140536;5170.276161337416;5192.584593232532;5200.375954885438;5238.5547228486;5012.097822392005;5015.874603134951;5017.448080711119;5032.84901452957;5066.569341605931;5008.624353173526;5033.043721692867;5071.87320316995;5088.287639730441;5116.2069879168785;5052.722100284446;5061.493850614909;5062.620303477254;5062.9746851266245;5077.339945807022;5015.423226328946;5016.799328026911;5062.7468626568525;5069.746512674352;5078.212877003996;4280.764557949343;4302.419173411549;4310.50429200646;4338.544452832471;4347;3755.7183211259266;3774.1130834253927;3790.8104594770703;3798.9430158547643;3843.0392401899526;4337.631301339421;4338.696291259582;4339.414179085783;4348.065165989265;4386.539413361551;5001.099802035668;5039.874003149951;5090.669106508065;5092.592592592584;5103.979204159183;4303.053328267776;4328.653707703376;4331.545187755303;4333.414988976448;4339.218940590661;4572.428446444194;4573.679842410999;4578.198815207325;4581.2440947243995;4582.839600613963;4706.599939005155;4709.670323077352;4711.081339138835;4716.292556116541;4718.292256161603;5098.107831129611;5103.974480127724;5104.821331253388;5105.081085404663;5106.387233532;4726.929096063493;4734.715917044989;4760.833370832004;4787.712737235777;4837.153498137742;5099.23511473282;5103.515166059218;5104.795808167632;5113.616478764033;5155.046316431472;5185.170372740344;5192.688438693691;5194.1429664105;5194.68831870089;5196.298499702643;4854.441739200037;4899.04468628614;4927.975360123177;4937.802487900443;4969.701817890938;4984.42679091909;4986.12742770021;4989.101961646938;4991.301217829434;5021.246812978083;4941.160002799461;4941.728204948762;4948.307236986897;4953.950460495493;4987.226979818134;4862.270659401118;4895.143349913822;4980.850574482772;4994.9500504994485;5008.524190201925;4944.802207911644;4954.182559877724;4958.057968985881;4964.05682920244;4998.825041123689 21:48:28 INFO - PROCESS | 30367 | |1;ext.html;534.4521865088285;545.888092940947;551.2943432406519;561.663241485265;564.2777245126238;29893.850530747342;30637.387252254997;30650.69349306509;30660.38679226417;30665.080047598552;30808.229794255156;38018.4297235541;39686.60313396865;39746.403803942965;43400.3489947651;7661.655225514851;7793.688252469894;7802.063752349726;7809.258120478546;7809.648565814524;7610.543367397946;7701.037370328709;7703.8844417333785;7706.922930770705;7709.575973321465;7564.810879727996;7762.45662803604;9901.801963960717;10194.184465242799;10205.081542661159;10113.089821916026;10143.746406339827;10156.187504999582;10166.288359814816;10196.541155648014;9912.603495860158;9977.80044399108;9995.950020249926;10031.398116113056;10036.498175091216;36073.81963090168;36376.27247455036;36403;36448.27103457916;36448.81775591105;3483.9129021774406;3489.94765078524;3522.6829585337186;3534.7348948828685;3557.3596752584526;28466.857665711745;28495;28564.14307570776;28573.142805715863;29241.268968275752;3357.3788849063003;3374.3588718143474;3395.439752440844;3402.3705614461405;3430.5025771263277;175108.99999999872;175271;176207.11896440564;176293.1185344065;176703;50327.748361258695;50573.24140137901;50634.74682626563;50746.49253507492;50769.49230507648;53099.75201983832;53134.20298695523;53271.46728532665;53337.466625334026;53473.1979020315;12257.816132758016;12595.433205505775;12600.495980160875;12640.6839829005;12668.873311266954;9166.633334666672;9283.675071372605;9304.302177336727;9334.393264437926;9380.718578442658;13777.588921938821;14073.577792666236;14946.028508147154;15475.148866812207;15544.378224870878;6307.684615769285;6489.610623362613;6550.115734375893;6561.540692151503;6573.112629794917;711.9822004449827;714.1358955663601;716.8781307177688;731.9853602927911;734.7024455095694;26695.599566006524;27783.166505004883;28132;28244.43511129766;28247.717522824507;799.5442597719244;812.7074253268819;821.0762891746784;821.2608652213056;838.7399906029151;13625.659358515919;14311.499097531536;14526.418943242152;14640.414383424544;15073;326787;329036;330710;330859;330994;43237.567624323354;43395.34906976398;43411.13177736427;43413;43429;1183.958561450345;1207.3600991474532;1214.36245970866;1221.5113954418125;1225.583301677434;24473.632895506227;24613.75386246186;25127;25396.365090872878;25478.363040924127;35568.82215588906;40504.59495405126;41590;46103;46187.538124618324;292761;294435;294990;295011;295366;22905.31284061481;23044.884775576014;23364.299071027217;23385.532289354458;23421.531569368857;32815.50776738303;36569.26861462809;36866.26267474582;37654.811725942294;40223;31495;31559.36881262316;31591.526127108573;31651.841740791147;32877.50683739698;31945.68054319427;32302.676973229965;32344.83827580847;32560.674393255766;32884.34231315312;31502.36995260128;31862;31926;32146;32233.51649725209;28300.575491368058;28469.715302846707;29215.269618259714;29337.41325173442;29910.700892991663;21063.8946805265;22310.54981426202;22433.214837481264;22672;22674.886625566767 21:48:28 INFO - PROCESS | 30367 | |2;jquery.html;360.49170669356215;361.8896236647823;371.81966746128126;380.3249232612113;391.4324229866694;12534.185277956927;13376.130551514145;13845.307734613267;16490;17122.82877171228;16815;17182.312707491685;17241.827581724177;17500.474985750447;17503.474895753116;4971.428285747149;5269.499397557245;5273.156294992802;5275.103232450473;5275.36695596529;5129.051125541768;5191.0656081905245;5203.1414816555225;5207.4792520748;5208.2968799212;5291.523762861341;5302.628815982864;5305.522502974732;5339.652922560022;5409.107497262967;5313.229581710631;5314.734263286839;5322.2282768998275;5349.946500535023;5880.529557635379;5274.077036518594;5278.340207474066;5285.471452854722;5286.841394758163;5286.973565132188;11684.941575292154;11846;18555.628887422175;19281.132349044186;19386.4184074478;2201.306588424641;2248.5165689376863;2309.1340747219792;2385.2248019393764;2408.9879550602136;8820.206181443637;9111.134442227978;9345.345825792198;9610.807783844353;9613.663521776703;1668.4911102113851;1733.16807932192;1737.0185844997536;1755.920983555743;1756.3677076252538;59666.70166649183;66428.66785666089;67384.3261567383;68014.65992670006;69165.00854888883;9478.90521094794;9497.810043799085;9671.90328096724;9834.262430317754;10173.186145108373;13416;13530.864691353157;13533.323333833268;13537.323133843269;13553;6166.260048794174;6334.683265836782;6486.35136486357;6589.341065893373;6593.934060659428;5740.253767010262;5842.620229685057;5938.198343223609;5948.583599148018;5950.375210602911;4150.398192262165;4235.110626768414;4259.8083086261195;4380.211561918885;4390.670699697535;1662.8881320722614;1735.7222844344844;1736.5311365931127;1737.7914650241798;1759.7184450488116;753.6533194730375;760.5208718507305;772.7758949904577;777.9338756205786;789.1713700614447;15864.92067539655;15866.603334916488;16444.917775411046;16543.91728041352;16967.321307147577;752.3003606645872;770.1412924589043;778.2956424435895;778.4006315137312;803.493798906685;6124.846878828065;6316.30520642729;6322.557420980579;6326.240851097898;6548.101050695245;182930;183072;296810;349064;349240;71519.28480715126;71772;74715.62642186863;74742.6262868693;75237.24762752412;910.5219759626117;922.326701507903;949.1410273702172;949.4682977532603;951.0727041134838;10438.791224175626;10504.212184086377;10522.1056210223;10552.155827533625;10568.207384446043;33819.66180338165;34013.82993085019;34067.48898766471;35203.64796352004;35782.82108589545;45246.321305179794;47576.762116189195;48825;48845.51154488552;48858.75570622124;6383.170187875547;6949.235584085847;7711.768646940601;7715.807104822425;8176.754697359089;8634.925072132635;11851.52593896269;12101.455434505644;12183.390830458264;12354.814677779661;9378.109079637423;9573.234141268822;9587.280953928344;9955.601775928882;10257.692269231935;9661.082197191256;10029.498525073863;10112.84830727525;10235.181185504993;10719.785604287714;9488.857667135137;9784.9021509784;9803.16673082796;10170.542325595217;10214.285000049926;6660.1615007020455;6771.3905748482875;6829.863402732017;6912.516123871281;7136.250693677089;9084.818303633758;9707.417554946725;9782.755431113994;9824.852627210455;10018.098371146632 21:48:28 INFO - PROCESS | 30367 | |3;mootools.html;1255.046164914665;1287.8905293050095;1291.0962326371541;1299.0322209953586;1306.947722091116;6272.153259309992;6550.96724516378;7022.157341119067;7450.329470347668;7454.776356709301;6968.8257793555185;7255;7256.020437240971;7438.516496427742;7445.739399121032;2016.3950814755551;2042.2647846775149;2050.41563154501;2050.641137800879;2052.2509284111266;1746.3451205797826;1806.4219449776076;2119.607872543577;2167.111484291441;2170.7612162662094;1953.4627977306213;2031.7054027166052;2032.19728207358;2034.745656792901;2050.712900193974;2135.29535253366;2149.591577600259;2167.6531754919138;2175.7824217578273;2178.7930146636045;2099.191811152702;2124.8937553122437;2158.093600687715;2158.8380871434706;2161.4164175672636;7260.45546584003;7412.925870741331;7426.851462970764;7435.03344565203;7459.291367320096;4342.109867477159;4442.2226110430865;4465.441819772528;4467.3299005149165;4469.08383781324;2800.0759749282893;2840.2757296889367;2895.739383455478;2898.565215217713;2909.8545072746488;1074.9355038697624;1103.0238239158402;1110.6279396402215;1125.0999200639455;1177.864545577261;9485.952570237174;9521.047895210493;9572.282078844115;9577.904220957771;9578.473183974878;3722.5532936047307;3887.8444862205197;3937.940930886039;3995.1011022520283;4015.5382131054716;7806.57063861493;7808.843823123506;7809.531428114331;7823.608819558999;7834.255745704149;4301.0537681709975;4322.524522302544;4330.133973205371;4363.47638283408;4365.497967733688;3950.644442000234;4033.4756481657196;4037.636612704812;4040;4044.6966477513715;3247.9350412991607;3248.5381578289393;3326.6340702522702;3350.6146793118614;3356.865725370958;894.3247847874812;906.3881879731155;990.346371394876;992.3549692699803;1004.2769206171545;588.488015426573;711.7793484019867;743.086004214808;744.2222877093342;748.9700411983569;4461.397711308997;4468.195724769507;4503.189425903369;4542.454905411372;4556.225441674923;816.6610856494607;819.2053707903324;826.6941231744294;827.2265431821266;850.2050582705191;2941.7453099197255;3012.819230846156;3041.8783248670247;3077.4305104396917;3117.9064628061196;8355.665773369119;8558.101399353103;8670.86993695095;8796.384253102222;8898.154675305836;8789.077146899612;8850.955745221232;8873.689420870238;8875.822483550293;8879.689210877717;6849.965750171217;6853.040574319599;6920.515563910579;6966.721331146799;6982.755603553953;4756.381670382828;4809.302651115639;4829.806807727792;4897.045076210247;4908.092002979461;7986.7603971880935;8018.4787988780545;8028.718994835152;8039.718609848627;8116.066652335057;8083.666195077746;8259.876101858357;8294.129116442688;8367.079621241537;8375.539345336065;5568.081266590967;5629.127485239795;5669.716514174357;5688.203651488712;5688.800891968761;6795.422389096982;6842.7605033823575;6849.623270719959;6859.828504287433;6874.484413668894;6250.1874756281395;6340.524460665376;6359.109724638647;6360.872782544416;6480.449161821298;5968.46283834457;6023.216981792339;6104.481119104748;6144.078388241799;6229.626222426669;5445.428230035789;5541.058020136506;5543.667379957215;5544.556435485232;5549.639273447213;4069.143493213944;4082.612151845689;4197.139586384723;4235.682323825664;4282.78586070689;4146.813393397365;4200.831966721297;4320.4383430153875;4423.181711383404;4481.282994720829 21:48:28 INFO - PROCESS | 30367 | |4;prototype.html;345.9878904238352;352.65616024376226;354.25959744134764;355.9466080087988;359.7391890879113;4797.976010119949;4877.634177436693;4887.633427492943;4908.263760435936;4918.803247870081;4713.434387873452;4799.232122860343;4840.225563909775;4843.903121937568;4852.29641701953;3674.6325367463173;3789.7536660117003;3844.211936553014;3870.4194370844457;3880.6701430378453;3918.804059797013;3923.431102490137;3938.586448422903;3949.5063117110362;3966.968588167083;3858.9807050964705;3863.0535518797933;3878.825452854614;3884.106655469244;3897.941530877039;3746.943795843051;3824.464574959508;3879.35990561557;3912.7456715313415;3917.157811070605;3466.722662187044;3488.8081155536433;3507.5615548056494;3508.7543871928974;3515.4199557073307;4695.906081878377;4704.835330763407;4708.952910470885;4712.410948631421;4773.570378665902;4329.740215587075;4461.866144015685;4462.286034234539;4464.620507256855;4473.150101480741;5028.647994640377;5048.318477005594;5061.569766569846;5063.924041139349;5084.9745751271375;3842.634949679776;3845.384738441836;3848.6536211741077;3860.1700634363465;3861.7489863158803;5064.442911279756;5226.320578324793;5259.5529380002745;5298.735063246861;5314.654547454403;3922.4704664870446;3924.978636002461;3935.2326296371934;3969.54350249725;3993.2612466693754;3967.4048892666333;3975.6819454444153;3978.3435733104293;4020.718549701493;4032.354823228269;4429.313456414261;4447.73313601185;4450.821967121344;4462.866114016585;4477.932831007537;4861.95138048615;5022.347094877642;5057.165567681365;5082.695038297714;5110.156824124052;1328.34247047712;1340.443715857908;1350.2371160294401;1360.1567028442232;1361.6868120332379;928.0765638190044;968.4528241543505;973.4597298499259;985.3742873275378;989.5547003848297;4228.471441069866;4247.893802654959;4359.38968544404;4368.934465983013;4384.188925048812;5160.767765450564;5185;5198.870028249248;5216.26972223889;5237.5286224239235;4308.030693094029;4349.608535231844;4350.869473915787;4410.404595379583;4446.1330040642415;2581.690197176351;2676.892924283007;2693.393986353094;2699.2442116207494;2702.2433718558837;4693.967327188013;4701.576858082789;4714.858554243378;4725.40932383452;4740.885891815489;5175.404828444702;5209.426963034063;5215.843524694265;5223.738813059408;5237.738113094406;4582.770861456914;4885.60915126789;4924.704517728947;4944.28307895346;4961.727105009187;5280.6303558750515;5301.204819277139;5339.3058902342445;5343.9465605345;5350.464953504618;4969.304297398295;5259.815906443256;5283.524482796567;5315.335583052119;5329.333833270841;4808.495108013608;4828.937633720576;4867.5132486751045;4875.26927940576;4895.853124406273;3962.2867883781196;3986.0304073465454;4005.439238506553;4006.719529633014;4009.4787677601726;5054.469280725618;5191.377034755854;5199.4540573239265;5272.657277276965;5272.894542109213;4999.525045120708;5023.648344615842;5028.899422011613;5060.139776238122;5062.594992400525;5028.245763135413;5033.169527028;5037.5214354636255;5045;5060.443351231435;4822.373091498082;4981.925271120864;4987.0524600325825;4996.15065438869;5020;4917.803287868445;5024.71375524143;5061.190209566452;5130.666506677054;5139.665921715076;4868.17241069012;4972.179590367549;4973.651844370859;4973.875653108556;4988.401391833003 21:48:28 INFO - PROCESS | 30367 | |5;yui.html;638.1735652330232;655.4395991427333;709.1808960650451;716.878130717778;725.3567246819741;11711.180217384786;12000.75998480031;12030.097742669313;12032.458539365749;12065.638030859078;12724;12860.164089334186;12907.870921290783;12936.417861196245;12941.611751647442;658.7661380210028;678.5724993254242;796.5061661769711;805.4040010392297;813.3574476163824;827.3381294964034;831.3266254333979;832.01406333495;832.1221111727125;832.142892820396;876.1501343696636;877.9253763430085;878.6221924572432;879.0286733159862;879.3141349747208;691.802836191688;710.594960872303;713.1584730018576;715.2632788228134;874.9256313213384;611.5138464920354;625.5933643131955;633.3033662970695;635.0696230023038;635.3170341882495;12782.04134689902;12805.359732013361;12842.422091005927;12845.421956012002;12870.806937895939;11811.173217874752;11860.347680877549;11991.28052316855;11994.580189693319;12276.140670153003;11622.941885290604;11691.94154029233;11711.882881171165;11764.000898853945;12223.266604003788;4092.1611069730634;4114.91770164598;4125.422440858282;4152.086540960984;4164.104717485725;59156.40843591595;60931.08603370953;61760.691196544176;61861.690691546704;62558.06162907606;9975.850362244571;10168.28821982454;10189.898101018895;10261.94869025665;10262.743431414274;18938.62122757537;18962.905185473985;18985.525361865788;18997.525061873563;19025.714614280798;6356.396142366468;6370.713317900613;6392.169018027626;6404.871902561923;6617.503687223478;3365.4110530657035;3399.9830000850334;3409.8636054558037;3417.572803399575;3419.452887537982;2611.9085831996167;2671.3722275265295;2682.865856707157;2685.154176434456;2698.0691661376795;484.3461327208241;484.41144010027983;486.409013049147;492.48535280631825;496.66226965662975;505.5273319446329;518.3313525552071;520.6700017422006;526.1528938409158;534.6471328922893;7199.748008819666;7292.34368906801;7314.926850731425;7465.738699145504;7537.321641052222;502.6406119624486;514.2954152810598;515.052303761074;525.4640266927712;527.6359312074657;3247.2044349134612;3617.1138071172204;3637.3452778499595;3737.1965027519213;3836.366999445116;55538.72230638821;65182;65855.34144658588;68561.3143868565;68807;49158.75420622874;51747.741261294206;59001;60432.69783651141;60865.69567152136;51500.227496587584;53099;53120.4687953131;53933.730331348095;54158.45841541613;6782.660866956731;7076.929230707627;7120.013465746499;7203.639818008974;7228.20489746138;37204.44193337048;38139.23721525609;38453.80773096117;38818;39096.41355379615;39939.80030099831;40512.392314114724;42353.57646423496;46412.767936160104;46519;11232.66302010941;11527.538898444303;11795.41022948832;11802.94098529502;11821.586244481401;12759.425825838045;12897.419616117484;12951;13309.66725831862;13796.965837170213;11179.832302515306;11894.345810980132;12186.146969712036;12258.019358451123;12395.814062788884;11609.129315301218;11702.122340824642;12047.879521204675;12235.388230588613;12241.449134788778;11696.415179241174;12004.339761313211;12006.579769708018;12735.426905789447;12765.553205637758;10036.49817509107;10187.21201035512;10403.843942341022;10584.523696433833;10774.515146818569;10196;10251.794964100825;10269.640562580274;10275.332103413255;10288.485575721334 21:48:28 INFO - PROCESS | 30367 | __end_tp_report 21:48:28 INFO - PROCESS | 30367 | __start_cc_report 21:48:28 INFO - PROCESS | 30367 | _x_x_mozilla_cycle_collect,1330 21:48:28 INFO - PROCESS | 30367 | __end_cc_report 21:48:28 INFO - PROCESS | 30367 | __startTimestamp1482904108564__endTimestamp 21:48:28 INFO - PROCESS | 30367 | 21:48:28 INFO - PROCESS | 30367 | ------- Summary: start ------- 21:48:28 INFO - PROCESS | 30367 | Number of tests: 6 21:48:28 INFO - PROCESS | 30367 | 21:48:28 INFO - PROCESS | 30367 | [#0] dojo.html Cycles:165 Average:4705.08 Median:4834.54 stddev:506.04 (10.5%) stddev-sans-first:478.34 21:48:28 INFO - PROCESS | 30367 | Values: 2543.6 2563.6 2575.0 2594.0 2600.1 4644.3 4682.0 4780.9 4816.0 4834.5 4722.4 4745.0 4760.8 4817.3 4859.1 4347.6 4364.8 4397.6 4411.7 4430.4 4043.6 4451.9 4482.0 4522.8 4560.0 4293.5 4439.9 4477.6 4480.3 4565.8 4316.8 4327.9 4349.4 4368.7 4374.1 4351.8 4354.4 4367.2 4394.3 4419.7 4713.6 4714.8 4716.0 4723.5 4737.1 4965.7 4999.7 5033.9 5061.0 5078.5 5109.3 5111.4 5115.1 5168.7 5176.5 4781.8 4783.6 4805.0 4806.5 4832.1 5140.6 5170.3 5192.6 5200.4 5238.6 5012.1 5015.9 5017.4 5032.8 5066.6 5008.6 5033.0 5071.9 5088.3 5116.2 5052.7 5061.5 5062.6 5063.0 5077.3 5015.4 5016.8 5062.7 5069.7 5078.2 4280.8 4302.4 4310.5 4338.5 4347.0 3755.7 3774.1 3790.8 3798.9 3843.0 4337.6 4338.7 4339.4 4348.1 4386.5 5001.1 5039.9 5090.7 5092.6 5104.0 4303.1 4328.7 4331.5 4333.4 4339.2 4572.4 4573.7 4578.2 4581.2 4582.8 4706.6 4709.7 4711.1 4716.3 4718.3 5098.1 5104.0 5104.8 5105.1 5106.4 4726.9 4734.7 4760.8 4787.7 4837.2 5099.2 5103.5 5104.8 5113.6 5155.0 5185.2 5192.7 5194.1 5194.7 5196.3 4854.4 4899.0 4928.0 4937.8 4969.7 4984.4 4986.1 4989.1 4991.3 5021.2 4941.2 4941.7 4948.3 4954.0 4987.2 4862.3 4895.1 4980.9 4995.0 5008.5 4944.8 4954.2 4958.1 4964.1 4998.8 21:48:28 INFO - PROCESS | 30367 | 21:48:28 INFO - PROCESS | 30367 | [#1] ext.html Cycles:180 Average:41556.39 Median:32880.92 stddev:72214.82 (219.6%) stddev-sans-first:72351.72 21:48:28 INFO - PROCESS | 30367 | Values: 534.5 545.9 551.3 561.7 564.3 29893.9 30637.4 30650.7 30660.4 30665.1 30808.2 38018.4 39686.6 39746.4 43400.3 7661.7 7793.7 7802.1 7809.3 7809.6 7610.5 7701.0 7703.9 7706.9 7709.6 7564.8 7762.5 9901.8 10194.2 10205.1 10113.1 10143.7 10156.2 10166.3 10196.5 9912.6 9977.8 9996.0 10031.4 10036.5 36073.8 36376.3 36403.0 36448.3 36448.8 3483.9 3489.9 3522.7 3534.7 3557.4 28466.9 28495.0 28564.1 28573.1 29241.3 3357.4 3374.4 3395.4 3402.4 3430.5 175109.0 175271.0 176207.1 176293.1 176703.0 50327.7 50573.2 50634.7 50746.5 50769.5 53099.8 53134.2 53271.5 53337.5 53473.2 12257.8 12595.4 12600.5 12640.7 12668.9 9166.6 9283.7 9304.3 9334.4 9380.7 13777.6 14073.6 14946.0 15475.1 15544.4 6307.7 6489.6 6550.1 6561.5 6573.1 712.0 714.1 716.9 732.0 734.7 26695.6 27783.2 28132.0 28244.4 28247.7 799.5 812.7 821.1 821.3 838.7 13625.7 14311.5 14526.4 14640.4 15073.0 326787.0 329036.0 330710.0 330859.0 330994.0 43237.6 43395.3 43411.1 43413.0 43429.0 1184.0 1207.4 1214.4 1221.5 1225.6 24473.6 24613.8 25127.0 25396.4 25478.4 35568.8 40504.6 41590.0 46103.0 46187.5 292761.0 294435.0 294990.0 295011.0 295366.0 22905.3 23044.9 23364.3 23385.5 23421.5 32815.5 36569.3 36866.3 37654.8 40223.0 31495.0 31559.4 31591.5 31651.8 32877.5 31945.7 32302.7 32344.8 32560.7 32884.3 31502.4 31862.0 31926.0 32146.0 32233.5 28300.6 28469.7 29215.3 29337.4 29910.7 21063.9 22310.5 22433.2 22672.0 22674.9 21:48:28 INFO - PROCESS | 30367 | 21:48:28 INFO - PROCESS | 30367 | [#2] jquery.html Cycles:180 Average:20139.68 Median:5273.62 stddev:47541.19 (901.5%) stddev-sans-first:47651.36 21:48:28 INFO - PROCESS | 30367 | Values: 360.5 361.9 371.8 380.3 391.4 12534.2 13376.1 13845.3 16490.0 17122.8 16815.0 17182.3 17241.8 17500.5 17503.5 4971.4 5269.5 5273.2 5275.1 5275.4 5129.1 5191.1 5203.1 5207.5 5208.3 5291.5 5302.6 5305.5 5339.7 5409.1 5313.2 5314.7 5322.2 5349.9 5880.5 5274.1 5278.3 5285.5 5286.8 5287.0 11684.9 11846.0 18555.6 19281.1 19386.4 2201.3 2248.5 2309.1 2385.2 2409.0 8820.2 9111.1 9345.3 9610.8 9613.7 1668.5 1733.2 1737.0 1755.9 1756.4 59666.7 66428.7 67384.3 68014.7 69165.0 9478.9 9497.8 9671.9 9834.3 10173.2 13416.0 13530.9 13533.3 13537.3 13553.0 6166.3 6334.7 6486.4 6589.3 6593.9 5740.3 5842.6 5938.2 5948.6 5950.4 4150.4 4235.1 4259.8 4380.2 4390.7 1662.9 1735.7 1736.5 1737.8 1759.7 753.7 760.5 772.8 777.9 789.2 15864.9 15866.6 16444.9 16543.9 16967.3 752.3 770.1 778.3 778.4 803.5 6124.8 6316.3 6322.6 6326.2 6548.1 182930.0 183072.0 296810.0 349064.0 349240.0 71519.3 71772.0 74715.6 74742.6 75237.2 910.5 922.3 949.1 949.5 951.1 10438.8 10504.2 10522.1 10552.2 10568.2 33819.7 34013.8 34067.5 35203.6 35782.8 45246.3 47576.8 48825.0 48845.5 48858.8 6383.2 6949.2 7711.8 7715.8 8176.8 8634.9 11851.5 12101.5 12183.4 12354.8 9378.1 9573.2 9587.3 9955.6 10257.7 9661.1 10029.5 10112.8 10235.2 10719.8 9488.9 9784.9 9803.2 10170.5 10214.3 6660.2 6771.4 6829.9 6912.5 7136.3 9084.8 9707.4 9782.8 9824.9 10018.1 21:48:28 INFO - PROCESS | 30367 | 21:48:28 INFO - PROCESS | 30367 | [#3] mootools.html Cycles:180 Average:4700.41 Median:5176.76 stddev:2583.59 (49.9%) stddev-sans-first:2577.86 21:48:28 INFO - PROCESS | 30367 | Values: 1255.0 1287.9 1291.1 1299.0 1306.9 6272.2 6551.0 7022.2 7450.3 7454.8 6968.8 7255.0 7256.0 7438.5 7445.7 2016.4 2042.3 2050.4 2050.6 2052.3 1746.3 1806.4 2119.6 2167.1 2170.8 1953.5 2031.7 2032.2 2034.7 2050.7 2135.3 2149.6 2167.7 2175.8 2178.8 2099.2 2124.9 2158.1 2158.8 2161.4 7260.5 7412.9 7426.9 7435.0 7459.3 4342.1 4442.2 4465.4 4467.3 4469.1 2800.1 2840.3 2895.7 2898.6 2909.9 1074.9 1103.0 1110.6 1125.1 1177.9 9486.0 9521.0 9572.3 9577.9 9578.5 3722.6 3887.8 3937.9 3995.1 4015.5 7806.6 7808.8 7809.5 7823.6 7834.3 4301.1 4322.5 4330.1 4363.5 4365.5 3950.6 4033.5 4037.6 4040.0 4044.7 3247.9 3248.5 3326.6 3350.6 3356.9 894.3 906.4 990.3 992.4 1004.3 588.5 711.8 743.1 744.2 749.0 4461.4 4468.2 4503.2 4542.5 4556.2 816.7 819.2 826.7 827.2 850.2 2941.7 3012.8 3041.9 3077.4 3117.9 8355.7 8558.1 8670.9 8796.4 8898.2 8789.1 8851.0 8873.7 8875.8 8879.7 6850.0 6853.0 6920.5 6966.7 6982.8 4756.4 4809.3 4829.8 4897.0 4908.1 7986.8 8018.5 8028.7 8039.7 8116.1 8083.7 8259.9 8294.1 8367.1 8375.5 5568.1 5629.1 5669.7 5688.2 5688.8 6795.4 6842.8 6849.6 6859.8 6874.5 6250.2 6340.5 6359.1 6360.9 6480.4 5968.5 6023.2 6104.5 6144.1 6229.6 5445.4 5541.1 5543.7 5544.6 5549.6 4069.1 4082.6 4197.1 4235.7 4282.8 4146.8 4200.8 4320.4 4423.2 4481.3 21:48:28 INFO - PROCESS | 30367 | 21:48:28 INFO - PROCESS | 30367 | [#4] prototype.html Cycles:180 Average:4251.73 Median:4757.23 stddev:1186.91 (24.9%) stddev-sans-first:1153.47 21:48:28 INFO - PROCESS | 30367 | Values: 346.0 352.7 354.3 355.9 359.7 4798.0 4877.6 4887.6 4908.3 4918.8 4713.4 4799.2 4840.2 4843.9 4852.3 3674.6 3789.8 3844.2 3870.4 3880.7 3918.8 3923.4 3938.6 3949.5 3967.0 3859.0 3863.1 3878.8 3884.1 3897.9 3746.9 3824.5 3879.4 3912.7 3917.2 3466.7 3488.8 3507.6 3508.8 3515.4 4695.9 4704.8 4709.0 4712.4 4773.6 4329.7 4461.9 4462.3 4464.6 4473.2 5028.6 5048.3 5061.6 5063.9 5085.0 3842.6 3845.4 3848.7 3860.2 3861.7 5064.4 5226.3 5259.6 5298.7 5314.7 3922.5 3925.0 3935.2 3969.5 3993.3 3967.4 3975.7 3978.3 4020.7 4032.4 4429.3 4447.7 4450.8 4462.9 4477.9 4862.0 5022.3 5057.2 5082.7 5110.2 1328.3 1340.4 1350.2 1360.2 1361.7 928.1 968.5 973.5 985.4 989.6 4228.5 4247.9 4359.4 4368.9 4384.2 5160.8 5185.0 5198.9 5216.3 5237.5 4308.0 4349.6 4350.9 4410.4 4446.1 2581.7 2676.9 2693.4 2699.2 2702.2 4694.0 4701.6 4714.9 4725.4 4740.9 5175.4 5209.4 5215.8 5223.7 5237.7 4582.8 4885.6 4924.7 4944.3 4961.7 5280.6 5301.2 5339.3 5343.9 5350.5 4969.3 5259.8 5283.5 5315.3 5329.3 4808.5 4828.9 4867.5 4875.3 4895.9 3962.3 3986.0 4005.4 4006.7 4009.5 5054.5 5191.4 5199.5 5272.7 5272.9 4999.5 5023.6 5028.9 5060.1 5062.6 5028.2 5033.2 5037.5 5045.0 5060.4 4822.4 4981.9 4987.1 4996.2 5020.0 4917.8 5024.7 5061.2 5130.7 5139.7 4868.2 4972.2 4973.7 4973.9 4988.4 21:48:28 INFO - PROCESS | 30367 | 21:48:28 INFO - PROCESS | 30367 | [#5] yui.html Cycles:180 Average:14719.71 Median:22302.28 stddev:18236.64 (81.8%) stddev-sans-first:18257.15 21:48:28 INFO - PROCESS | 30367 | Values: 638.2 655.4 709.2 716.9 725.4 11711.2 12000.8 12030.1 12032.5 12065.6 12724.0 12860.2 12907.9 12936.4 12941.6 658.8 678.6 796.5 805.4 813.4 827.3 831.3 832.0 832.1 832.1 876.2 877.9 878.6 879.0 879.3 691.8 710.6 713.2 715.3 874.9 611.5 625.6 633.3 635.1 635.3 12782.0 12805.4 12842.4 12845.4 12870.8 11811.2 11860.3 11991.3 11994.6 12276.1 11622.9 11691.9 11711.9 11764.0 12223.3 4092.2 4114.9 4125.4 4152.1 4164.1 59156.4 60931.1 61760.7 61861.7 62558.1 9975.9 10168.3 10189.9 10261.9 10262.7 18938.6 18962.9 18985.5 18997.5 19025.7 6356.4 6370.7 6392.2 6404.9 6617.5 3365.4 3400.0 3409.9 3417.6 3419.5 2611.9 2671.4 2682.9 2685.2 2698.1 484.3 484.4 486.4 492.5 496.7 505.5 518.3 520.7 526.2 534.6 7199.7 7292.3 7314.9 7465.7 7537.3 502.6 514.3 515.1 525.5 527.6 3247.2 3617.1 3637.3 3737.2 3836.4 55538.7 65182.0 65855.3 68561.3 68807.0 49158.8 51747.7 59001.0 60432.7 60865.7 51500.2 53099.0 53120.5 53933.7 54158.5 6782.7 7076.9 7120.0 7203.6 7228.2 37204.4 38139.2 38453.8 38818.0 39096.4 39939.8 40512.4 42353.6 46412.8 46519.0 11232.7 11527.5 11795.4 11802.9 11821.6 12759.4 12897.4 12951.0 13309.7 13797.0 11179.8 11894.3 12186.1 12258.0 12395.8 11609.1 11702.1 12047.9 12235.4 12241.4 11696.4 12004.3 12006.6 12735.4 12765.6 10036.5 10187.2 10403.8 10584.5 10774.5 10196.0 10251.8 10269.6 10275.3 10288.5 21:48:28 INFO - PROCESS | 30367 | -------- Summary: end -------- 21:48:28 INFO - PROCESS | 30367 | 21:48:29 INFO - TEST-INFO | 30367: exit 0 21:48:29 INFO - TEST-OK | dromaeo_css | took 1121526ms 21:48:29 INFO - TEST-START | kraken 21:48:29 INFO - operating with platform_type : linux_ 21:48:29 INFO - Initialising browser for kraken test... 21:48:29 INFO - TEST-INFO | started process 30663 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp4ZiXvK/profile http://localhost:52616/getInfo.html) 21:48:29 INFO - PROCESS | 30663 | 1482904109905 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4986 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3827 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2831 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 21:48:33 INFO - PROCESS | 30663 | __metrics Screen width/height:1600/1200 21:48:33 INFO - PROCESS | 30663 | colorDepth:24 21:48:33 INFO - PROCESS | 30663 | Browser inner width/height: 1024/697 21:48:33 INFO - PROCESS | 30663 | __metrics 21:48:34 INFO - PROCESS | 30663 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 21:48:34 INFO - PROCESS | 30663 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 21:48:34 INFO - PROCESS | 30663 | console.error: PushService: 21:48:34 INFO - PROCESS | 30663 | stateChangeProcessEnqueue: Error transitioning state 21:48:34 INFO - PROCESS | 30663 | UnknownError 21:48:35 INFO - TEST-INFO | 30663: exit 0 21:48:35 INFO - Browser initialized. 21:48:35 INFO - Running cycle 1/1 for kraken test... 21:48:35 INFO - TEST-INFO | started process 30746 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp4ZiXvK/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:48:47 INFO - PROCESS | 30746 | RSS: Main: 150835200 21:48:47 INFO - PROCESS | 30746 | 21:48:50 INFO - PROCESS | 30746 | Cycle 1(1): loaded http://localhost:52616/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52616/tests/kraken/driver.html?testName=audio-beat-detection) 21:48:51 INFO - PROCESS | 30746 | RSS: Main: 205484032 21:48:51 INFO - PROCESS | 30746 | 21:48:53 INFO - PROCESS | 30746 | Cycle 1(1): loaded http://localhost:52616/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52616/tests/kraken/driver.html?testName=audio-dft) 21:48:54 INFO - PROCESS | 30746 | RSS: Main: 185323520 21:48:54 INFO - PROCESS | 30746 | 21:48:57 INFO - PROCESS | 30746 | Cycle 1(1): loaded http://localhost:52616/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52616/tests/kraken/driver.html?testName=audio-fft) 21:48:58 INFO - PROCESS | 30746 | RSS: Main: 267751424 21:48:58 INFO - PROCESS | 30746 | 21:49:00 INFO - PROCESS | 30746 | Cycle 1(1): loaded http://localhost:52616/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52616/tests/kraken/driver.html?testName=audio-oscillator) 21:49:01 INFO - PROCESS | 30746 | RSS: Main: 179081216 21:49:01 INFO - PROCESS | 30746 | 21:49:03 INFO - PROCESS | 30746 | Cycle 1(1): loaded http://localhost:52616/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52616/tests/kraken/driver.html?testName=imaging-gaussian-blur) 21:49:03 INFO - PROCESS | 30746 | RSS: Main: 182837248 21:49:03 INFO - PROCESS | 30746 | 21:49:07 INFO - PROCESS | 30746 | Cycle 1(1): loaded http://localhost:52616/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52616/tests/kraken/driver.html?testName=imaging-darkroom) 21:49:07 INFO - PROCESS | 30746 | RSS: Main: 220581888 21:49:07 INFO - PROCESS | 30746 | 21:49:11 INFO - PROCESS | 30746 | Cycle 1(1): loaded http://localhost:52616/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52616/tests/kraken/driver.html?testName=imaging-desaturate) 21:49:11 INFO - PROCESS | 30746 | RSS: Main: 215654400 21:49:11 INFO - PROCESS | 30746 | 21:49:14 INFO - PROCESS | 30746 | Cycle 1(1): loaded http://localhost:52616/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52616/tests/kraken/driver.html?testName=json-parse-financial) 21:49:15 INFO - PROCESS | 30746 | RSS: Main: 217235456 21:49:15 INFO - PROCESS | 30746 | 21:49:17 INFO - PROCESS | 30746 | Cycle 1(1): loaded http://localhost:52616/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52616/tests/kraken/driver.html?testName=json-stringify-tinderbox) 21:49:17 INFO - PROCESS | 30746 | RSS: Main: 180531200 21:49:17 INFO - PROCESS | 30746 | 21:49:19 INFO - PROCESS | 30746 | Cycle 1(1): loaded http://localhost:52616/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52616/tests/kraken/driver.html?testName=stanford-crypto-aes) 21:49:20 INFO - PROCESS | 30746 | RSS: Main: 188674048 21:49:20 INFO - PROCESS | 30746 | 21:49:22 INFO - PROCESS | 30746 | Cycle 1(1): loaded http://localhost:52616/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52616/tests/kraken/driver.html?testName=stanford-crypto-ccm) 21:49:22 INFO - PROCESS | 30746 | RSS: Main: 196681728 21:49:22 INFO - PROCESS | 30746 | 21:49:25 INFO - PROCESS | 30746 | Cycle 1(1): loaded http://localhost:52616/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52616/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 21:49:26 INFO - PROCESS | 30746 | RSS: Main: 206143488 21:49:26 INFO - PROCESS | 30746 | 21:49:29 INFO - PROCESS | 30746 | Cycle 1(1): loaded http://localhost:52616/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52616/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 21:49:30 INFO - PROCESS | 30746 | RSS: Main: 202031104 21:49:30 INFO - PROCESS | 30746 | 21:49:31 INFO - PROCESS | 30746 | Cycle 1(1): loaded http://localhost:52616/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52616/tests/kraken/driver.html?testName=ai-astar) 21:49:32 INFO - PROCESS | 30746 | RSS: Main: 253775872 21:49:32 INFO - PROCESS | 30746 | 21:49:32 INFO - PROCESS | 30746 | __start_tp_report 21:49:32 INFO - PROCESS | 30746 | _x_x_mozilla_page_load 21:49:32 INFO - PROCESS | 30746 | _x_x_mozilla_page_load_details 21:49:32 INFO - PROCESS | 30746 | |i|pagename|runs| 21:49:32 INFO - PROCESS | 30746 | |0;ai-astar;111;118;116;116;119;114;115;115;115;117 21:49:32 INFO - PROCESS | 30746 | |1;audio-beat-detection;154;124;125;159;126;126;127;130;152;125 21:49:32 INFO - PROCESS | 30746 | |2;audio-dft;160;161;162;162;162;162;162;162;161;160 21:49:32 INFO - PROCESS | 30746 | |3;audio-fft;76;77;75;110;78;76;76;75;90;76 21:49:32 INFO - PROCESS | 30746 | |4;audio-oscillator;84;85;118;84;85;84;96;85;84;118 21:49:32 INFO - PROCESS | 30746 | |5;imaging-gaussian-blur;117;117;117;117;117;117;118;117;117;118 21:49:32 INFO - PROCESS | 30746 | |6;imaging-darkroom;149;149;150;148;149;150;149;148;149;148 21:49:32 INFO - PROCESS | 30746 | |7;imaging-desaturate;95;96;96;96;95;95;95;96;95;95 21:49:32 INFO - PROCESS | 30746 | |8;json-parse-financial;67;64;65;67;65;66;66;65;66;67 21:49:32 INFO - PROCESS | 30746 | |9;json-stringify-tinderbox;68;69;67;68;68;68;69;68;68;68 21:49:32 INFO - PROCESS | 30746 | |10;stanford-crypto-aes;86;85;86;85;86;85;85;91;91;85 21:49:32 INFO - PROCESS | 30746 | |11;stanford-crypto-ccm;135;146;143;136;133;137;144;132;191;130 21:49:32 INFO - PROCESS | 30746 | |12;stanford-crypto-pbkdf2;176;175;178;175;176;173;178;174;172;182 21:49:32 INFO - PROCESS | 30746 | |13;stanford-crypto-sha256-iterative;70;75;68;68;67;69;66;66;67;66 21:49:32 INFO - PROCESS | 30746 | __end_tp_report 21:49:32 INFO - PROCESS | 30746 | __start_cc_report 21:49:32 INFO - PROCESS | 30746 | _x_x_mozilla_cycle_collect,1835 21:49:32 INFO - PROCESS | 30746 | __end_cc_report 21:49:32 INFO - PROCESS | 30746 | __startTimestamp1482904172172__endTimestamp 21:49:32 INFO - PROCESS | 30746 | 21:49:32 INFO - PROCESS | 30746 | ------- Summary: start ------- 21:49:32 INFO - PROCESS | 30746 | Number of tests: 14 21:49:32 INFO - PROCESS | 30746 | 21:49:32 INFO - PROCESS | 30746 | [#0] ai-astar Cycles:10 Average:115.60 Median:116.00 stddev:2.22 (1.9%) stddev-sans-first:1.62 21:49:32 INFO - PROCESS | 30746 | Values: 111.0 118.0 116.0 116.0 119.0 114.0 115.0 115.0 115.0 117.0 21:49:32 INFO - PROCESS | 30746 | 21:49:32 INFO - PROCESS | 30746 | [#1] audio-beat-detection Cycles:10 Average:134.80 Median:128.50 stddev:14.13 (11.0%) stddev-sans-first:13.17 21:49:32 INFO - PROCESS | 30746 | Values: 154.0 124.0 125.0 159.0 126.0 126.0 127.0 130.0 152.0 125.0 21:49:32 INFO - PROCESS | 30746 | 21:49:32 INFO - PROCESS | 30746 | [#2] audio-dft Cycles:10 Average:161.40 Median:162.00 stddev:0.84 (0.5%) stddev-sans-first:0.73 21:49:32 INFO - PROCESS | 30746 | Values: 160.0 161.0 162.0 162.0 162.0 162.0 162.0 162.0 161.0 160.0 21:49:32 INFO - PROCESS | 30746 | 21:49:32 INFO - PROCESS | 30746 | [#3] audio-fft Cycles:10 Average:80.90 Median:76.00 stddev:11.15 (14.7%) stddev-sans-first:11.68 21:49:32 INFO - PROCESS | 30746 | Values: 76.0 77.0 75.0 110.0 78.0 76.0 76.0 75.0 90.0 76.0 21:49:32 INFO - PROCESS | 30746 | 21:49:32 INFO - PROCESS | 30746 | [#4] audio-oscillator Cycles:10 Average:92.30 Median:84.50 stddev:14.02 (16.6%) stddev-sans-first:14.55 21:49:32 INFO - PROCESS | 30746 | Values: 84.0 85.0 118.0 84.0 85.0 84.0 96.0 85.0 84.0 118.0 21:49:32 INFO - PROCESS | 30746 | 21:49:32 INFO - PROCESS | 30746 | [#5] imaging-gaussian-blur Cycles:10 Average:117.20 Median:117.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 21:49:32 INFO - PROCESS | 30746 | Values: 117.0 117.0 117.0 117.0 117.0 117.0 118.0 117.0 117.0 118.0 21:49:32 INFO - PROCESS | 30746 | 21:49:32 INFO - PROCESS | 30746 | [#6] imaging-darkroom Cycles:10 Average:148.90 Median:149.00 stddev:0.74 (0.5%) stddev-sans-first:0.78 21:49:32 INFO - PROCESS | 30746 | Values: 149.0 149.0 150.0 148.0 149.0 150.0 149.0 148.0 149.0 148.0 21:49:32 INFO - PROCESS | 30746 | 21:49:32 INFO - PROCESS | 30746 | [#7] imaging-desaturate Cycles:10 Average:95.40 Median:95.50 stddev:0.52 (0.5%) stddev-sans-first:0.53 21:49:32 INFO - PROCESS | 30746 | Values: 95.0 96.0 96.0 96.0 95.0 95.0 95.0 96.0 95.0 95.0 21:49:32 INFO - PROCESS | 30746 | 21:49:32 INFO - PROCESS | 30746 | [#8] json-parse-financial Cycles:10 Average:65.80 Median:66.00 stddev:1.03 (1.6%) stddev-sans-first:1.00 21:49:32 INFO - PROCESS | 30746 | Values: 67.0 64.0 65.0 67.0 65.0 66.0 66.0 65.0 66.0 67.0 21:49:32 INFO - PROCESS | 30746 | 21:49:32 INFO - PROCESS | 30746 | [#9] json-stringify-tinderbox Cycles:10 Average:68.10 Median:68.00 stddev:0.57 (0.8%) stddev-sans-first:0.60 21:49:32 INFO - PROCESS | 30746 | Values: 68.0 69.0 67.0 68.0 68.0 68.0 69.0 68.0 68.0 68.0 21:49:32 INFO - PROCESS | 30746 | 21:49:32 INFO - PROCESS | 30746 | [#10] stanford-crypto-aes Cycles:10 Average:86.50 Median:86.00 stddev:2.42 (2.8%) stddev-sans-first:2.55 21:49:32 INFO - PROCESS | 30746 | Values: 86.0 85.0 86.0 85.0 86.0 85.0 85.0 91.0 91.0 85.0 21:49:32 INFO - PROCESS | 30746 | 21:49:32 INFO - PROCESS | 30746 | [#11] stanford-crypto-ccm Cycles:10 Average:142.70 Median:140.00 stddev:17.80 (12.7%) stddev-sans-first:18.66 21:49:32 INFO - PROCESS | 30746 | Values: 135.0 146.0 143.0 136.0 133.0 137.0 144.0 132.0 191.0 130.0 21:49:32 INFO - PROCESS | 30746 | 21:49:32 INFO - PROCESS | 30746 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.90 Median:176.00 stddev:2.88 (1.6%) stddev-sans-first:3.06 21:49:32 INFO - PROCESS | 30746 | Values: 176.0 175.0 178.0 175.0 176.0 173.0 178.0 174.0 172.0 182.0 21:49:32 INFO - PROCESS | 30746 | 21:49:32 INFO - PROCESS | 30746 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.20 Median:68.00 stddev:2.74 (4.0%) stddev-sans-first:2.83 21:49:32 INFO - PROCESS | 30746 | Values: 70.0 75.0 68.0 68.0 67.0 69.0 66.0 66.0 67.0 66.0 21:49:32 INFO - PROCESS | 30746 | -------- Summary: end -------- 21:49:32 INFO - PROCESS | 30746 | 21:49:33 INFO - TEST-INFO | 30746: exit 0 21:49:33 INFO - TEST-OK | kraken | took 64001ms 21:49:33 INFO - SUITE-END | took 1185s 21:49:33 INFO - Completed test suite (00:19:45) 21:49:33 INFO - javascript benchmark 21:49:33 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2543.605741110128, 2563.5898256278997, 2574.961375579366, 2594.0, 2600.1029644772557, 4644.326572646964, 4682.0, 4780.928286075708, 4815.951840481598, 4834.540718631729, 4722.362481065055, 4745.0, 4760.8095676173, 4817.301491283762, 4859.076775412669, 4347.60871521563, 4364.847230346939, 4397.626201772853, 4411.669124815636, 4430.401895744066, 4043.615856493643, 4451.88870278244, 4482.0, 4522.7512486813375, 4559.9772001139945, 4293.506246781629, 4439.889002774924, 4477.552244775497, 4480.283154695249, 4565.7717114144325, 4316.848910288125, 4327.870163895088, 4349.434573505456, 4368.672349573794, 4374.103308821684, 4351.8476853309985, 4354.433923589913, 4367.170237654836, 4394.340848872663, 4419.690621656486, 4713.552212539803, 4714.811407543694, 4715.952840471586, 4723.527647235283, 4737.147313483571, 4965.652404331699, 4999.675021123615, 5033.899322013539, 5061.038402703512, 5078.542931136179, 5109.259157422191, 5111.361079865017, 5115.104856650109, 5168.689878607295, 5176.482351764831, 4781.784819683123, 4783.569478746929, 4804.95195048052, 4806.4712881583, 4832.081904438181, 5140.563052140536, 5170.276161337416, 5192.584593232532, 5200.375954885438, 5238.5547228486, 5012.097822392005, 5015.874603134951, 5017.448080711119, 5032.84901452957, 5066.569341605931, 5008.624353173526, 5033.043721692867, 5071.87320316995, 5088.287639730441, 5116.2069879168785, 5052.722100284446, 5061.493850614909, 5062.620303477254, 5062.9746851266245, 5077.339945807022, 5015.423226328946, 5016.799328026911, 5062.7468626568525, 5069.746512674352, 5078.212877003996, 4280.764557949343, 4302.419173411549, 4310.50429200646, 4338.544452832471, 4347.0, 3755.7183211259266, 3774.1130834253927, 3790.8104594770703, 3798.9430158547643, 3843.0392401899526, 4337.631301339421, 4338.696291259582, 4339.414179085783, 4348.065165989265, 4386.539413361551, 5001.099802035668, 5039.874003149951, 5090.669106508065, 5092.592592592584, 5103.979204159183, 4303.053328267776, 4328.653707703376, 4331.545187755303, 4333.414988976448, 4339.218940590661, 4572.428446444194, 4573.679842410999, 4578.198815207325, 4581.2440947243995, 4582.839600613963, 4706.599939005155, 4709.670323077352, 4711.081339138835, 4716.292556116541, 4718.292256161603, 5098.107831129611, 5103.974480127724, 5104.821331253388, 5105.081085404663, 5106.387233532, 4726.929096063493, 4734.715917044989, 4760.833370832004, 4787.712737235777, 4837.153498137742, 5099.23511473282, 5103.515166059218, 5104.795808167632, 5113.616478764033, 5155.046316431472, 5185.170372740344, 5192.688438693691, 5194.1429664105, 5194.68831870089, 5196.298499702643, 4854.441739200037, 4899.04468628614, 4927.975360123177, 4937.802487900443, 4969.701817890938, 4984.42679091909, 4986.12742770021, 4989.101961646938, 4991.301217829434, 5021.246812978083, 4941.160002799461, 4941.728204948762, 4948.307236986897, 4953.950460495493, 4987.226979818134, 4862.270659401118, 4895.143349913822, 4980.850574482772, 4994.9500504994485, 5008.524190201925, 4944.802207911644, 4954.182559877724, 4958.057968985881, 4964.05682920244, 4998.825041123689], "value": 4671.382996698604, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [534.4521865088285, 545.888092940947, 551.2943432406519, 561.663241485265, 564.2777245126238, 29893.850530747342, 30637.387252254997, 30650.69349306509, 30660.38679226417, 30665.080047598552, 30808.229794255156, 38018.4297235541, 39686.60313396865, 39746.403803942965, 43400.3489947651, 7661.655225514851, 7793.688252469894, 7802.063752349726, 7809.258120478546, 7809.648565814524, 7610.543367397946, 7701.037370328709, 7703.8844417333785, 7706.922930770705, 7709.575973321465, 7564.810879727996, 7762.45662803604, 9901.801963960717, 10194.184465242799, 10205.081542661159, 10113.089821916026, 10143.746406339827, 10156.187504999582, 10166.288359814816, 10196.541155648014, 9912.603495860158, 9977.80044399108, 9995.950020249926, 10031.398116113056, 10036.498175091216, 36073.81963090168, 36376.27247455036, 36403.0, 36448.27103457916, 36448.81775591105, 3483.9129021774406, 3489.94765078524, 3522.6829585337186, 3534.7348948828685, 3557.3596752584526, 28466.857665711745, 28495.0, 28564.14307570776, 28573.142805715863, 29241.268968275752, 3357.3788849063003, 3374.3588718143474, 3395.439752440844, 3402.3705614461405, 3430.5025771263277, 175108.99999999872, 175271.0, 176207.11896440564, 176293.1185344065, 176703.0, 50327.748361258695, 50573.24140137901, 50634.74682626563, 50746.49253507492, 50769.49230507648, 53099.75201983832, 53134.20298695523, 53271.46728532665, 53337.466625334026, 53473.1979020315, 12257.816132758016, 12595.433205505775, 12600.495980160875, 12640.6839829005, 12668.873311266954, 9166.633334666672, 9283.675071372605, 9304.302177336727, 9334.393264437926, 9380.718578442658, 13777.588921938821, 14073.577792666236, 14946.028508147154, 15475.148866812207, 15544.378224870878, 6307.684615769285, 6489.610623362613, 6550.115734375893, 6561.540692151503, 6573.112629794917, 711.9822004449827, 714.1358955663601, 716.8781307177688, 731.9853602927911, 734.7024455095694, 26695.599566006524, 27783.166505004883, 28132.0, 28244.43511129766, 28247.717522824507, 799.5442597719244, 812.7074253268819, 821.0762891746784, 821.2608652213056, 838.7399906029151, 13625.659358515919, 14311.499097531536, 14526.418943242152, 14640.414383424544, 15073.0, 326787.0, 329036.0, 330710.0, 330859.0, 330994.0, 43237.567624323354, 43395.34906976398, 43411.13177736427, 43413.0, 43429.0, 1183.958561450345, 1207.3600991474532, 1214.36245970866, 1221.5113954418125, 1225.583301677434, 24473.632895506227, 24613.75386246186, 25127.0, 25396.365090872878, 25478.363040924127, 35568.82215588906, 40504.59495405126, 41590.0, 46103.0, 46187.538124618324, 292761.0, 294435.0, 294990.0, 295011.0, 295366.0, 22905.31284061481, 23044.884775576014, 23364.299071027217, 23385.532289354458, 23421.531569368857, 32815.50776738303, 36569.26861462809, 36866.26267474582, 37654.811725942294, 40223.0, 31495.0, 31559.36881262316, 31591.526127108573, 31651.841740791147, 32877.50683739698, 31945.68054319427, 32302.676973229965, 32344.83827580847, 32560.674393255766, 32884.34231315312, 31502.36995260128, 31862.0, 31926.0, 32146.0, 32233.51649725209, 28300.575491368058, 28469.715302846707, 29215.269618259714, 29337.41325173442, 29910.700892991663, 21063.8946805265, 22310.54981426202, 22433.214837481264, 22672.0, 22674.886625566767], "value": 16404.096745655275, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [360.49170669356215, 361.8896236647823, 371.81966746128126, 380.3249232612113, 391.4324229866694, 12534.185277956927, 13376.130551514145, 13845.307734613267, 16490.0, 17122.82877171228, 16815.0, 17182.312707491685, 17241.827581724177, 17500.474985750447, 17503.474895753116, 4971.428285747149, 5269.499397557245, 5273.156294992802, 5275.103232450473, 5275.36695596529, 5129.051125541768, 5191.0656081905245, 5203.1414816555225, 5207.4792520748, 5208.2968799212, 5291.523762861341, 5302.628815982864, 5305.522502974732, 5339.652922560022, 5409.107497262967, 5313.229581710631, 5314.734263286839, 5322.2282768998275, 5349.946500535023, 5880.529557635379, 5274.077036518594, 5278.340207474066, 5285.471452854722, 5286.841394758163, 5286.973565132188, 11684.941575292154, 11846.0, 18555.628887422175, 19281.132349044186, 19386.4184074478, 2201.306588424641, 2248.5165689376863, 2309.1340747219792, 2385.2248019393764, 2408.9879550602136, 8820.206181443637, 9111.134442227978, 9345.345825792198, 9610.807783844353, 9613.663521776703, 1668.4911102113851, 1733.16807932192, 1737.0185844997536, 1755.920983555743, 1756.3677076252538, 59666.70166649183, 66428.66785666089, 67384.3261567383, 68014.65992670006, 69165.00854888883, 9478.90521094794, 9497.810043799085, 9671.90328096724, 9834.262430317754, 10173.186145108373, 13416.0, 13530.864691353157, 13533.323333833268, 13537.323133843269, 13553.0, 6166.260048794174, 6334.683265836782, 6486.35136486357, 6589.341065893373, 6593.934060659428, 5740.253767010262, 5842.620229685057, 5938.198343223609, 5948.583599148018, 5950.375210602911, 4150.398192262165, 4235.110626768414, 4259.8083086261195, 4380.211561918885, 4390.670699697535, 1662.8881320722614, 1735.7222844344844, 1736.5311365931127, 1737.7914650241798, 1759.7184450488116, 753.6533194730375, 760.5208718507305, 772.7758949904577, 777.9338756205786, 789.1713700614447, 15864.92067539655, 15866.603334916488, 16444.917775411046, 16543.91728041352, 16967.321307147577, 752.3003606645872, 770.1412924589043, 778.2956424435895, 778.4006315137312, 803.493798906685, 6124.846878828065, 6316.30520642729, 6322.557420980579, 6326.240851097898, 6548.101050695245, 182930.0, 183072.0, 296810.0, 349064.0, 349240.0, 71519.28480715126, 71772.0, 74715.62642186863, 74742.6262868693, 75237.24762752412, 910.5219759626117, 922.326701507903, 949.1410273702172, 949.4682977532603, 951.0727041134838, 10438.791224175626, 10504.212184086377, 10522.1056210223, 10552.155827533625, 10568.207384446043, 33819.66180338165, 34013.82993085019, 34067.48898766471, 35203.64796352004, 35782.82108589545, 45246.321305179794, 47576.762116189195, 48825.0, 48845.51154488552, 48858.75570622124, 6383.170187875547, 6949.235584085847, 7711.768646940601, 7715.807104822425, 8176.754697359089, 8634.925072132635, 11851.52593896269, 12101.455434505644, 12183.390830458264, 12354.814677779661, 9378.109079637423, 9573.234141268822, 9587.280953928344, 9955.601775928882, 10257.692269231935, 9661.082197191256, 10029.498525073863, 10112.84830727525, 10235.181185504993, 10719.785604287714, 9488.857667135137, 9784.9021509784, 9803.16673082796, 10170.542325595217, 10214.285000049926, 6660.1615007020455, 6771.3905748482875, 6829.863402732017, 6912.516123871281, 7136.250693677089, 9084.818303633758, 9707.417554946725, 9782.755431113994, 9824.852627210455, 10018.098371146632], "value": 7646.7376376009615, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1255.046164914665, 1287.8905293050095, 1291.0962326371541, 1299.0322209953586, 1306.947722091116, 6272.153259309992, 6550.96724516378, 7022.157341119067, 7450.329470347668, 7454.776356709301, 6968.8257793555185, 7255.0, 7256.020437240971, 7438.516496427742, 7445.739399121032, 2016.3950814755551, 2042.2647846775149, 2050.41563154501, 2050.641137800879, 2052.2509284111266, 1746.3451205797826, 1806.4219449776076, 2119.607872543577, 2167.111484291441, 2170.7612162662094, 1953.4627977306213, 2031.7054027166052, 2032.19728207358, 2034.745656792901, 2050.712900193974, 2135.29535253366, 2149.591577600259, 2167.6531754919138, 2175.7824217578273, 2178.7930146636045, 2099.191811152702, 2124.8937553122437, 2158.093600687715, 2158.8380871434706, 2161.4164175672636, 7260.45546584003, 7412.925870741331, 7426.851462970764, 7435.03344565203, 7459.291367320096, 4342.109867477159, 4442.2226110430865, 4465.441819772528, 4467.3299005149165, 4469.08383781324, 2800.0759749282893, 2840.2757296889367, 2895.739383455478, 2898.565215217713, 2909.8545072746488, 1074.9355038697624, 1103.0238239158402, 1110.6279396402215, 1125.0999200639455, 1177.864545577261, 9485.952570237174, 9521.047895210493, 9572.282078844115, 9577.904220957771, 9578.473183974878, 3722.5532936047307, 3887.8444862205197, 3937.940930886039, 3995.1011022520283, 4015.5382131054716, 7806.57063861493, 7808.843823123506, 7809.531428114331, 7823.608819558999, 7834.255745704149, 4301.0537681709975, 4322.524522302544, 4330.133973205371, 4363.47638283408, 4365.497967733688, 3950.644442000234, 4033.4756481657196, 4037.636612704812, 4040.0, 4044.6966477513715, 3247.9350412991607, 3248.5381578289393, 3326.6340702522702, 3350.6146793118614, 3356.865725370958, 894.3247847874812, 906.3881879731155, 990.346371394876, 992.3549692699803, 1004.2769206171545, 588.488015426573, 711.7793484019867, 743.086004214808, 744.2222877093342, 748.9700411983569, 4461.397711308997, 4468.195724769507, 4503.189425903369, 4542.454905411372, 4556.225441674923, 816.6610856494607, 819.2053707903324, 826.6941231744294, 827.2265431821266, 850.2050582705191, 2941.7453099197255, 3012.819230846156, 3041.8783248670247, 3077.4305104396917, 3117.9064628061196, 8355.665773369119, 8558.101399353103, 8670.86993695095, 8796.384253102222, 8898.154675305836, 8789.077146899612, 8850.955745221232, 8873.689420870238, 8875.822483550293, 8879.689210877717, 6849.965750171217, 6853.040574319599, 6920.515563910579, 6966.721331146799, 6982.755603553953, 4756.381670382828, 4809.302651115639, 4829.806807727792, 4897.045076210247, 4908.092002979461, 7986.7603971880935, 8018.4787988780545, 8028.718994835152, 8039.718609848627, 8116.066652335057, 8083.666195077746, 8259.876101858357, 8294.129116442688, 8367.079621241537, 8375.539345336065, 5568.081266590967, 5629.127485239795, 5669.716514174357, 5688.203651488712, 5688.800891968761, 6795.422389096982, 6842.7605033823575, 6849.623270719959, 6859.828504287433, 6874.484413668894, 6250.1874756281395, 6340.524460665376, 6359.109724638647, 6360.872782544416, 6480.449161821298, 5968.46283834457, 6023.216981792339, 6104.481119104748, 6144.078388241799, 6229.626222426669, 5445.428230035789, 5541.058020136506, 5543.667379957215, 5544.556435485232, 5549.639273447213, 4069.143493213944, 4082.612151845689, 4197.139586384723, 4235.682323825664, 4282.78586070689, 4146.813393397365, 4200.831966721297, 4320.4383430153875, 4423.181711383404, 4481.282994720829], "value": 3815.6762774157814, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [345.9878904238352, 352.65616024376226, 354.25959744134764, 355.9466080087988, 359.7391890879113, 4797.976010119949, 4877.634177436693, 4887.633427492943, 4908.263760435936, 4918.803247870081, 4713.434387873452, 4799.232122860343, 4840.225563909775, 4843.903121937568, 4852.29641701953, 3674.6325367463173, 3789.7536660117003, 3844.211936553014, 3870.4194370844457, 3880.6701430378453, 3918.804059797013, 3923.431102490137, 3938.586448422903, 3949.5063117110362, 3966.968588167083, 3858.9807050964705, 3863.0535518797933, 3878.825452854614, 3884.106655469244, 3897.941530877039, 3746.943795843051, 3824.464574959508, 3879.35990561557, 3912.7456715313415, 3917.157811070605, 3466.722662187044, 3488.8081155536433, 3507.5615548056494, 3508.7543871928974, 3515.4199557073307, 4695.906081878377, 4704.835330763407, 4708.952910470885, 4712.410948631421, 4773.570378665902, 4329.740215587075, 4461.866144015685, 4462.286034234539, 4464.620507256855, 4473.150101480741, 5028.647994640377, 5048.318477005594, 5061.569766569846, 5063.924041139349, 5084.9745751271375, 3842.634949679776, 3845.384738441836, 3848.6536211741077, 3860.1700634363465, 3861.7489863158803, 5064.442911279756, 5226.320578324793, 5259.5529380002745, 5298.735063246861, 5314.654547454403, 3922.4704664870446, 3924.978636002461, 3935.2326296371934, 3969.54350249725, 3993.2612466693754, 3967.4048892666333, 3975.6819454444153, 3978.3435733104293, 4020.718549701493, 4032.354823228269, 4429.313456414261, 4447.73313601185, 4450.821967121344, 4462.866114016585, 4477.932831007537, 4861.95138048615, 5022.347094877642, 5057.165567681365, 5082.695038297714, 5110.156824124052, 1328.34247047712, 1340.443715857908, 1350.2371160294401, 1360.1567028442232, 1361.6868120332379, 928.0765638190044, 968.4528241543505, 973.4597298499259, 985.3742873275378, 989.5547003848297, 4228.471441069866, 4247.893802654959, 4359.38968544404, 4368.934465983013, 4384.188925048812, 5160.767765450564, 5185.0, 5198.870028249248, 5216.26972223889, 5237.5286224239235, 4308.030693094029, 4349.608535231844, 4350.869473915787, 4410.404595379583, 4446.1330040642415, 2581.690197176351, 2676.892924283007, 2693.393986353094, 2699.2442116207494, 2702.2433718558837, 4693.967327188013, 4701.576858082789, 4714.858554243378, 4725.40932383452, 4740.885891815489, 5175.404828444702, 5209.426963034063, 5215.843524694265, 5223.738813059408, 5237.738113094406, 4582.770861456914, 4885.60915126789, 4924.704517728947, 4944.28307895346, 4961.727105009187, 5280.6303558750515, 5301.204819277139, 5339.3058902342445, 5343.9465605345, 5350.464953504618, 4969.304297398295, 5259.815906443256, 5283.524482796567, 5315.335583052119, 5329.333833270841, 4808.495108013608, 4828.937633720576, 4867.5132486751045, 4875.26927940576, 4895.853124406273, 3962.2867883781196, 3986.0304073465454, 4005.439238506553, 4006.719529633014, 4009.4787677601726, 5054.469280725618, 5191.377034755854, 5199.4540573239265, 5272.657277276965, 5272.894542109213, 4999.525045120708, 5023.648344615842, 5028.899422011613, 5060.139776238122, 5062.594992400525, 5028.245763135413, 5033.169527028, 5037.5214354636255, 5045.0, 5060.443351231435, 4822.373091498082, 4981.925271120864, 4987.0524600325825, 4996.15065438869, 5020.0, 4917.803287868445, 5024.71375524143, 5061.190209566452, 5130.666506677054, 5139.665921715076, 4868.17241069012, 4972.179590367549, 4973.651844370859, 4973.875653108556, 4988.401391833003], "value": 3893.3414114334246, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [638.1735652330232, 655.4395991427333, 709.1808960650451, 716.878130717778, 725.3567246819741, 11711.180217384786, 12000.75998480031, 12030.097742669313, 12032.458539365749, 12065.638030859078, 12724.0, 12860.164089334186, 12907.870921290783, 12936.417861196245, 12941.611751647442, 658.7661380210028, 678.5724993254242, 796.5061661769711, 805.4040010392297, 813.3574476163824, 827.3381294964034, 831.3266254333979, 832.01406333495, 832.1221111727125, 832.142892820396, 876.1501343696636, 877.9253763430085, 878.6221924572432, 879.0286733159862, 879.3141349747208, 691.802836191688, 710.594960872303, 713.1584730018576, 715.2632788228134, 874.9256313213384, 611.5138464920354, 625.5933643131955, 633.3033662970695, 635.0696230023038, 635.3170341882495, 12782.04134689902, 12805.359732013361, 12842.422091005927, 12845.421956012002, 12870.806937895939, 11811.173217874752, 11860.347680877549, 11991.28052316855, 11994.580189693319, 12276.140670153003, 11622.941885290604, 11691.94154029233, 11711.882881171165, 11764.000898853945, 12223.266604003788, 4092.1611069730634, 4114.91770164598, 4125.422440858282, 4152.086540960984, 4164.104717485725, 59156.40843591595, 60931.08603370953, 61760.691196544176, 61861.690691546704, 62558.06162907606, 9975.850362244571, 10168.28821982454, 10189.898101018895, 10261.94869025665, 10262.743431414274, 18938.62122757537, 18962.905185473985, 18985.525361865788, 18997.525061873563, 19025.714614280798, 6356.396142366468, 6370.713317900613, 6392.169018027626, 6404.871902561923, 6617.503687223478, 3365.4110530657035, 3399.9830000850334, 3409.8636054558037, 3417.572803399575, 3419.452887537982, 2611.9085831996167, 2671.3722275265295, 2682.865856707157, 2685.154176434456, 2698.0691661376795, 484.3461327208241, 484.41144010027983, 486.409013049147, 492.48535280631825, 496.66226965662975, 505.5273319446329, 518.3313525552071, 520.6700017422006, 526.1528938409158, 534.6471328922893, 7199.748008819666, 7292.34368906801, 7314.926850731425, 7465.738699145504, 7537.321641052222, 502.6406119624486, 514.2954152810598, 515.052303761074, 525.4640266927712, 527.6359312074657, 3247.2044349134612, 3617.1138071172204, 3637.3452778499595, 3737.1965027519213, 3836.366999445116, 55538.72230638821, 65182.0, 65855.34144658588, 68561.3143868565, 68807.0, 49158.75420622874, 51747.741261294206, 59001.0, 60432.69783651141, 60865.69567152136, 51500.227496587584, 53099.0, 53120.4687953131, 53933.730331348095, 54158.45841541613, 6782.660866956731, 7076.929230707627, 7120.013465746499, 7203.639818008974, 7228.20489746138, 37204.44193337048, 38139.23721525609, 38453.80773096117, 38818.0, 39096.41355379615, 39939.80030099831, 40512.392314114724, 42353.57646423496, 46412.767936160104, 46519.0, 11232.66302010941, 11527.538898444303, 11795.41022948832, 11802.94098529502, 11821.586244481401, 12759.425825838045, 12897.419616117484, 12951.0, 13309.66725831862, 13796.965837170213, 11179.832302515306, 11894.345810980132, 12186.146969712036, 12258.019358451123, 12395.814062788884, 11609.129315301218, 11702.122340824642, 12047.879521204675, 12235.388230588613, 12241.449134788778, 11696.415179241174, 12004.339761313211, 12006.579769708018, 12735.426905789447, 12765.553205637758, 10036.49817509107, 10187.21201035512, 10403.843942341022, 10584.523696433833, 10774.515146818569, 10196.0, 10251.794964100825, 10269.640562580274, 10275.332103413255, 10288.485575721334], "value": 6193.145636309404, "unit": "score"}], "name": "dromaeo_css", "value": 6146.405037532628, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 118.0, 116.0, 116.0, 119.0, 114.0, 115.0, 115.0, 115.0, 117.0], "value": 115.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 124.0, 125.0, 159.0, 126.0, 126.0, 127.0, 130.0, 152.0, 125.0], "value": 134.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 161.0, 162.0, 162.0, 162.0, 162.0, 162.0, 162.0, 161.0, 160.0], "value": 161.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 77.0, 75.0, 110.0, 78.0, 76.0, 76.0, 75.0, 90.0, 76.0], "value": 80.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 118.0, 84.0, 85.0, 84.0, 96.0, 85.0, 84.0, 118.0], "value": 92.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 118.0, 117.0, 117.0, 118.0], "value": 117.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 149.0, 150.0, 148.0, 149.0, 150.0, 149.0, 148.0, 149.0, 148.0], "value": 148.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 96.0, 96.0, 95.0, 95.0, 95.0, 96.0, 95.0, 95.0], "value": 95.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 64.0, 65.0, 67.0, 65.0, 66.0, 66.0, 65.0, 66.0, 67.0], "value": 65.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 69.0, 67.0, 68.0, 68.0, 68.0, 69.0, 68.0, 68.0, 68.0], "value": 68.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 86.0, 85.0, 86.0, 85.0, 85.0, 91.0, 91.0, 85.0], "value": 86.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 146.0, 143.0, 136.0, 133.0, 137.0, 144.0, 132.0, 191.0, 130.0], "value": 142.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 175.0, 178.0, 175.0, 176.0, 173.0, 178.0, 174.0, 172.0, 182.0], "value": 175.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 75.0, 68.0, 68.0, 67.0, 69.0, 66.0, 66.0, 67.0, 66.0], "value": 68.2, "unit": "score"}], "name": "kraken", "value": 1553.7, "alertThreshold": 2.0}]} 21:49:33 INFO - Return code: 0 21:49:33 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 21:49:33 INFO - # TBPL SUCCESS # 21:49:33 INFO - Running post-action listener: _resource_record_post_action 21:49:33 INFO - [mozharness: 2016-12-28 05:49:33.953953Z] Finished run-tests step (success) 21:49:33 INFO - Running post-run listener: _resource_record_post_run 21:49:34 INFO - Total resource usage - Wall time: 1202s; CPU: 13.0%; Read bytes: 4096; Write bytes: 393306112; Read time: 12; Write time: 212428 21:49:34 INFO - TinderboxPrint: CPU usage
13.1% 21:49:34 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 21:49:34 INFO - TinderboxPrint: I/O write bytes / time
393,306,112 / 212,428 21:49:34 INFO - TinderboxPrint: CPU idle
8,339.4 (86.9%) 21:49:34 INFO - TinderboxPrint: CPU user
1,210.7 (12.6%) 21:49:34 INFO - TinderboxPrint: Swap in / out
0 / 0 21:49:34 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 110587904; Read time: 0; Write time: 56420 21:49:34 INFO - run-tests - Wall time: 1186s; CPU: 13.0%; Read bytes: 0; Write bytes: 281739264; Read time: 0; Write time: 155980 21:49:34 INFO - Running post-run listener: _upload_blobber_files 21:49:34 INFO - Blob upload gear active. 21:49:34 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 21:49:34 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 21:49:34 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-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 21:49:34 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-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 21:49:35 INFO - (blobuploader) - INFO - Open directory for files ... 21:49:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 21:49:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:49:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:49:35 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 21:49:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:49:35 INFO - (blobuploader) - INFO - Done attempting. 21:49:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 21:49:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:49:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:49:39 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 21:49:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:49:39 INFO - (blobuploader) - INFO - Done attempting. 21:49:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 21:49:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:49:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:49:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 21:49:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:49:40 INFO - (blobuploader) - INFO - Done attempting. 21:49:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 21:49:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:49:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:49:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 21:49:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:49:40 INFO - (blobuploader) - INFO - Done attempting. 21:49:40 INFO - (blobuploader) - INFO - Iteration through files over. 21:49:40 INFO - Return code: 0 21:49:40 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 21:49:40 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 21:49:40 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/54daccd8d79a8a9541236d42a5d4633ddceedceb208e511a167b11b36f745ecfa04d234442b56e3bfbf61b0bedc17513cf64b43139072fb8ef6d1d51cabb394f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5f2bbf615c899c69469e93140fcf9fd26f3566a45a7c9c3167fd485cfa075857958e6fe6465e823d322061ce79efe5734d015285fd69a4853c003dc9e0f390d9", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7a73f093fb7386e59ed7a9df3104a1b1c8d367768d792df6d36ec75046e60002fb9ddb7330d559a92aa8245c02a2506b45f7544d4d0bd53a29a75281ed9e446a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3ffc56f4c3c974d77462942745fdc0a4e6d4a25113705a399e2737740de302d21f2ba82c56c510aa535a7f4ba99a181f9fa96b76c543c9ea00b1cda282e6fc9f"} 21:49:40 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 21:49:40 INFO - Writing to file /builds/slave/test/properties/blobber_files 21:49:40 INFO - Contents: 21:49:40 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/54daccd8d79a8a9541236d42a5d4633ddceedceb208e511a167b11b36f745ecfa04d234442b56e3bfbf61b0bedc17513cf64b43139072fb8ef6d1d51cabb394f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5f2bbf615c899c69469e93140fcf9fd26f3566a45a7c9c3167fd485cfa075857958e6fe6465e823d322061ce79efe5734d015285fd69a4853c003dc9e0f390d9", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7a73f093fb7386e59ed7a9df3104a1b1c8d367768d792df6d36ec75046e60002fb9ddb7330d559a92aa8245c02a2506b45f7544d4d0bd53a29a75281ed9e446a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3ffc56f4c3c974d77462942745fdc0a4e6d4a25113705a399e2737740de302d21f2ba82c56c510aa535a7f4ba99a181f9fa96b76c543c9ea00b1cda282e6fc9f"} 21:49:40 INFO - Running post-run listener: copy_logs_to_upload_dir 21:49:40 INFO - Copying logs to upload dir... 21:49:40 INFO - mkdir: /builds/slave/test/build/upload/logs 21:49:40 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1247.067386 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2016-12-27 21:49:40.182280) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-27 21:49:40.185922) ========= 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=d2a8c569965ab8367867b893000001a1-1482899256.628074-202179440 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/54daccd8d79a8a9541236d42a5d4633ddceedceb208e511a167b11b36f745ecfa04d234442b56e3bfbf61b0bedc17513cf64b43139072fb8ef6d1d51cabb394f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5f2bbf615c899c69469e93140fcf9fd26f3566a45a7c9c3167fd485cfa075857958e6fe6465e823d322061ce79efe5734d015285fd69a4853c003dc9e0f390d9", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7a73f093fb7386e59ed7a9df3104a1b1c8d367768d792df6d36ec75046e60002fb9ddb7330d559a92aa8245c02a2506b45f7544d4d0bd53a29a75281ed9e446a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3ffc56f4c3c974d77462942745fdc0a4e6d4a25113705a399e2737740de302d21f2ba82c56c510aa535a7f4ba99a181f9fa96b76c543c9ea00b1cda282e6fc9f"} build_url:https://queue.taskcluster.net/v1/task/U2oKbSY9TNaJgz2uM0_NQA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011268 build_url: 'https://queue.taskcluster.net/v1/task/U2oKbSY9TNaJgz2uM0_NQA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/54daccd8d79a8a9541236d42a5d4633ddceedceb208e511a167b11b36f745ecfa04d234442b56e3bfbf61b0bedc17513cf64b43139072fb8ef6d1d51cabb394f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5f2bbf615c899c69469e93140fcf9fd26f3566a45a7c9c3167fd485cfa075857958e6fe6465e823d322061ce79efe5734d015285fd69a4853c003dc9e0f390d9", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7a73f093fb7386e59ed7a9df3104a1b1c8d367768d792df6d36ec75046e60002fb9ddb7330d559a92aa8245c02a2506b45f7544d4d0bd53a29a75281ed9e446a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3ffc56f4c3c974d77462942745fdc0a4e6d4a25113705a399e2737740de302d21f2ba82c56c510aa535a7f4ba99a181f9fa96b76c543c9ea00b1cda282e6fc9f"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-27 21:49:40.213734) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-27 21:49:40.214046) ========= 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=d2a8c569965ab8367867b893000001a1-1482899256.628074-202179440 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004563 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-27 21:49:40.299755) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-27 21:49:40.300088) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-27 21:49:40.300388) ========= ========= Total master_lag: 0.17 =========