builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-014 starttime: 1470559140.25 results: success (0) buildid: 20160807001753 builduid: 014ffd15a6a14b4e8c385e5aef36d2f2 revision: d42aacfe34af25e2f5110e2ca3d24a210eabeb33 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-07 01:39:00.246856) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-07 01:39:00.247359) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-07 01:39:00.247709) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-07 01:39:00.289368) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-07 01:39:00.289866) ========= 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=31acecf42b6a79ec49471d4a000001bb-1470550271.716546-1247145742 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-07 01:39:00-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 67.1M=0s 2016-08-07 01:39:00 (67.1 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.115631 ========= master_lag: 0.13 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-07 01:39:00.530727) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-07 01:39:00.531135) ========= 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=31acecf42b6a79ec49471d4a000001bb-1470550271.716546-1247145742 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020053 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-07 01:39:00.589369) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-07 01:39:00.589726) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev d42aacfe34af25e2f5110e2ca3d24a210eabeb33 --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 d42aacfe34af25e2f5110e2ca3d24a210eabeb33 --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=31acecf42b6a79ec49471d4a000001bb-1470550271.716546-1247145742 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-07 01:39:00,868 truncating revision to first 12 chars 2016-08-07 01:39:00,868 Setting DEBUG logging. 2016-08-07 01:39:00,868 attempt 1/10 2016-08-07 01:39:00,868 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/d42aacfe34af?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-07 01:39:01,640 unpacking tar archive at: mozilla-central-d42aacfe34af/testing/mozharness/ program finished with exit code 0 elapsedTime=1.104486 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-07 01:39:01.711295) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-07 01:39:01.711594) ========= script_repo_revision: d42aacfe34af25e2f5110e2ca3d24a210eabeb33 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-07 01:39:01.711983) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-07 01:39:01.712534) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-07 01:39:01.721432) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 36 secs) (at 2016-08-07 01:39:01.721699) ========= /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=31acecf42b6a79ec49471d4a000001bb-1470550271.716546-1247145742 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 01:39:02 INFO - MultiFileLogger online at 20160807 01:39:02 in /builds/slave/test 01:39:02 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 01:39:02 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 01:39:02 INFO - {'append_to_log': False, 01:39:02 INFO - 'base_work_dir': '/builds/slave/test', 01:39:02 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 01:39:02 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 01:39:02 INFO - 'branch': 'Firefox-Non-PGO', 01:39:02 INFO - 'buildbot_json_path': 'buildprops.json', 01:39:02 INFO - 'config_files': ('talos/linux_config.py',), 01:39:02 INFO - 'default_actions': ('clobber', 01:39:02 INFO - 'read-buildbot-config', 01:39:02 INFO - 'download-and-extract', 01:39:02 INFO - 'populate-webroot', 01:39:02 INFO - 'create-virtualenv', 01:39:02 INFO - 'install', 01:39:02 INFO - 'run-tests'), 01:39:02 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 01:39:02 INFO - 'download_minidump_stackwalk': True, 01:39:02 INFO - 'download_symbols': 'ondemand', 01:39:02 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 01:39:02 INFO - 'tooltool.py': '/tools/tooltool.py', 01:39:02 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 01:39:02 INFO - '/tools/misc-python/virtualenv.py')}, 01:39:02 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 01:39:02 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 01:39:02 INFO - 'installer_path': 'installer.exe', 01:39:02 INFO - 'log_level': 'info', 01:39:02 INFO - 'log_name': 'talos', 01:39:02 INFO - 'log_to_console': True, 01:39:02 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 01:39:02 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 01:39:02 INFO - 'opt_config_files': (), 01:39:02 INFO - 'pip_index': False, 01:39:02 INFO - 'sps_profile': False, 01:39:02 INFO - 'sps_profile_interval': 0, 01:39:02 INFO - 'suite': 'dromaeojs', 01:39:02 INFO - 'system_bits': '32', 01:39:02 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 01:39:02 INFO - 'title': 'talos-linux64-ix-014', 01:39:02 INFO - 'tooltool_cache': '/builds/tooltool_cache', 01:39:02 INFO - 'use_talos_json': True, 01:39:02 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 01:39:02 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 01:39:02 INFO - 'work_dir': 'build'} 01:39:02 INFO - [mozharness: 2016-08-07 08:39:02.083790Z] Running clobber step. 01:39:02 INFO - Running pre-action listener: _resource_record_pre_action 01:39:02 INFO - Running main action method: clobber 01:39:02 INFO - rmtree: /builds/slave/test/build 01:39:02 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 01:39:02 INFO - Running post-action listener: _resource_record_post_action 01:39:02 INFO - [mozharness: 2016-08-07 08:39:02.536965Z] Finished clobber step (success) 01:39:02 INFO - [mozharness: 2016-08-07 08:39:02.537067Z] Running read-buildbot-config step. 01:39:02 INFO - Running pre-action listener: _resource_record_pre_action 01:39:02 INFO - Running main action method: read_buildbot_config 01:39:02 INFO - Using buildbot properties: 01:39:02 INFO - { 01:39:02 INFO - "project": "", 01:39:02 INFO - "product": "firefox", 01:39:02 INFO - "script_repo_revision": "production", 01:39:02 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 01:39:02 INFO - "repository": "", 01:39:02 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 01:39:02 INFO - "buildid": "20160807001753", 01:39:02 INFO - "pgo_build": "False", 01:39:02 INFO - "basedir": "/builds/slave/test", 01:39:02 INFO - "buildnumber": 175, 01:39:02 INFO - "slavename": "talos-linux64-ix-014", 01:39:02 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 01:39:02 INFO - "platform": "ubuntu64_hw", 01:39:02 INFO - "scheduler": "tests-mozilla-central-linux64-talos", 01:39:02 INFO - "branch": "mozilla-central", 01:39:02 INFO - "repo_path": "mozilla-central", 01:39:02 INFO - "revision": "d42aacfe34af25e2f5110e2ca3d24a210eabeb33", 01:39:02 INFO - "stage_platform": "linux64", 01:39:02 INFO - "builduid": "014ffd15a6a14b4e8c385e5aef36d2f2", 01:39:02 INFO - "slavebuilddir": "test" 01:39:02 INFO - } 01:39:02 INFO - Found installer url https://queue.taskcluster.net/v1/task/cfzoeDjPRu6yBZ4FEc73hw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 01:39:02 INFO - Running post-action listener: _resource_record_post_action 01:39:02 INFO - [mozharness: 2016-08-07 08:39:02.539536Z] Finished read-buildbot-config step (success) 01:39:02 INFO - [mozharness: 2016-08-07 08:39:02.539626Z] Running download-and-extract step. 01:39:02 INFO - Running pre-action listener: _resource_record_pre_action 01:39:02 INFO - Running main action method: download_and_extract 01:39:02 INFO - mkdir: /builds/slave/test/build/tests 01:39:02 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')]} 01:39:02 INFO - trying https://queue.taskcluster.net/v1/task/cfzoeDjPRu6yBZ4FEc73hw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 01:39:02 INFO - Downloading https://queue.taskcluster.net/v1/task/cfzoeDjPRu6yBZ4FEc73hw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 01:39:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cfzoeDjPRu6yBZ4FEc73hw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 01:39:03 INFO - Downloaded 1361 bytes. 01:39:03 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 01:39:03 INFO - Using the following test package requirements: 01:39:03 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 01:39:03 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 01:39:03 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 01:39:03 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 01:39:03 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 01:39:03 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 01:39:03 INFO - u'jsshell-linux-x86_64.zip'], 01:39:03 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 01:39:03 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 01:39:03 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 01:39:03 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 01:39:03 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 01:39:03 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 01:39:03 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 01:39:03 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 01:39:03 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 01:39:03 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 01:39:03 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 01:39:03 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 01:39:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:39:03 INFO - trying https://queue.taskcluster.net/v1/task/cfzoeDjPRu6yBZ4FEc73hw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 01:39:03 INFO - Downloading https://queue.taskcluster.net/v1/task/cfzoeDjPRu6yBZ4FEc73hw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 01:39:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cfzoeDjPRu6yBZ4FEc73hw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 01:39:04 INFO - Downloaded 20257706 bytes. 01:39:04 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 01:39:04 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 01:39:05 INFO - Return code: 0 01:39:05 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 01:39:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:39:05 INFO - trying https://queue.taskcluster.net/v1/task/cfzoeDjPRu6yBZ4FEc73hw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 01:39:05 INFO - Downloading https://queue.taskcluster.net/v1/task/cfzoeDjPRu6yBZ4FEc73hw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 01:39:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cfzoeDjPRu6yBZ4FEc73hw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 01:39:06 INFO - Downloaded 20257706 bytes. 01:39:06 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 01:39:06 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 01:39:07 INFO - Return code: 0 01:39:07 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')]} 01:39:07 INFO - trying https://queue.taskcluster.net/v1/task/cfzoeDjPRu6yBZ4FEc73hw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 01:39:07 INFO - Downloading https://queue.taskcluster.net/v1/task/cfzoeDjPRu6yBZ4FEc73hw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 01:39:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cfzoeDjPRu6yBZ4FEc73hw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 01:39:08 INFO - Downloaded 14413723 bytes. 01:39:08 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 01:39:08 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 01:39:08 INFO - Return code: 0 01:39:08 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')]} 01:39:08 INFO - trying https://queue.taskcluster.net/v1/task/cfzoeDjPRu6yBZ4FEc73hw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 01:39:08 INFO - Downloading https://queue.taskcluster.net/v1/task/cfzoeDjPRu6yBZ4FEc73hw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 01:39:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cfzoeDjPRu6yBZ4FEc73hw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 01:39:09 INFO - Downloaded 56125504 bytes. 01:39:09 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/cfzoeDjPRu6yBZ4FEc73hw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 01:39:09 INFO - mkdir: /builds/slave/test/properties 01:39:09 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 01:39:09 INFO - Writing to file /builds/slave/test/properties/build_url 01:39:09 INFO - Contents: 01:39:09 INFO - build_url:https://queue.taskcluster.net/v1/task/cfzoeDjPRu6yBZ4FEc73hw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 01:39:10 INFO - Running post-action listener: _resource_record_post_action 01:39:10 INFO - Running post-action listener: set_extra_try_arguments 01:39:10 INFO - [mozharness: 2016-08-07 08:39:10.531855Z] Finished download-and-extract step (success) 01:39:10 INFO - [mozharness: 2016-08-07 08:39:10.532090Z] Running populate-webroot step. 01:39:10 INFO - Running pre-action listener: _resource_record_pre_action 01:39:10 INFO - Running main action method: populate_webroot 01:39:10 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 01:39:10 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 01:39:10 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 01:39:10 INFO - '--tppagecycles', 01:39:10 INFO - '7'], 01:39:10 INFO - 'tests': ['tsvgm']}}, 01:39:10 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 01:39:10 INFO - 'tests': ['tresize', 'tcanvasmark']}, 01:39:10 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 01:39:10 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 01:39:10 INFO - 'tests': ['dromaeo_css', 'kraken']}, 01:39:10 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 01:39:10 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:39:10 INFO - 'talos_options': ['--disable-e10s'], 01:39:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:39:10 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:39:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:39:10 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:39:10 INFO - 'talos_options': ['--disable-e10s'], 01:39:10 INFO - 'tests': ['damp', 'tps']}, 01:39:10 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:39:10 INFO - 'tests': ['damp', 'tps']}, 01:39:10 INFO - 'g3': {'talos_options': ['--disable-e10s'], 01:39:10 INFO - 'tests': ['dromaeo_dom']}, 01:39:10 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 01:39:10 INFO - 'g4': {'talos_options': ['--disable-e10s'], 01:39:10 INFO - 'tests': ['basic_compositor_video']}, 01:39:10 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 01:39:10 INFO - 'other': {'talos_options': ['--disable-e10s'], 01:39:10 INFO - 'tests': ['a11yr', 01:39:10 INFO - 'ts_paint', 01:39:10 INFO - 'tpaint', 01:39:10 INFO - 'sessionrestore', 01:39:10 INFO - 'sessionrestore_no_auto_restore', 01:39:10 INFO - 'tabpaint']}, 01:39:10 INFO - 'other-e10s': {'tests': ['a11yr', 01:39:10 INFO - 'ts_paint', 01:39:10 INFO - 'tpaint', 01:39:10 INFO - 'sessionrestore', 01:39:10 INFO - 'sessionrestore_no_auto_restore', 01:39:10 INFO - 'tabpaint']}, 01:39:10 INFO - 'other-e10s_l64': {'tests': ['a11yr', 01:39:10 INFO - 'ts_paint', 01:39:10 INFO - 'tpaint', 01:39:10 INFO - 'sessionrestore', 01:39:10 INFO - 'sessionrestore_no_auto_restore', 01:39:10 INFO - 'tabpaint']}, 01:39:10 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 01:39:10 INFO - 'ts_paint', 01:39:10 INFO - 'tpaint', 01:39:10 INFO - 'sessionrestore', 01:39:10 INFO - 'sessionrestore_no_auto_restore', 01:39:10 INFO - 'tabpaint']}, 01:39:10 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 01:39:10 INFO - 'tests': ['a11yr', 01:39:10 INFO - 'ts_paint', 01:39:10 INFO - 'tpaint', 01:39:10 INFO - 'sessionrestore', 01:39:10 INFO - 'sessionrestore_no_auto_restore', 01:39:10 INFO - 'tabpaint']}, 01:39:10 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 01:39:10 INFO - 'tests': ['a11yr', 01:39:10 INFO - 'ts_paint', 01:39:10 INFO - 'tpaint', 01:39:10 INFO - 'sessionrestore', 01:39:10 INFO - 'sessionrestore_no_auto_restore', 01:39:10 INFO - 'tabpaint']}, 01:39:10 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 01:39:10 INFO - 'tests': ['tsvgx', 01:39:10 INFO - 'tsvgr_opacity', 01:39:10 INFO - 'tart', 01:39:10 INFO - 'tscrollx', 01:39:10 INFO - 'cart']}, 01:39:10 INFO - 'svgr-e10s': {'tests': ['tsvgx', 01:39:10 INFO - 'tsvgr_opacity', 01:39:10 INFO - 'tart', 01:39:10 INFO - 'tscrollx', 01:39:10 INFO - 'cart']}, 01:39:10 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:39:10 INFO - 'talos_options': ['--disable-e10s'], 01:39:10 INFO - 'tests': ['tp5o']}, 01:39:10 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:39:10 INFO - 'tests': ['tp5o']}, 01:39:10 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:39:10 INFO - 'talos_options': ['--disable-e10s', 01:39:10 INFO - '--xperf_path', 01:39:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:39:10 INFO - 'tests': ['tp5n']}, 01:39:10 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:39:10 INFO - 'talos_options': ['--xperf_path', 01:39:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:39:10 INFO - 'tests': ['tp5n']}}, 01:39:10 INFO - 'talos.zip': {'path': '', 01:39:10 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 01:39:10 INFO - Running post-action listener: _resource_record_post_action 01:39:10 INFO - [mozharness: 2016-08-07 08:39:10.552011Z] Finished populate-webroot step (success) 01:39:10 INFO - [mozharness: 2016-08-07 08:39:10.552100Z] Running create-virtualenv step. 01:39:10 INFO - Running pre-action listener: _resource_record_pre_action 01:39:10 INFO - Running main action method: create_virtualenv 01:39:10 INFO - Creating virtualenv /builds/slave/test/build/venv 01:39:10 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 01:39:10 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 01:39:10 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 01:39:10 INFO - Using real prefix '/usr' 01:39:10 INFO - New python executable in /builds/slave/test/build/venv/bin/python 01:39:11 INFO - Installing distribute.............................................................................................................................................................................................done. 01:39:13 INFO - Installing pip.................done. 01:39:13 INFO - Return code: 0 01:39:13 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 01:39:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:39:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:39:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:39:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:39:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:39:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:39:13 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 0x2a1a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b7d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cdb180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cd93b0>, '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 0x2cda2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cda740>, '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': '31acecf42b6a79ec49471d4a000001bb-1470550271.716546-1247145742', '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 01:39:13 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 01:39:13 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 01:39:13 INFO - Using env: {'DISPLAY': ':0', 01:39:13 INFO - 'HOME': '/home/cltbld', 01:39:13 INFO - 'LANG': 'en_US.UTF-8', 01:39:13 INFO - 'LANGUAGE': 'en_US:en', 01:39:13 INFO - 'LOGNAME': 'cltbld', 01:39:13 INFO - 'MAIL': '/var/mail/cltbld', 01:39:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:39:13 INFO - 'MOZ_NO_REMOTE': '1', 01:39:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:39:13 INFO - 'NO_EM_RESTART': '1', 01:39:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:39:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:39:13 INFO - 'PWD': '/builds/slave/test', 01:39:13 INFO - 'SHELL': '/bin/bash', 01:39:13 INFO - 'SHLVL': '1', 01:39:13 INFO - 'TERM': 'linux', 01:39:13 INFO - 'TMOUT': '86400', 01:39:13 INFO - 'USER': 'cltbld', 01:39:13 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1470550271.716546-1247145742', 01:39:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:39:13 INFO - '_': '/tools/buildbot/bin/python'} 01:39:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:39:13 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 01:39:13 INFO - Cleaning up... 01:39:13 INFO - Return code: 0 01:39:13 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 01:39:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:39:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:39:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:39:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:39:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:39:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:39:13 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 0x2a1a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b7d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cdb180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cd93b0>, '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 0x2cda2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cda740>, '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': '31acecf42b6a79ec49471d4a000001bb-1470550271.716546-1247145742', '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 01:39:13 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 01:39:13 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 01:39:13 INFO - Using env: {'DISPLAY': ':0', 01:39:13 INFO - 'HOME': '/home/cltbld', 01:39:13 INFO - 'LANG': 'en_US.UTF-8', 01:39:13 INFO - 'LANGUAGE': 'en_US:en', 01:39:13 INFO - 'LOGNAME': 'cltbld', 01:39:13 INFO - 'MAIL': '/var/mail/cltbld', 01:39:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:39:13 INFO - 'MOZ_NO_REMOTE': '1', 01:39:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:39:13 INFO - 'NO_EM_RESTART': '1', 01:39:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:39:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:39:13 INFO - 'PWD': '/builds/slave/test', 01:39:13 INFO - 'SHELL': '/bin/bash', 01:39:13 INFO - 'SHLVL': '1', 01:39:13 INFO - 'TERM': 'linux', 01:39:13 INFO - 'TMOUT': '86400', 01:39:13 INFO - 'USER': 'cltbld', 01:39:13 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1470550271.716546-1247145742', 01:39:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:39:13 INFO - '_': '/tools/buildbot/bin/python'} 01:39:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:39:13 INFO - Downloading/unpacking psutil>=3.1.1 01:39:13 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 01:39:13 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 01:39:13 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 01:39:13 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 01:39:13 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 01:39:13 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 01:39:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 01:39:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:39:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:39:15 INFO - Installing collected packages: psutil 01:39:15 INFO - Running setup.py install for psutil 01:39:15 INFO - building 'psutil._psutil_linux' extension 01:39:15 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 01:39:15 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 01:39:15 INFO - building 'psutil._psutil_posix' extension 01:39:15 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 01:39:15 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 01:39:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:39:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:39:16 INFO - Successfully installed psutil 01:39:16 INFO - Cleaning up... 01:39:16 INFO - Return code: 0 01:39:16 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 01:39: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')]} 01:39:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:39:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:39: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')]} 01:39:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:39:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:39: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a1a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b7d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cdb180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cd93b0>, '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 0x2cda2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cda740>, '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': '31acecf42b6a79ec49471d4a000001bb-1470550271.716546-1247145742', '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 01:39: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 01:39: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 mozsystemmonitor==0.3 01:39:16 INFO - Using env: {'DISPLAY': ':0', 01:39:16 INFO - 'HOME': '/home/cltbld', 01:39:16 INFO - 'LANG': 'en_US.UTF-8', 01:39:16 INFO - 'LANGUAGE': 'en_US:en', 01:39:16 INFO - 'LOGNAME': 'cltbld', 01:39:16 INFO - 'MAIL': '/var/mail/cltbld', 01:39:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:39:16 INFO - 'MOZ_NO_REMOTE': '1', 01:39:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:39:16 INFO - 'NO_EM_RESTART': '1', 01:39:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:39:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:39:16 INFO - 'PWD': '/builds/slave/test', 01:39:16 INFO - 'SHELL': '/bin/bash', 01:39:16 INFO - 'SHLVL': '1', 01:39:16 INFO - 'TERM': 'linux', 01:39:16 INFO - 'TMOUT': '86400', 01:39:16 INFO - 'USER': 'cltbld', 01:39:16 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1470550271.716546-1247145742', 01:39:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:39:16 INFO - '_': '/tools/buildbot/bin/python'} 01:39:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:39:16 INFO - Downloading/unpacking mozsystemmonitor==0.3 01:39:16 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 01:39:16 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 01:39:16 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 01:39:16 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 01:39:16 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 01:39:16 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 01:39:18 INFO - Downloading mozsystemmonitor-0.3.tar.gz 01:39:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 01:39:18 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 01:39:18 INFO - Installing collected packages: mozsystemmonitor 01:39:18 INFO - Running setup.py install for mozsystemmonitor 01:39:18 INFO - Successfully installed mozsystemmonitor 01:39:18 INFO - Cleaning up... 01:39:18 INFO - Return code: 0 01:39:18 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 01:39:18 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')]} 01:39:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:39:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:39:18 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')]} 01:39:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:39:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:39:18 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 0x2a1a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b7d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cdb180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cd93b0>, '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 0x2cda2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cda740>, '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': '31acecf42b6a79ec49471d4a000001bb-1470550271.716546-1247145742', '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 01:39:18 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 01:39:18 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 01:39:18 INFO - Using env: {'DISPLAY': ':0', 01:39:18 INFO - 'HOME': '/home/cltbld', 01:39:18 INFO - 'LANG': 'en_US.UTF-8', 01:39:18 INFO - 'LANGUAGE': 'en_US:en', 01:39:18 INFO - 'LOGNAME': 'cltbld', 01:39:18 INFO - 'MAIL': '/var/mail/cltbld', 01:39:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:39:18 INFO - 'MOZ_NO_REMOTE': '1', 01:39:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:39:18 INFO - 'NO_EM_RESTART': '1', 01:39:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:39:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:39:18 INFO - 'PWD': '/builds/slave/test', 01:39:18 INFO - 'SHELL': '/bin/bash', 01:39:18 INFO - 'SHLVL': '1', 01:39:18 INFO - 'TERM': 'linux', 01:39:18 INFO - 'TMOUT': '86400', 01:39:18 INFO - 'USER': 'cltbld', 01:39:18 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1470550271.716546-1247145742', 01:39:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:39:18 INFO - '_': '/tools/buildbot/bin/python'} 01:39:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:39:18 INFO - Downloading/unpacking jsonschema==2.5.1 01:39:18 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 01:39:18 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 01:39:18 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 01:39:18 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 01:39:18 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 01:39:18 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 01:39:20 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 01:39:20 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 01:39: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 01:39: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 01:39: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 01:39: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 01:39: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 01:39: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 01:39:20 INFO - Downloading functools32-3.2.3-2.tar.gz 01:39:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 01:39:20 INFO - warning: no files found matching '*.txt' 01:39:20 INFO - no previously-included directories found matching 'build' 01:39:20 INFO - no previously-included directories found matching 'dist' 01:39:20 INFO - no previously-included directories found matching '.git*' 01:39:20 INFO - Installing collected packages: jsonschema, functools32 01:39:20 INFO - Running setup.py install for functools32 01:39:20 INFO - warning: no files found matching '*.txt' 01:39:20 INFO - no previously-included directories found matching 'build' 01:39:20 INFO - no previously-included directories found matching 'dist' 01:39:20 INFO - no previously-included directories found matching '.git*' 01:39:20 INFO - Successfully installed jsonschema functools32 01:39:20 INFO - Cleaning up... 01:39:21 INFO - Return code: 0 01:39:21 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 01:39:21 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')]} 01:39:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:39:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:39:21 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')]} 01:39:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:39:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:39:21 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 0x2a1a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b7d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cdb180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cd93b0>, '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 0x2cda2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cda740>, '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': '31acecf42b6a79ec49471d4a000001bb-1470550271.716546-1247145742', '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 01:39:21 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 01:39:21 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 01:39:21 INFO - Using env: {'DISPLAY': ':0', 01:39:21 INFO - 'HOME': '/home/cltbld', 01:39:21 INFO - 'LANG': 'en_US.UTF-8', 01:39:21 INFO - 'LANGUAGE': 'en_US:en', 01:39:21 INFO - 'LOGNAME': 'cltbld', 01:39:21 INFO - 'MAIL': '/var/mail/cltbld', 01:39:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:39:21 INFO - 'MOZ_NO_REMOTE': '1', 01:39:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:39:21 INFO - 'NO_EM_RESTART': '1', 01:39:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:39:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:39:21 INFO - 'PWD': '/builds/slave/test', 01:39:21 INFO - 'SHELL': '/bin/bash', 01:39:21 INFO - 'SHLVL': '1', 01:39:21 INFO - 'TERM': 'linux', 01:39:21 INFO - 'TMOUT': '86400', 01:39:21 INFO - 'USER': 'cltbld', 01:39:21 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1470550271.716546-1247145742', 01:39:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:39:21 INFO - '_': '/tools/buildbot/bin/python'} 01:39:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:39:21 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 01:39:21 INFO - Cleaning up... 01:39:21 INFO - Return code: 0 01:39:21 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 01:39:21 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')]} 01:39:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:39:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:39:21 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')]} 01:39:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:39:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:39:21 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 0x2a1a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b7d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cdb180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cd93b0>, '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 0x2cda2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cda740>, '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': '31acecf42b6a79ec49471d4a000001bb-1470550271.716546-1247145742', '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 01:39:21 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 01:39:21 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 01:39:21 INFO - Using env: {'DISPLAY': ':0', 01:39:21 INFO - 'HOME': '/home/cltbld', 01:39:21 INFO - 'LANG': 'en_US.UTF-8', 01:39:21 INFO - 'LANGUAGE': 'en_US:en', 01:39:21 INFO - 'LOGNAME': 'cltbld', 01:39:21 INFO - 'MAIL': '/var/mail/cltbld', 01:39:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:39:21 INFO - 'MOZ_NO_REMOTE': '1', 01:39:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:39:21 INFO - 'NO_EM_RESTART': '1', 01:39:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:39:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:39:21 INFO - 'PWD': '/builds/slave/test', 01:39:21 INFO - 'SHELL': '/bin/bash', 01:39:21 INFO - 'SHLVL': '1', 01:39:21 INFO - 'TERM': 'linux', 01:39:21 INFO - 'TMOUT': '86400', 01:39:21 INFO - 'USER': 'cltbld', 01:39:21 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1470550271.716546-1247145742', 01:39:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:39:21 INFO - '_': '/tools/buildbot/bin/python'} 01:39:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:39:21 INFO - Downloading/unpacking blobuploader==1.2.4 01:39:21 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 01:39:21 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 01:39:21 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 01:39:21 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 01:39:21 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 01:39:21 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 01:39:23 INFO - Downloading blobuploader-1.2.4.tar.gz 01:39:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 01:39:23 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 01:39:23 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 01:39:23 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 01:39:23 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 01:39:23 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 01:39:23 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 01:39:23 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 01:39:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 01:39:23 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 01:39:23 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 01:39:23 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 01:39:23 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 01:39:23 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 01:39:23 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 01:39:23 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 01:39:24 INFO - Downloading docopt-0.6.1.tar.gz 01:39:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 01:39:24 INFO - Installing collected packages: blobuploader, requests, docopt 01:39:24 INFO - Running setup.py install for blobuploader 01:39:24 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 01:39:24 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 01:39:24 INFO - Running setup.py install for requests 01:39:24 INFO - Running setup.py install for docopt 01:39:24 INFO - Successfully installed blobuploader requests docopt 01:39:24 INFO - Cleaning up... 01:39:24 INFO - Return code: 0 01:39:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:39:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:39:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:39:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:39:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:39:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:39:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:39:24 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 0x2a1a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b7d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cdb180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cd93b0>, '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 0x2cda2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cda740>, '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': '31acecf42b6a79ec49471d4a000001bb-1470550271.716546-1247145742', '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 01:39:24 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 01:39:24 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 01:39:24 INFO - Using env: {'DISPLAY': ':0', 01:39:24 INFO - 'HOME': '/home/cltbld', 01:39:24 INFO - 'LANG': 'en_US.UTF-8', 01:39:24 INFO - 'LANGUAGE': 'en_US:en', 01:39:24 INFO - 'LOGNAME': 'cltbld', 01:39:24 INFO - 'MAIL': '/var/mail/cltbld', 01:39:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:39:24 INFO - 'MOZ_NO_REMOTE': '1', 01:39:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:39:24 INFO - 'NO_EM_RESTART': '1', 01:39:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:39:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:39:24 INFO - 'PWD': '/builds/slave/test', 01:39:24 INFO - 'SHELL': '/bin/bash', 01:39:24 INFO - 'SHLVL': '1', 01:39:24 INFO - 'TERM': 'linux', 01:39:24 INFO - 'TMOUT': '86400', 01:39:24 INFO - 'USER': 'cltbld', 01:39:24 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1470550271.716546-1247145742', 01:39:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:39:24 INFO - '_': '/tools/buildbot/bin/python'} 01:39:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:39:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:39:24 INFO - Running setup.py (path:/tmp/pip-35QOYt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:39:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:39:24 INFO - Running setup.py (path:/tmp/pip-rQyEAe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:39:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:39:25 INFO - Running setup.py (path:/tmp/pip-g1c0xW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:39:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:39:25 INFO - Running setup.py (path:/tmp/pip-G71YSo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:39:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:39:25 INFO - Running setup.py (path:/tmp/pip-pFtiFL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:39:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:39:25 INFO - Running setup.py (path:/tmp/pip-PZdgJE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:39:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:39:25 INFO - Running setup.py (path:/tmp/pip-Woql6C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:39:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:39:25 INFO - Running setup.py (path:/tmp/pip-Gkv7Tf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:39:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:39:25 INFO - Running setup.py (path:/tmp/pip-VLbVuy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:39:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:39:25 INFO - Running setup.py (path:/tmp/pip-eV_Xe7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:39:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:39:25 INFO - Running setup.py (path:/tmp/pip-dgc70K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:39:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:39:25 INFO - Running setup.py (path:/tmp/pip-qNuecX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:39:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:39:25 INFO - Running setup.py (path:/tmp/pip-Z_IJfL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:39:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:39:25 INFO - Running setup.py (path:/tmp/pip-wUR7lS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:39:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:39:25 INFO - Running setup.py (path:/tmp/pip-Y5RnBf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:39:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:39:25 INFO - Running setup.py (path:/tmp/pip-Kj53_f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:39:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:39:26 INFO - Running setup.py (path:/tmp/pip-PVFW9T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:39:26 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 01:39:26 INFO - Running setup.py install for manifestparser 01:39:26 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 01:39:26 INFO - Running setup.py install for mozcrash 01:39:26 INFO - Running setup.py install for mozdebug 01:39:26 INFO - Running setup.py install for mozdevice 01:39:26 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 01:39:26 INFO - Installing dm script to /builds/slave/test/build/venv/bin 01:39:26 INFO - Running setup.py install for mozfile 01:39:26 INFO - Running setup.py install for mozhttpd 01:39:26 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 01:39:26 INFO - Running setup.py install for mozinfo 01:39:26 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 01:39:26 INFO - Running setup.py install for mozInstall 01:39:26 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 01:39:26 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 01:39:26 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 01:39:26 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 01:39:26 INFO - Running setup.py install for mozleak 01:39:27 INFO - Running setup.py install for mozlog 01:39:27 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 01:39:27 INFO - Running setup.py install for moznetwork 01:39:27 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 01:39:27 INFO - Running setup.py install for mozprocess 01:39:27 INFO - Running setup.py install for mozprofile 01:39:27 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 01:39:27 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 01:39:27 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 01:39:27 INFO - Running setup.py install for mozrunner 01:39:27 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 01:39:27 INFO - Running setup.py install for mozscreenshot 01:39:27 INFO - Running setup.py install for moztest 01:39:27 INFO - Running setup.py install for mozversion 01:39:27 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 01:39:27 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 01:39:27 INFO - Cleaning up... 01:39:27 INFO - Return code: 0 01:39:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:39:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:39:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:39:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:39:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:39:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:39:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:39:27 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 0x2a1a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b7d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cdb180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cd93b0>, '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 0x2cda2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cda740>, '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': '31acecf42b6a79ec49471d4a000001bb-1470550271.716546-1247145742', '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 01:39:27 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 01:39:27 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 01:39:27 INFO - Using env: {'DISPLAY': ':0', 01:39:27 INFO - 'HOME': '/home/cltbld', 01:39:27 INFO - 'LANG': 'en_US.UTF-8', 01:39:27 INFO - 'LANGUAGE': 'en_US:en', 01:39:27 INFO - 'LOGNAME': 'cltbld', 01:39:27 INFO - 'MAIL': '/var/mail/cltbld', 01:39:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:39:27 INFO - 'MOZ_NO_REMOTE': '1', 01:39:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:39:27 INFO - 'NO_EM_RESTART': '1', 01:39:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:39:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:39:27 INFO - 'PWD': '/builds/slave/test', 01:39:27 INFO - 'SHELL': '/bin/bash', 01:39:27 INFO - 'SHLVL': '1', 01:39:27 INFO - 'TERM': 'linux', 01:39:27 INFO - 'TMOUT': '86400', 01:39:27 INFO - 'USER': 'cltbld', 01:39:27 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1470550271.716546-1247145742', 01:39:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:39:27 INFO - '_': '/tools/buildbot/bin/python'} 01:39:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:39:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:39:28 INFO - Running setup.py (path:/tmp/pip-j7ACVv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:39:28 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)) 01:39:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:39:28 INFO - Running setup.py (path:/tmp/pip-6tqqxu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:39:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 01:39:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:39:28 INFO - Running setup.py (path:/tmp/pip-sdK0WQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:39:28 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)) 01:39:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:39:28 INFO - Running setup.py (path:/tmp/pip-tRTDDX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:39:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 01:39:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:39:28 INFO - Running setup.py (path:/tmp/pip-XYMxiY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:39:28 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)) 01:39:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:39:28 INFO - Running setup.py (path:/tmp/pip-rtdzT7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:39:28 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)) 01:39:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:39:28 INFO - Running setup.py (path:/tmp/pip-u3phsf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:39:28 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)) 01:39:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:39:28 INFO - Running setup.py (path:/tmp/pip-ECN2xS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:39:28 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)) 01:39:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:39:28 INFO - Running setup.py (path:/tmp/pip-n4r3Uq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:39:28 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)) 01:39:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:39:28 INFO - Running setup.py (path:/tmp/pip-zrfxCx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:39:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 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)) 01:39:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:39:28 INFO - Running setup.py (path:/tmp/pip-7gQooo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:39:28 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)) 01:39:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:39:28 INFO - Running setup.py (path:/tmp/pip-Ydva4m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:39:29 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)) 01:39:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:39:29 INFO - Running setup.py (path:/tmp/pip-v1Dp9p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:39:29 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)) 01:39:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:39:29 INFO - Running setup.py (path:/tmp/pip-lT64Rd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:39:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 01:39:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:39:29 INFO - Running setup.py (path:/tmp/pip-QxEbx1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:39:29 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)) 01:39:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:39:29 INFO - Running setup.py (path:/tmp/pip-3f75Cv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:39:29 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)) 01:39:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:39:29 INFO - Running setup.py (path:/tmp/pip-0qEdu0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:39:29 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)) 01:39:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 01:39:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 01:39:29 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)) 01:39:29 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 01:39:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 01:39:29 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 01:39:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:39:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:39:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:39:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:39:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:39:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:39:31 INFO - Downloading blessings-1.6.tar.gz 01:39:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 01:39:31 INFO - Installing collected packages: blessings 01:39:31 INFO - Running setup.py install for blessings 01:39:31 INFO - Successfully installed blessings 01:39:31 INFO - Cleaning up... 01:39:31 INFO - Return code: 0 01:39:31 INFO - Done creating virtualenv /builds/slave/test/build/venv. 01:39:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:39:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:39:31 INFO - Reading from file tmpfile_stdout 01:39:31 INFO - Current package versions: 01:39:31 INFO - argparse == 1.2.1 01:39:31 INFO - blessings == 1.6 01:39:31 INFO - blobuploader == 1.2.4 01:39:31 INFO - docopt == 0.6.1 01:39:31 INFO - functools32 == 3.2.3-2 01:39:31 INFO - jsonschema == 2.5.1 01:39:31 INFO - manifestparser == 1.1 01:39:31 INFO - mozInstall == 1.12 01:39:31 INFO - mozcrash == 0.17 01:39:31 INFO - mozdebug == 0.1 01:39:31 INFO - mozdevice == 0.48 01:39:31 INFO - mozfile == 1.2 01:39:31 INFO - mozhttpd == 0.7 01:39:31 INFO - mozinfo == 0.9 01:39:31 INFO - mozleak == 0.1 01:39:31 INFO - mozlog == 3.3 01:39:31 INFO - moznetwork == 0.27 01:39:31 INFO - mozprocess == 0.23 01:39:31 INFO - mozprofile == 0.28 01:39:31 INFO - mozrunner == 6.12 01:39:31 INFO - mozscreenshot == 0.1 01:39:31 INFO - mozsystemmonitor == 0.3 01:39:31 INFO - moztest == 0.7 01:39:31 INFO - mozversion == 1.4 01:39:31 INFO - psutil == 3.1.1 01:39:31 INFO - requests == 1.2.3 01:39:31 INFO - wsgiref == 0.1.2 01:39:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:39:31 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')]} 01:39:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:39:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:39:31 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')]} 01:39:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:39:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:39:31 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 0x2a1a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b7d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cdb180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cd93b0>, '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 0x2cda2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cda740>, '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': '31acecf42b6a79ec49471d4a000001bb-1470550271.716546-1247145742', '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 01:39:31 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 01:39:31 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 01:39:31 INFO - Using env: {'DISPLAY': ':0', 01:39:31 INFO - 'HOME': '/home/cltbld', 01:39:31 INFO - 'LANG': 'en_US.UTF-8', 01:39:31 INFO - 'LANGUAGE': 'en_US:en', 01:39:31 INFO - 'LOGNAME': 'cltbld', 01:39:31 INFO - 'MAIL': '/var/mail/cltbld', 01:39:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:39:31 INFO - 'MOZ_NO_REMOTE': '1', 01:39:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:39:31 INFO - 'NO_EM_RESTART': '1', 01:39:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:39:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:39:31 INFO - 'PWD': '/builds/slave/test', 01:39:31 INFO - 'SHELL': '/bin/bash', 01:39:31 INFO - 'SHLVL': '1', 01:39:31 INFO - 'TERM': 'linux', 01:39:31 INFO - 'TMOUT': '86400', 01:39:31 INFO - 'USER': 'cltbld', 01:39:31 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1470550271.716546-1247145742', 01:39:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:39:31 INFO - '_': '/tools/buildbot/bin/python'} 01:39:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:39:31 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)) 01:39:31 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)) 01:39:31 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)) 01:39:31 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)) 01:39:31 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)) 01:39:31 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)) 01:39:31 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)) 01:39:31 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)) 01:39:31 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)) 01:39:31 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)) 01:39:31 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)) 01:39:31 INFO - Cleaning up... 01:39:31 INFO - Return code: 0 01:39:31 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 01:39:31 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')]} 01:39:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:39:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:39:31 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')]} 01:39:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:39:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:39:31 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 0x2a1a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b7d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cdb180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cd93b0>, '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 0x2cda2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cda740>, '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': '31acecf42b6a79ec49471d4a000001bb-1470550271.716546-1247145742', '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 01:39:31 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 01:39:31 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 01:39:31 INFO - Using env: {'DISPLAY': ':0', 01:39:31 INFO - 'HOME': '/home/cltbld', 01:39:31 INFO - 'LANG': 'en_US.UTF-8', 01:39:31 INFO - 'LANGUAGE': 'en_US:en', 01:39:31 INFO - 'LOGNAME': 'cltbld', 01:39:31 INFO - 'MAIL': '/var/mail/cltbld', 01:39:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:39:31 INFO - 'MOZ_NO_REMOTE': '1', 01:39:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:39:31 INFO - 'NO_EM_RESTART': '1', 01:39:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:39:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:39:31 INFO - 'PWD': '/builds/slave/test', 01:39:31 INFO - 'SHELL': '/bin/bash', 01:39:31 INFO - 'SHLVL': '1', 01:39:31 INFO - 'TERM': 'linux', 01:39:31 INFO - 'TMOUT': '86400', 01:39:31 INFO - 'USER': 'cltbld', 01:39:31 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1470550271.716546-1247145742', 01:39:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:39:31 INFO - '_': '/tools/buildbot/bin/python'} 01:39:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:39:32 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 01:39:32 INFO - Cleaning up... 01:39:32 INFO - Return code: 0 01:39:32 INFO - Running post-action listener: _resource_record_post_action 01:39:32 INFO - Running post-action listener: _start_resource_monitoring 01:39:32 INFO - Starting resource monitoring. 01:39:32 INFO - [mozharness: 2016-08-07 08:39:32.182290Z] Finished create-virtualenv step (success) 01:39:32 INFO - [mozharness: 2016-08-07 08:39:32.182837Z] Running install step. 01:39:32 INFO - Running pre-action listener: _resource_record_pre_action 01:39:32 INFO - Running main action method: install 01:39:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:39:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:39:32 INFO - Reading from file tmpfile_stdout 01:39:32 INFO - Detecting whether we're running mozinstall >=1.0... 01:39:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 01:39:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 01:39:32 INFO - Reading from file tmpfile_stdout 01:39:32 INFO - Output received: 01:39:32 INFO - Usage: mozinstall [options] installer 01:39:32 INFO - Options: 01:39:32 INFO - -h, --help show this help message and exit 01:39:32 INFO - -d DEST, --destination=DEST 01:39:32 INFO - Directory to install application into. [default: 01:39:32 INFO - "/builds/slave/test"] 01:39:32 INFO - --app=APP Application being installed. [default: firefox] 01:39:32 INFO - mkdir: /builds/slave/test/build/application 01:39:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 01:39:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 01:39:48 INFO - Reading from file tmpfile_stdout 01:39:48 INFO - Output received: 01:39:48 INFO - /builds/slave/test/build/application/firefox/firefox 01:39:48 INFO - Running post-action listener: _resource_record_post_action 01:39:48 INFO - [mozharness: 2016-08-07 08:39:48.705228Z] Finished install step (success) 01:39:48 INFO - [mozharness: 2016-08-07 08:39:48.705478Z] Running run-tests step. 01:39:48 INFO - Running pre-action listener: _resource_record_pre_action 01:39:48 INFO - Running main action method: run_tests 01:39:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 01:39:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 01:39:48 INFO - Python 2.7.3 01:39:48 INFO - Return code: 0 01:39:48 INFO - grabbing minidump binary from tooltool 01:39:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:39:48 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 0x2cd93b0>, '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 0x2cda2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cda740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 01:39:48 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 01:39:48 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 01:39:48 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 01:39:48 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 01:39:48 INFO - Return code: 0 01:39:48 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 01:39:48 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 01:39:48 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 01:39:48 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 01:39:48 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 01:39:48 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/cfzoeDjPRu6yBZ4FEc73hw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-014', '--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 01:39:48 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/cfzoeDjPRu6yBZ4FEc73hw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-014 --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 01:39:48 INFO - Using env: {'DISPLAY': ':0', 01:39:48 INFO - 'HOME': '/home/cltbld', 01:39:48 INFO - 'LANG': 'en_US.UTF-8', 01:39:48 INFO - 'LANGUAGE': 'en_US:en', 01:39:48 INFO - 'LOGNAME': 'cltbld', 01:39:48 INFO - 'MAIL': '/var/mail/cltbld', 01:39:48 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 01:39:48 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 01:39:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:39:48 INFO - 'MOZ_NO_REMOTE': '1', 01:39:48 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 01:39:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:39:48 INFO - 'NO_EM_RESTART': '1', 01:39:48 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:39:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:39:48 INFO - 'PWD': '/builds/slave/test', 01:39:48 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 01:39:48 INFO - 'SHELL': '/bin/bash', 01:39:48 INFO - 'SHLVL': '1', 01:39:48 INFO - 'TERM': 'linux', 01:39:48 INFO - 'TMOUT': '86400', 01:39:48 INFO - 'USER': 'cltbld', 01:39:48 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1470550271.716546-1247145742', 01:39:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:39:48 INFO - '_': '/tools/buildbot/bin/python'} 01:39:48 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/cfzoeDjPRu6yBZ4FEc73hw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-014', '--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 01:39:48 INFO - mozversion application_buildid: 20160807001753 01:39:48 INFO - mozversion application_changeset: d42aacfe34af25e2f5110e2ca3d24a210eabeb33 01:39:48 INFO - mozversion application_display_name: Nightly 01:39:48 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 01:39:48 INFO - mozversion application_name: Firefox 01:39:48 INFO - mozversion application_remotingname: firefox 01:39:48 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 01:39:48 INFO - mozversion application_vendor: Mozilla 01:39:48 INFO - mozversion application_version: 51.0a1 01:39:48 INFO - mozversion platform_buildid: 20160807001753 01:39:48 INFO - mozversion platform_changeset: d42aacfe34af25e2f5110e2ca3d24a210eabeb33 01:39:48 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 01:39:48 INFO - mozversion platform_version: 51.0a1 01:39:48 INFO - using testdate: 1470559188 01:39:48 INFO - actual date: 1470559188 01:39:48 INFO - starting webserver on 'localhost:43418' 01:39:48 INFO - SUITE-START | Running 2 tests 01:39:48 INFO - TEST-START | dromaeo_css 01:39:48 INFO - operating with platform_type : linux_ 01:39:48 INFO - Initialising browser for dromaeo_css test... 01:39:49 INFO - TEST-INFO | started process 29933 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpmUx5X4/profile http://localhost:43418/getInfo.html) 01:39:53 INFO - PROCESS | 29933 | __metrics Screen width/height:1600/1200 01:39:53 INFO - PROCESS | 29933 | colorDepth:24 01:39:53 INFO - PROCESS | 29933 | Browser inner width/height: 1024/697 01:39:53 INFO - PROCESS | 29933 | __metrics 01:39:54 INFO - PROCESS | 29933 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 01:39:54 INFO - PROCESS | 29933 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 01:39:54 INFO - PROCESS | 29933 | console.error: PushService: 01:39:54 INFO - PROCESS | 29933 | stateChangeProcessEnqueue: Error transitioning state 01:39:54 INFO - PROCESS | 29933 | UnknownError 01:39:55 INFO - TEST-INFO | 29933: exit 0 01:39:55 INFO - Browser initialized. 01:39:55 INFO - Running cycle 1/1 for dromaeo_css test... 01:39:55 INFO - TEST-INFO | started process 30015 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpmUx5X4/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:40:07 INFO - PROCESS | 30015 | RSS: Main: 157548544 01:40:07 INFO - PROCESS | 30015 | 01:40:58 INFO - PROCESS | 30015 | 1470559258008 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 01:43:11 INFO - PROCESS | 30015 | Cycle 1(1): loaded http://localhost:43418/tests/dromaeo/cssquery-dojo.html (next: http://localhost:43418/tests/dromaeo/cssquery-ext.html) 01:43:12 INFO - PROCESS | 30015 | RSS: Main: 247869440 01:43:12 INFO - PROCESS | 30015 | 01:46:15 INFO - PROCESS | 30015 | Cycle 1(1): loaded http://localhost:43418/tests/dromaeo/cssquery-ext.html (next: http://localhost:43418/tests/dromaeo/cssquery-jquery.html) 01:46:15 INFO - PROCESS | 30015 | RSS: Main: 243204096 01:46:15 INFO - PROCESS | 30015 | 01:49:17 INFO - PROCESS | 30015 | Cycle 1(1): loaded http://localhost:43418/tests/dromaeo/cssquery-jquery.html (next: http://localhost:43418/tests/dromaeo/cssquery-mootools.html) 01:49:18 INFO - PROCESS | 30015 | RSS: Main: 217706496 01:49:18 INFO - PROCESS | 30015 | 01:52:20 INFO - PROCESS | 30015 | Cycle 1(1): loaded http://localhost:43418/tests/dromaeo/cssquery-mootools.html (next: http://localhost:43418/tests/dromaeo/cssquery-prototype.html) 01:52:21 INFO - PROCESS | 30015 | RSS: Main: 186802176 01:52:21 INFO - PROCESS | 30015 | 01:55:24 INFO - PROCESS | 30015 | Cycle 1(1): loaded http://localhost:43418/tests/dromaeo/cssquery-prototype.html (next: http://localhost:43418/tests/dromaeo/cssquery-yui.html) 01:55:24 INFO - PROCESS | 30015 | RSS: Main: 185896960 01:55:24 INFO - PROCESS | 30015 | 01:58:27 INFO - PROCESS | 30015 | Cycle 1(1): loaded http://localhost:43418/tests/dromaeo/cssquery-yui.html (next: http://localhost:43418/tests/dromaeo/cssquery-dojo.html) 01:58:28 INFO - PROCESS | 30015 | RSS: Main: 270807040 01:58:28 INFO - PROCESS | 30015 | 01:58:28 INFO - PROCESS | 30015 | __start_tp_report 01:58:28 INFO - PROCESS | 30015 | _x_x_mozilla_page_load 01:58:28 INFO - PROCESS | 30015 | _x_x_mozilla_page_load_details 01:58:28 INFO - PROCESS | 30015 | |i|pagename|runs| 01:58:28 INFO - PROCESS | 30015 | |0;dojo.html;2602.726713695063;2606.413556949686;2647.563152079907;2652.3899503114294;2660.9467810643787;4518.118966801475;4564.817407303713;4696.624270058392;4706.505816889225;4725.385699859022;4721.362616046833;4746.050789842028;4751.762411879409;4823.155947709154;4827.6137908967285;4180.644645205161;4291.6566674665955;4346.913061738764;4394.472663280395;4521.728696278234;4430.6898517103655;4450.376947227391;4468.396766436538;4478.126765280772;4489.618382437497;4154.459920210354;4210.7473551587;4213.073123912734;4291.463567054118;4351.869443916656;4334.111507141029;4335.56644335567;4346.913061738779;4389.956100438987;4428.933565996513;4341.370501277297;4354.521002689701;4371.628411585019;4395.714278571914;4399.274119770234;4521.502634710212;4740.502247264022;4756.07256584966;4803.7357945313;4815.229563269895;4957.5042495750495;5041.647084704072;5047.545720885138;5055.241713742931;5102.770375333119;5065.214891691757;5081.517255860687;5102.132637451605;5110.616703747234;5119.257707632372;4528.43394575678;4669.719816811003;4761.59526440253;4764.690295130806;4769.785359658824;5161.561267292247;5173.068847607392;5216.765245563959;5228.2941802856885;5239.345081864767;4953.628477864175;4957.454679985199;4960.479149689303;5005.399352077774;5008.774605142778;4824.927626085611;5036.445990940993;5099.847004589868;5100.285959965608;5101.234814777813;5027.271045698353;5061.291419201315;5063.443021267658;5088.338515992971;5092.567131793765;5029.798808047711;5042.873928151752;5043.899122017612;5049.722265275445;5096.949030509648;4076;4103.507579090468;4317.417148684887;4331.148240595059;4358.673099517486;3747.6814470769746;3762.473253744478;3783.39465685489;3824.6749026333073;3882.398228274622;4379.255526560492;4386.978065109654;4388.473383193973;4415.116976604692;4433.556644335541;4801.039792041605;5111.233315002779;5120.206368012964;5122.257272695513;5163.122269214243;4174.937375939363;4294.763787991628;4324.070324880166;4332.1985432695055;4368.08270263242;4533.048059907456;4554.817807287738;4563.247064234364;4564.748938808398;4603.815847366068;4739.194336962656;4740.786664600032;4745.976270118766;4748.548887855648;4801.231802911518;4864.610831133572;5005;5031.597472202142;5036.269740887477;5088.338515992898;4710.8351207707565;4740.454847692559;4740.928886066781;4743.739094349844;4788.784504697227;4949.430815456269;4949.554540091409;4951.356323677899;4955;5001.049800537886;4906.754662266943;5088.414832294334;5095.821646242363;5127.051495473349;5180.248863914787;4961.801527938842;4974.701517908937;4974.726390048582;4976.651634385558;5017.698938063728;5012.3233363495465;5022.648414610942;5024.321716568216;5028.371453568304;5076.923846142385;4643.698159619614;4863.562279394871;4899;4905.8037678492465;4941.876453088702;4990.351254336913;4998.300237966615;5011.924821127613;5014.87462813418;5061.316722242597;4887.486813884491;4903.31353610502;4908.852734417973;4910.091633047898;4962.230854217602 01:58:28 INFO - PROCESS | 30015 | |1;ext.html;552.8049733834642;579.6145563200472;591.1398914579288;591.8964181268277;593.1932571702483;28217.717822821793;30298.242543936412;30680.53979190314;30744.692553074492;30909.22726931828;31370.686293137118;42961.570384296145;43992.78003609985;44053.55946440535;44112.3383149253;7607.581583012946;7704.691812327528;7716.6913323467;7722.034745656793;7727.459077864552;7497.925020749804;7691.692332306701;7735.72924947627;7821.648025838851;7824.06111266646;7666.2717041881215;7721.922780772176;9933.652322168726;10106.292559520836;10130.138938190263;10103.989601039912;10137.695869123938;10149.89850101497;10155.238357123171;10168.79662406755;9798.853017204749;9848.35985660937;9851.507424628811;9857.507124643811;9865.50672466381;29541.40917181644;36689.88136008442;37026.25947481062;37071.629283707356;40747.796261018804;3060.4491191585507;3071.9442616298993;3094.3347180356104;3172.904812855618;3203.823789691566;28632;28931.421371572662;29062.85468572665;29082;29553.408931821243;3207.83960801959;3231.2083539532728;3360.899173024813;3364.34395292918;3403.710684591813;251512.99999999817;252038.91844326205;253149;253408;254395;47239;54901.725491372286;55311;55374.44625553693;55415.445845541835;55472;56618.43381566214;56771;62375;62739.372606273355;13090.08369414151;13213;13214.669633259053;13229.206247625174;13253.66865828362;9898.851517227247;9971.252156088178;9973.451460169761;9979.351342162736;9983.400995940267;15928.681426371408;17400;17463.825361746472;17501.47495575135;17571.297148114187;6254.998400511792;6803.829904252334;7001;7142.357187853118;7230.313120253568;811.6834434570522;878.7188099808;898.820235952812;912.3567884641312;1017.8269694152012;28695.56956645652;28732.856335718607;28815.855920720263;29266.561001584992;29361.265968350963;911.0618256124079;930.734740598926;950.7337945375306;1024.533837104116;1052.1109662335314;14893.47872824446;15256.313465894062;15330.463433779763;15635.530934071996;15672.138032408327;388901;393378;394341;394558;394950;34097.488537671954;34138.31723365519;43545.34681979773;48284.5171548287;49014.26478602824;1170.0814860334597;1196.772613203506;1209.9455524501243;1227.1961864978452;1233.3956361382857;27095;29192;29334.853325733235;29974.550381743855;30042.69957300399;42649.57350426456;43121.568784311756;43203.56796432121;43222.78388608037;43233.3514997269;390417;400430;401892;621574;622521;38834.41748373833;38936.026599335244;39169;39397.80301098476;39792.403113952736;42053.57946420619;42300.78849605732;42688.78655606702;42878.571214287454;43967;36538.8173059133;36870.63129368672;37111.628883710815;37464.812675936446;37878.62121378861;36818.815905920295;37170.25659486849;37470.812645936596;37477;37827.43258851174;35032.82483587566;35797.64202357943;36072.27855442929;36371.45442818413;36855.81572092122;31143.221419464695;33032;33116.33767324688;33726;33728.83135584307;22886.198983035512;23365.182218622267;23511.529769404857;23581;23856.88071559631 01:58:28 INFO - PROCESS | 30015 | |2;jquery.html;334.7706820827735;358.3048885162784;370.20221422833794;373.97943113128775;385.87073330434305;12713.300768457731;16332.265048072835;16485.67028659426;16791.664166716688;17667.381641642543;16495.83504164958;17401.651966960653;17857.285708571642;18019.549511262227;18050.90974545129;4889.291052797351;5104.974475127619;5277.234800953859;5283.07546179419;5294.867628309304;3744.644258795424;5212.791488340481;5306.575473962093;5901.822945311626;6196.9690151549175;6228.283747369042;6237.251529816405;6241.812745617639;6254.249490061199;6255.593386429868;6033.909491357633;6213.378662133773;6221.160143380631;6225.688715564249;6237.033259844731;4806.8077276909235;5450.964316779799;5453.3728621208675;5456.863578410572;5463.2078348639625;19941.202351905908;20242.190312387487;20426.69359959602;20668.276610318717;20851.5829683407;1861.6835138026568;1928.421473557941;2140.1011575138477;2291.163725240292;2344.3904584808074;8837.38138330317;8920.553972301359;8949.239314658196;9432.575534100983;9654.613815447445;1689.510042087793;1690.2562872336255;1715.1424287856073;1721.5782133377402;1798.235749806327;63655;64736.99999999953;68327.65836170787;68831.65584172047;69029.3097069033;9153.63385464574;9444.480553569481;9504.382215155993;9555.331126821056;9567.186789123003;12652.17760845542;12670.873291267153;12670.936645316715;12712.55506057302;12745;6254.718537665816;6442.48460123189;6512.218533775977;6520.510961677893;6598.571092879043;6020.939790602126;6057.4851137653795;6180.227471566054;6188.040853667689;6246.625202487865;4154.439150714676;4157.833686652561;4245;4292.957070429318;4311.417958575552;1605.3819279577447;1645.522798388454;1702.5318037539776;1730.6884760742935;1784.1079460269866;720.9170945341248;837.3050368194384;958.0563145301873;966.6278482784179;980.548947484151;17094.40169594058;18104.456866294033;18123.21346472916;18378.264869405342;18451.723224151647;843.5782108945527;861.1130535548396;867.8394497018072;871.7994861181963;873.1050673060038;7750.612469376509;9606.459533101042;10013.447915573099;10084.747881303027;10342.741431464274;363143;367163;370189;370193;372571;51455;68815;73955;73977;74479.62760186165;912.4434095201922;919.1268295119529;931.5528546297815;949.2880339745191;952.476138124029;10939.671809845717;14882.553523393883;15457.84542154609;16038.518844434686;16890.915545422195;34442.13894652654;36210;38272.2345553093;38398.616013839506;38485.807570961966;48380.274295886295;50849.49150508447;50877;52120.73939630278;52309.47690523046;6626.469882409492;6797.354251346114;6844.486663500158;7043.929560704532;7248.637568121468;8919.73240802751;11078.5568577256;11103.333799972028;12186;12329.136960412685;10108.545115469675;10186.541605627914;10365.689029329133;10562.947185264025;10747.623833165802;9741.123298903132;10209.336393134423;10342.172626189737;10611.840822387516;10965.396903170084;8577.571121443778;9050.728478145666;9996.850047249152;10437.947810260901;10441.843372349573;7432;7484.588347640932;7596.886046709194;7614.124375696866;7766.495177813424;9426;10043.497825108569;10346.32748871321;10468.371897686164;10557.683269501927 01:58:28 INFO - PROCESS | 30015 | |3;mootools.html;1368.5004973184784;1413.109740863256;1478.6599082211092;1644.5559698881295;1680.9075500847448;7342.302481264278;7838.72564460244;7856.685732570704;7863;7871.173526779678;6375.904361434582;7205.495615306931;7381.557106573609;7400.185979542246;7708.57602831844;2147.066026278566;2175.5540114276537;2181.3892110209167;2182.2689399051337;2182.7380714314304;2113;2153.0741781034226;2188.3763127508664;2190.080166330137;2192.1121945612053;2149.656055031195;2152.2251989283845;2156.105216335219;2159.0500179920787;2192.3642143778366;2129.7763734807777;2133.8292936565194;2134.3063504361144;2134.658454647249;2135.6049130910674;2077.574097310048;2112.493001679601;2115.1962254343407;2115.693224482457;2116.619008578455;7596.050493688289;7835.764927052198;7953.12515623281;7958.084820245688;7958.243966823143;4442.355858400514;4575;4586.495485496593;4586.610138138262;4590.724556526619;2750.2024412920227;2834.6031555582235;2902.954936222979;2906.9709302906913;2911.432270707213;841.8947631546057;883.3683916000026;902.5036230073433;903.4759839293195;907.981840363189;10667.359958402521;10681.519331630017;10732.517036733288;10741.624043158452;10784.083352914933;3897.2595206910596;4041.8585349513214;4181.435506206623;4191.266528357525;4192.392103145027;9154.084591540792;9155.81688366229;9162.908370916339;9163.679271225608;9172.403793753518;4457.465104187453;4461;4465.754383508938;4467.173572889026;4471.642268618502;3272.2310257089566;4008.118213992917;4015;4018.3771515415156;4165.45849039629;3521.6126226115107;3559.982200088983;3669.761465504787;3677.687396571268;3692.7415080944074;1120.1151090638468;1165.7959857025157;1174.3247632611283;1180.2741314091772;1181.3797756178076;748.2069006852754;758.6623952341265;778.2567647896245;778.6457161991281;786.6420778545748;5265.131253343232;5434.619576629598;5524.944750552443;5533.114701647717;5667.4615911488345;870.2733217763113;879.8856148700756;890.3900827932887;908.2688435809237;924.7040946897063;3310.9172270692943;3344.5484859544135;3380.577427821522;3395.1002984209204;3537.239493508908;9372.90627093734;9650.806983860284;9719.076687714656;9724.659636912675;9727.173190278763;9667.70996870081;9675.758106047406;9679.951600242095;9693.9030609693;9698.078682525149;7373.225811289737;7410.814729631695;7494.5128566643;7497.362724168507;7503.662335194929;5350.411454739898;5403.162509810985;5425.891482170414;5476.808311709071;5523.696196709219;8807.163319484638;9032.548372581476;9050.954745226232;9100.22648074921;9183.494907780136;8447.493150410995;8829.028806831115;8838.513881736566;9179.449233046038;9183.448993060438;5866.266716660418;6373.171487706569;6616.933830661825;6772.55978361405;6775.830604234737;7502.549847008979;7528.811779705551;7585.544867307979;7599.772006839804;7607.923920760722;6612.140421745335;6727.529072965041;6765.458763298826;6871;6945.861082778417;6370.044493326038;6493.967530162319;6634.635095069818;6748.966255168692;6836.555623884432;6337.683115844097;6429.485641148791;6437.485001199986;6443.96778016107;6450.451711604566;4643.8142474302;4666.696664716783;4754.286856971482;4783.784729687103;4863.440704318907;4620.491745907881;4726.841923728708;4737.3130896020575;4776.498467660985;4864.173090574682 01:58:28 INFO - PROCESS | 30015 | |4;prototype.html;282.8628115364048;283.15009430086184;290.51484021683785;301.1162238829037;301.47994709126743;4905.386826646669;4963.404391473019;4978.87552811179;4991.875203119924;5013.749312534377;4661.696989695676;4706.764661766915;4769.356136921515;4836.806527738886;4948.663860757593;3827.062369719423;3828.770273783568;3845.38473844185;3851.4415409765566;3872.7095467839886;3858.054776579728;3860.2665493556274;3860.285847118292;3877.806109694518;3884.242572698325;3656.7074634029345;3687.3547129252406;3766.114962983697;3826.770393776382;3831.4635950966886;3812.7331086823933;3827.311084004878;3831.4444405561317;3832.176082142325;3846.538415390143;3368.5620869286836;3378.527006219131;3381.966180338214;3389.068006298263;3389.745769067305;4645.605123564501;4741.762911854428;4767.451743049559;4787.8563643090765;4789.137955168068;4335.371371151197;4374.540673229329;4378.934315985262;4380.518143004267;4381.4961279452955;5133.537981581676;5135.87160320995;5136.974315128437;5141.434442211354;5142.280080788692;3690.372636651776;3690.630936906288;3695.334839728848;3704;3714.647108524713;5108.948910510921;5329.360476742816;5335.973320133413;5342.572594192456;5342.626016178869;3794.354959656865;3794.5256842894637;3800.4489349044234;3808.504894363715;3829.9042523937123;3849.6517124554;3859.749116307431;3862.24686186191;3880.2821478026653;3909.667678247381;4246.8513602024395;4389.648828093745;4439.267520859086;4453.153900758845;4453.977730111394;5069.315642388304;5086.847394578169;5099.490050994945;5109.540141387293;5145.871353216125;1257.534712156508;1336.8729970652773;1343.6036369270894;1344.4218985836185;1350.4260689207047;906.1255888067914;926.092429419173;939.8778158839307;942.472215559289;943.8537026760863;4422.84520041797;4440.977795111004;4472.300525800175;4479.745671212066;4480.462344518679;5204.869878252998;5221.451747566527;5232.529072383504;5248.737563121828;5271.736413179326;4346.348047792856;4457.487388950312;4462.553744625577;4470.083632855257;4471.831161886547;2782.3322402623226;2795.4968105740754;2804.8737806798745;2812.1001279590337;2814.915552533427;4913.115639184981;4936.703797772145;4942.308076869242;4947.268456040357;4947.5794557462295;5157.226416037549;5291.232771248147;5311.256424100629;5318.22885681574;5345.86635334112;4895.437024742129;4907.950920490821;4912.214045752662;4914.262860570943;4947.293183851565;5052.520010598987;5266.10476219036;5266.710330931835;5268.525832675077;5274.815381461631;5061.4938506150565;5087.186050231945;5097.209932460474;5101.954099409686;5113.948860511347;4884.4871288515615;4905.4358748742925;4911.705297682151;4912.508749125059;4934.827281045146;3756.323861704919;3827.1962887793875;3844.827327664977;3847.942280865733;3862.0537968197973;5198.740062996911;5207.9063396687125;5213.191955246943;5218.921716174184;5228.947710522998;5054.342935418372;5081.18701007837;5085.4406015337545;5086.753745332418;5134.922976155286;4905.435874874435;5091.516305950929;5106.974465127651;5112.923306150485;5131.102057139911;4858.4898585648;5078;5108.066788572853;5112.3865136183895;5122.385313762373;5072.28987941681;5185.351831021122;5205.479452054764;5206.349206349207;5214.7914083436235;5108.642395032312;5114.667546609458;5124.205748109049;5125.718085505333;5136.332276803992 01:58:28 INFO - PROCESS | 30015 | |5;yui.html;617.4597227426002;625.8599365199207;650.2684479960046;664.4850241063173;702.5047341624153;11758.118141139417;12361.072919531038;13372.197668139894;13548.729025419485;13597.252151131685;11334.149938754606;11338.036266917323;11341.37622430766;11345.37600431976;11347.262427942178;643.276314146585;776.9650365733527;788.6727008291552;792.0732742691036;793.5635400529715;753.7060546386914;766.2414209932155;766.3409467857638;780.5940910726447;782.5265714242886;807.5356669914793;808.9635966381527;809.1948511231333;809.2837838512929;809.5102463009882;665.986680266392;805.4200975297776;807.3258828877865;807.7132617920641;809.7004108479871;557.9804706835241;559.0524061715394;574.4399210769467;582.9883402331972;584.3659629302213;11945.761084778256;11954.163208575403;11962.282263064157;11987.820182697267;11988.100892433102;11704.47329870158;12214.57248996281;12225.572104976372;12321;12622.495100195982;9516.857247141299;9970.800583988279;11293.265937714104;11312.943435282854;11444.94277528607;4079.8572049978106;4166.104287578185;4174.457320548309;4177.26897792885;4180.059486615518;58707.70646146742;59606.403935960516;60436.69781651108;60516.697416513074;60576.69711651414;9412.247020238365;9696.6121355145;9733.9026609733;9851.50742462874;10154;17148.657026859393;17768.822311776974;17780.46658600244;17784.733229001577;18434.170462329228;6778.830529236809;6946.270641582662;6966.198887127944;7013.92986070143;7258.237885022102;3669.706423486168;3742.288965096623;3743.644353786386;3801.5248093988253;3814.5613254475534;2860.699626539225;3012.0813151988855;3013.5329024001317;3018.019143778281;3018.607581014454;521.119308368863;530.1993989076523;531.702246741818;535.9062164121341;552.9225908372831;500.8172017213691;526.6103083718021;536.4019118682719;562.1174755633698;571.3229822660161;7931.524108553505;8169.754907352789;8181.71364002269;8266.751997440086;8400.747977560683;498.2924247568462;503.1270745256976;506.43279526929336;512.595049910574;523.2386877093819;3814.8664796731982;4158.189153115113;4556.225441674923;4606.792694328763;4660.557247061523;95536;97168;97198;97305;104705.47647261867;49269.75365123223;49694.25458618124;50116.24825627619;64888.675556621914;87602.56198719093;76868;79045;79186.60406698045;81705.9999999988;82471.58764206141;8348.666053357798;8383.832323353621;8652.956735216283;8985.685501007434;9127.132922372364;44930;45342;45694.31458528058;46263.537364625925;47789;62817.37182628115;67822.66088669524;68330.31669683439;69678;69822;10829.67510974672;11033.944830275797;11221.326720396803;11519.712007199887;11904.642860714192;11927;12343;12360.320182390054;12472.314022728837;12575.748485030064;12242.632721018384;12575.434105465092;12692.619221423372;12697.55558555446;12708.046896482616;11629;11905.285682859056;12155.939220303842;12179.512819487121;12569.308688021882;11271.943640281746;11489.253198542068;11876;12308.26150430977;12320.199187052813;9817.950910245689;10244.846327305246;10300.330478518872;10759.13926885863;10819.134469242284;10147.188224942134;10384.896151038392;10453.020889046586;10880.238383313408;10888.61889833852 01:58:28 INFO - PROCESS | 30015 | __end_tp_report 01:58:28 INFO - PROCESS | 30015 | __start_cc_report 01:58:28 INFO - PROCESS | 30015 | _x_x_mozilla_cycle_collect,1018 01:58:28 INFO - PROCESS | 30015 | __end_cc_report 01:58:28 INFO - PROCESS | 30015 | __startTimestamp1470560308144__endTimestamp 01:58:28 INFO - PROCESS | 30015 | 01:58:28 INFO - PROCESS | 30015 | ------- Summary: start ------- 01:58:28 INFO - PROCESS | 30015 | Number of tests: 6 01:58:28 INFO - PROCESS | 30015 | 01:58:28 INFO - PROCESS | 30015 | [#0] dojo.html Cycles:165 Average:4680.17 Median:4801.23 stddev:496.11 (10.3%) stddev-sans-first:470.10 01:58:28 INFO - PROCESS | 30015 | Values: 2602.7 2606.4 2647.6 2652.4 2660.9 4518.1 4564.8 4696.6 4706.5 4725.4 4721.4 4746.1 4751.8 4823.2 4827.6 4180.6 4291.7 4346.9 4394.5 4521.7 4430.7 4450.4 4468.4 4478.1 4489.6 4154.5 4210.7 4213.1 4291.5 4351.9 4334.1 4335.6 4346.9 4390.0 4428.9 4341.4 4354.5 4371.6 4395.7 4399.3 4521.5 4740.5 4756.1 4803.7 4815.2 4957.5 5041.6 5047.5 5055.2 5102.8 5065.2 5081.5 5102.1 5110.6 5119.3 4528.4 4669.7 4761.6 4764.7 4769.8 5161.6 5173.1 5216.8 5228.3 5239.3 4953.6 4957.5 4960.5 5005.4 5008.8 4824.9 5036.4 5099.8 5100.3 5101.2 5027.3 5061.3 5063.4 5088.3 5092.6 5029.8 5042.9 5043.9 5049.7 5096.9 4076.0 4103.5 4317.4 4331.1 4358.7 3747.7 3762.5 3783.4 3824.7 3882.4 4379.3 4387.0 4388.5 4415.1 4433.6 4801.0 5111.2 5120.2 5122.3 5163.1 4174.9 4294.8 4324.1 4332.2 4368.1 4533.0 4554.8 4563.2 4564.7 4603.8 4739.2 4740.8 4746.0 4748.5 4801.2 4864.6 5005.0 5031.6 5036.3 5088.3 4710.8 4740.5 4740.9 4743.7 4788.8 4949.4 4949.6 4951.4 4955.0 5001.0 4906.8 5088.4 5095.8 5127.1 5180.2 4961.8 4974.7 4974.7 4976.7 5017.7 5012.3 5022.6 5024.3 5028.4 5076.9 4643.7 4863.6 4899.0 4905.8 4941.9 4990.4 4998.3 5011.9 5014.9 5061.3 4887.5 4903.3 4908.9 4910.1 4962.2 01:58:28 INFO - PROCESS | 30015 | 01:58:28 INFO - PROCESS | 30015 | [#1] ext.html Cycles:180 Average:52550.30 Median:37140.94 stddev:105130.12 (283.1%) stddev-sans-first:105352.55 01:58:28 INFO - PROCESS | 30015 | Values: 552.8 579.6 591.1 591.9 593.2 28217.7 30298.2 30680.5 30744.7 30909.2 31370.7 42961.6 43992.8 44053.6 44112.3 7607.6 7704.7 7716.7 7722.0 7727.5 7497.9 7691.7 7735.7 7821.6 7824.1 7666.3 7721.9 9933.7 10106.3 10130.1 10104.0 10137.7 10149.9 10155.2 10168.8 9798.9 9848.4 9851.5 9857.5 9865.5 29541.4 36689.9 37026.3 37071.6 40747.8 3060.4 3071.9 3094.3 3172.9 3203.8 28632.0 28931.4 29062.9 29082.0 29553.4 3207.8 3231.2 3360.9 3364.3 3403.7 251513.0 252038.9 253149.0 253408.0 254395.0 47239.0 54901.7 55311.0 55374.4 55415.4 55472.0 56618.4 56771.0 62375.0 62739.4 13090.1 13213.0 13214.7 13229.2 13253.7 9898.9 9971.3 9973.5 9979.4 9983.4 15928.7 17400.0 17463.8 17501.5 17571.3 6255.0 6803.8 7001.0 7142.4 7230.3 811.7 878.7 898.8 912.4 1017.8 28695.6 28732.9 28815.9 29266.6 29361.3 911.1 930.7 950.7 1024.5 1052.1 14893.5 15256.3 15330.5 15635.5 15672.1 388901.0 393378.0 394341.0 394558.0 394950.0 34097.5 34138.3 43545.3 48284.5 49014.3 1170.1 1196.8 1209.9 1227.2 1233.4 27095.0 29192.0 29334.9 29974.6 30042.7 42649.6 43121.6 43203.6 43222.8 43233.4 390417.0 400430.0 401892.0 621574.0 622521.0 38834.4 38936.0 39169.0 39397.8 39792.4 42053.6 42300.8 42688.8 42878.6 43967.0 36538.8 36870.6 37111.6 37464.8 37878.6 36818.8 37170.3 37470.8 37477.0 37827.4 35032.8 35797.6 36072.3 36371.5 36855.8 31143.2 33032.0 33116.3 33726.0 33728.8 22886.2 23365.2 23511.5 23581.0 23856.9 01:58:28 INFO - PROCESS | 30015 | 01:58:28 INFO - PROCESS | 30015 | [#2] jquery.html Cycles:180 Average:23326.64 Median:27869.39 stddev:60822.50 (218.2%) stddev-sans-first:60968.62 01:58:28 INFO - PROCESS | 30015 | Values: 334.8 358.3 370.2 374.0 385.9 12713.3 16332.3 16485.7 16791.7 17667.4 16495.8 17401.7 17857.3 18019.5 18050.9 4889.3 5105.0 5277.2 5283.1 5294.9 3744.6 5212.8 5306.6 5901.8 6197.0 6228.3 6237.3 6241.8 6254.2 6255.6 6033.9 6213.4 6221.2 6225.7 6237.0 4806.8 5451.0 5453.4 5456.9 5463.2 19941.2 20242.2 20426.7 20668.3 20851.6 1861.7 1928.4 2140.1 2291.2 2344.4 8837.4 8920.6 8949.2 9432.6 9654.6 1689.5 1690.3 1715.1 1721.6 1798.2 63655.0 64737.0 68327.7 68831.7 69029.3 9153.6 9444.5 9504.4 9555.3 9567.2 12652.2 12670.9 12670.9 12712.6 12745.0 6254.7 6442.5 6512.2 6520.5 6598.6 6020.9 6057.5 6180.2 6188.0 6246.6 4154.4 4157.8 4245.0 4293.0 4311.4 1605.4 1645.5 1702.5 1730.7 1784.1 720.9 837.3 958.1 966.6 980.5 17094.4 18104.5 18123.2 18378.3 18451.7 843.6 861.1 867.8 871.8 873.1 7750.6 9606.5 10013.4 10084.7 10342.7 363143.0 367163.0 370189.0 370193.0 372571.0 51455.0 68815.0 73955.0 73977.0 74479.6 912.4 919.1 931.6 949.3 952.5 10939.7 14882.6 15457.8 16038.5 16890.9 34442.1 36210.0 38272.2 38398.6 38485.8 48380.3 50849.5 50877.0 52120.7 52309.5 6626.5 6797.4 6844.5 7043.9 7248.6 8919.7 11078.6 11103.3 12186.0 12329.1 10108.5 10186.5 10365.7 10562.9 10747.6 9741.1 10209.3 10342.2 10611.8 10965.4 8577.6 9050.7 9996.9 10437.9 10441.8 7432.0 7484.6 7596.9 7614.1 7766.5 9426.0 10043.5 10346.3 10468.4 10557.7 01:58:28 INFO - PROCESS | 30015 | 01:58:28 INFO - PROCESS | 30015 | [#3] mootools.html Cycles:180 Average:5126.98 Median:5500.25 stddev:2893.14 (52.6%) stddev-sans-first:2887.47 01:58:28 INFO - PROCESS | 30015 | Values: 1368.5 1413.1 1478.7 1644.6 1680.9 7342.3 7838.7 7856.7 7863.0 7871.2 6375.9 7205.5 7381.6 7400.2 7708.6 2147.1 2175.6 2181.4 2182.3 2182.7 2113.0 2153.1 2188.4 2190.1 2192.1 2149.7 2152.2 2156.1 2159.1 2192.4 2129.8 2133.8 2134.3 2134.7 2135.6 2077.6 2112.5 2115.2 2115.7 2116.6 7596.1 7835.8 7953.1 7958.1 7958.2 4442.4 4575.0 4586.5 4586.6 4590.7 2750.2 2834.6 2903.0 2907.0 2911.4 841.9 883.4 902.5 903.5 908.0 10667.4 10681.5 10732.5 10741.6 10784.1 3897.3 4041.9 4181.4 4191.3 4192.4 9154.1 9155.8 9162.9 9163.7 9172.4 4457.5 4461.0 4465.8 4467.2 4471.6 3272.2 4008.1 4015.0 4018.4 4165.5 3521.6 3560.0 3669.8 3677.7 3692.7 1120.1 1165.8 1174.3 1180.3 1181.4 748.2 758.7 778.3 778.6 786.6 5265.1 5434.6 5524.9 5533.1 5667.5 870.3 879.9 890.4 908.3 924.7 3310.9 3344.5 3380.6 3395.1 3537.2 9372.9 9650.8 9719.1 9724.7 9727.2 9667.7 9675.8 9680.0 9693.9 9698.1 7373.2 7410.8 7494.5 7497.4 7503.7 5350.4 5403.2 5425.9 5476.8 5523.7 8807.2 9032.5 9051.0 9100.2 9183.5 8447.5 8829.0 8838.5 9179.4 9183.4 5866.3 6373.2 6616.9 6772.6 6775.8 7502.5 7528.8 7585.5 7599.8 7607.9 6612.1 6727.5 6765.5 6871.0 6945.9 6370.0 6494.0 6634.6 6749.0 6836.6 6337.7 6429.5 6437.5 6444.0 6450.5 4643.8 4666.7 4754.3 4783.8 4863.4 4620.5 4726.8 4737.3 4776.5 4864.2 01:58:28 INFO - PROCESS | 30015 | 01:58:28 INFO - PROCESS | 30015 | [#4] prototype.html Cycles:180 Average:4257.34 Median:4889.96 stddev:1217.88 (24.9%) stddev-sans-first:1184.20 01:58:28 INFO - PROCESS | 30015 | Values: 282.9 283.2 290.5 301.1 301.5 4905.4 4963.4 4978.9 4991.9 5013.7 4661.7 4706.8 4769.4 4836.8 4948.7 3827.1 3828.8 3845.4 3851.4 3872.7 3858.1 3860.3 3860.3 3877.8 3884.2 3656.7 3687.4 3766.1 3826.8 3831.5 3812.7 3827.3 3831.4 3832.2 3846.5 3368.6 3378.5 3382.0 3389.1 3389.7 4645.6 4741.8 4767.5 4787.9 4789.1 4335.4 4374.5 4378.9 4380.5 4381.5 5133.5 5135.9 5137.0 5141.4 5142.3 3690.4 3690.6 3695.3 3704.0 3714.6 5108.9 5329.4 5336.0 5342.6 5342.6 3794.4 3794.5 3800.4 3808.5 3829.9 3849.7 3859.7 3862.2 3880.3 3909.7 4246.9 4389.6 4439.3 4453.2 4454.0 5069.3 5086.8 5099.5 5109.5 5145.9 1257.5 1336.9 1343.6 1344.4 1350.4 906.1 926.1 939.9 942.5 943.9 4422.8 4441.0 4472.3 4479.7 4480.5 5204.9 5221.5 5232.5 5248.7 5271.7 4346.3 4457.5 4462.6 4470.1 4471.8 2782.3 2795.5 2804.9 2812.1 2814.9 4913.1 4936.7 4942.3 4947.3 4947.6 5157.2 5291.2 5311.3 5318.2 5345.9 4895.4 4908.0 4912.2 4914.3 4947.3 5052.5 5266.1 5266.7 5268.5 5274.8 5061.5 5087.2 5097.2 5102.0 5113.9 4884.5 4905.4 4911.7 4912.5 4934.8 3756.3 3827.2 3844.8 3847.9 3862.1 5198.7 5207.9 5213.2 5218.9 5228.9 5054.3 5081.2 5085.4 5086.8 5134.9 4905.4 5091.5 5107.0 5112.9 5131.1 4858.5 5078.0 5108.1 5112.4 5122.4 5072.3 5185.4 5205.5 5206.3 5214.8 5108.6 5114.7 5124.2 5125.7 5136.3 01:58:28 INFO - PROCESS | 30015 | 01:58:28 INFO - PROCESS | 30015 | [#5] yui.html Cycles:180 Average:17363.72 Median:25096.27 stddev:24616.88 (98.1%) stddev-sans-first:24653.82 01:58:28 INFO - PROCESS | 30015 | Values: 617.5 625.9 650.3 664.5 702.5 11758.1 12361.1 13372.2 13548.7 13597.3 11334.1 11338.0 11341.4 11345.4 11347.3 643.3 777.0 788.7 792.1 793.6 753.7 766.2 766.3 780.6 782.5 807.5 809.0 809.2 809.3 809.5 666.0 805.4 807.3 807.7 809.7 558.0 559.1 574.4 583.0 584.4 11945.8 11954.2 11962.3 11987.8 11988.1 11704.5 12214.6 12225.6 12321.0 12622.5 9516.9 9970.8 11293.3 11312.9 11444.9 4079.9 4166.1 4174.5 4177.3 4180.1 58707.7 59606.4 60436.7 60516.7 60576.7 9412.2 9696.6 9733.9 9851.5 10154.0 17148.7 17768.8 17780.5 17784.7 18434.2 6778.8 6946.3 6966.2 7013.9 7258.2 3669.7 3742.3 3743.6 3801.5 3814.6 2860.7 3012.1 3013.5 3018.0 3018.6 521.1 530.2 531.7 535.9 552.9 500.8 526.6 536.4 562.1 571.3 7931.5 8169.8 8181.7 8266.8 8400.7 498.3 503.1 506.4 512.6 523.2 3814.9 4158.2 4556.2 4606.8 4660.6 95536.0 97168.0 97198.0 97305.0 104705.5 49269.8 49694.3 50116.2 64888.7 87602.6 76868.0 79045.0 79186.6 81706.0 82471.6 8348.7 8383.8 8653.0 8985.7 9127.1 44930.0 45342.0 45694.3 46263.5 47789.0 62817.4 67822.7 68330.3 69678.0 69822.0 10829.7 11033.9 11221.3 11519.7 11904.6 11927.0 12343.0 12360.3 12472.3 12575.7 12242.6 12575.4 12692.6 12697.6 12708.0 11629.0 11905.3 12155.9 12179.5 12569.3 11271.9 11489.3 11876.0 12308.3 12320.2 9818.0 10244.8 10300.3 10759.1 10819.1 10147.2 10384.9 10453.0 10880.2 10888.6 01:58:28 INFO - PROCESS | 30015 | -------- Summary: end -------- 01:58:28 INFO - PROCESS | 30015 | 01:58:29 INFO - TEST-INFO | 30015: exit 0 01:58:29 INFO - TEST-OK | dromaeo_css | took 1120034ms 01:58:29 INFO - TEST-START | kraken 01:58:29 INFO - operating with platform_type : linux_ 01:58:29 INFO - Initialising browser for kraken test... 01:58:29 INFO - TEST-INFO | started process 30202 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpl_rx1S/profile http://localhost:43418/getInfo.html) 01:58:33 INFO - PROCESS | 30202 | __metrics Screen width/height:1600/1200 01:58:33 INFO - PROCESS | 30202 | colorDepth:24 01:58:33 INFO - PROCESS | 30202 | Browser inner width/height: 1024/697 01:58:33 INFO - PROCESS | 30202 | __metrics 01:58:34 INFO - PROCESS | 30202 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 01:58:34 INFO - PROCESS | 30202 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 01:58:35 INFO - TEST-INFO | 30202: exit 0 01:58:35 INFO - Browser initialized. 01:58:35 INFO - Running cycle 1/1 for kraken test... 01:58:35 INFO - TEST-INFO | started process 30285 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpl_rx1S/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:58:47 INFO - PROCESS | 30285 | RSS: Main: 157413376 01:58:47 INFO - PROCESS | 30285 | 01:58:49 INFO - PROCESS | 30285 | Cycle 1(1): loaded http://localhost:43418/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:43418/tests/kraken/driver.html?testName=audio-beat-detection) 01:58:50 INFO - PROCESS | 30285 | RSS: Main: 214175744 01:58:50 INFO - PROCESS | 30285 | 01:58:53 INFO - PROCESS | 30285 | Cycle 1(1): loaded http://localhost:43418/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:43418/tests/kraken/driver.html?testName=audio-dft) 01:58:53 INFO - PROCESS | 30285 | RSS: Main: 192229376 01:58:53 INFO - PROCESS | 30285 | 01:58:57 INFO - PROCESS | 30285 | Cycle 1(1): loaded http://localhost:43418/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:43418/tests/kraken/driver.html?testName=audio-fft) 01:58:57 INFO - PROCESS | 30285 | RSS: Main: 271630336 01:58:57 INFO - PROCESS | 30285 | 01:58:59 INFO - PROCESS | 30285 | Cycle 1(1): loaded http://localhost:43418/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:43418/tests/kraken/driver.html?testName=audio-oscillator) 01:59:00 INFO - PROCESS | 30285 | RSS: Main: 180502528 01:59:00 INFO - PROCESS | 30285 | 01:59:02 INFO - PROCESS | 30285 | Cycle 1(1): loaded http://localhost:43418/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:43418/tests/kraken/driver.html?testName=imaging-gaussian-blur) 01:59:03 INFO - PROCESS | 30285 | RSS: Main: 184430592 01:59:03 INFO - PROCESS | 30285 | 01:59:06 INFO - PROCESS | 30285 | Cycle 1(1): loaded http://localhost:43418/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:43418/tests/kraken/driver.html?testName=imaging-darkroom) 01:59:07 INFO - PROCESS | 30285 | RSS: Main: 221310976 01:59:07 INFO - PROCESS | 30285 | 01:59:10 INFO - PROCESS | 30285 | Cycle 1(1): loaded http://localhost:43418/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:43418/tests/kraken/driver.html?testName=imaging-desaturate) 01:59:11 INFO - PROCESS | 30285 | RSS: Main: 215171072 01:59:11 INFO - PROCESS | 30285 | 01:59:14 INFO - PROCESS | 30285 | Cycle 1(1): loaded http://localhost:43418/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:43418/tests/kraken/driver.html?testName=json-parse-financial) 01:59:14 INFO - PROCESS | 30285 | RSS: Main: 218660864 01:59:14 INFO - PROCESS | 30285 | 01:59:16 INFO - PROCESS | 30285 | Cycle 1(1): loaded http://localhost:43418/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:43418/tests/kraken/driver.html?testName=json-stringify-tinderbox) 01:59:17 INFO - PROCESS | 30285 | RSS: Main: 184463360 01:59:17 INFO - PROCESS | 30285 | 01:59:18 INFO - PROCESS | 30285 | Cycle 1(1): loaded http://localhost:43418/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:43418/tests/kraken/driver.html?testName=stanford-crypto-aes) 01:59:19 INFO - PROCESS | 30285 | RSS: Main: 188743680 01:59:19 INFO - PROCESS | 30285 | 01:59:21 INFO - PROCESS | 30285 | Cycle 1(1): loaded http://localhost:43418/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:43418/tests/kraken/driver.html?testName=stanford-crypto-ccm) 01:59:22 INFO - PROCESS | 30285 | RSS: Main: 193966080 01:59:22 INFO - PROCESS | 30285 | 01:59:25 INFO - PROCESS | 30285 | Cycle 1(1): loaded http://localhost:43418/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:43418/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 01:59:25 INFO - PROCESS | 30285 | RSS: Main: 189853696 01:59:25 INFO - PROCESS | 30285 | 01:59:28 INFO - PROCESS | 30285 | Cycle 1(1): loaded http://localhost:43418/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:43418/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 01:59:29 INFO - PROCESS | 30285 | RSS: Main: 191844352 01:59:29 INFO - PROCESS | 30285 | 01:59:31 INFO - PROCESS | 30285 | Cycle 1(1): loaded http://localhost:43418/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:43418/tests/kraken/driver.html?testName=ai-astar) 01:59:31 INFO - PROCESS | 30285 | RSS: Main: 242995200 01:59:31 INFO - PROCESS | 30285 | 01:59:31 INFO - PROCESS | 30285 | __start_tp_report 01:59:31 INFO - PROCESS | 30285 | _x_x_mozilla_page_load 01:59:31 INFO - PROCESS | 30285 | _x_x_mozilla_page_load_details 01:59:31 INFO - PROCESS | 30285 | |i|pagename|runs| 01:59:31 INFO - PROCESS | 30285 | |0;ai-astar;104;116;112;114;114;114;114;114;113;112 01:59:31 INFO - PROCESS | 30285 | |1;audio-beat-detection;147;121;123;183;123;124;145;124;181;127 01:59:31 INFO - PROCESS | 30285 | |2;audio-dft;161;161;160;160;162;160;161;161;162;161 01:59:31 INFO - PROCESS | 30285 | |3;audio-fft;74;75;74;106;76;76;75;75;106;77 01:59:31 INFO - PROCESS | 30285 | |4;audio-oscillator;85;85;132;86;86;87;123;86;85;119 01:59:31 INFO - PROCESS | 30285 | |5;imaging-gaussian-blur;117;116;117;117;116;116;117;117;117;116 01:59:31 INFO - PROCESS | 30285 | |6;imaging-darkroom;146;145;146;146;145;146;146;148;147;146 01:59:31 INFO - PROCESS | 30285 | |7;imaging-desaturate;96;95;96;96;95;95;95;95;95;94 01:59:31 INFO - PROCESS | 30285 | |8;json-parse-financial;63;62;63;64;64;65;73;64;65;63 01:59:31 INFO - PROCESS | 30285 | |9;json-stringify-tinderbox;67;66;65;64;66;65;65;66;65;66 01:59:31 INFO - PROCESS | 30285 | |10;stanford-crypto-aes;86;79;88;85;79;79;79;79;79;84 01:59:31 INFO - PROCESS | 30285 | |11;stanford-crypto-ccm;133;129;130;129;127;131;128;127;149;130 01:59:31 INFO - PROCESS | 30285 | |12;stanford-crypto-pbkdf2;166;165;168;166;167;173;167;167;163;167 01:59:31 INFO - PROCESS | 30285 | |13;stanford-crypto-sha256-iterative;67;67;71;68;71;68;67;67;66;68 01:59:31 INFO - PROCESS | 30285 | __end_tp_report 01:59:31 INFO - PROCESS | 30285 | __start_cc_report 01:59:31 INFO - PROCESS | 30285 | _x_x_mozilla_cycle_collect,1892 01:59:31 INFO - PROCESS | 30285 | __end_cc_report 01:59:31 INFO - PROCESS | 30285 | __startTimestamp1470560371279__endTimestamp 01:59:31 INFO - PROCESS | 30285 | 01:59:31 INFO - PROCESS | 30285 | ------- Summary: start ------- 01:59:31 INFO - PROCESS | 30285 | Number of tests: 14 01:59:31 INFO - PROCESS | 30285 | 01:59:31 INFO - PROCESS | 30285 | [#0] ai-astar Cycles:10 Average:112.70 Median:114.00 stddev:3.27 (2.9%) stddev-sans-first:1.22 01:59:31 INFO - PROCESS | 30285 | Values: 104.0 116.0 112.0 114.0 114.0 114.0 114.0 114.0 113.0 112.0 01:59:31 INFO - PROCESS | 30285 | 01:59:31 INFO - PROCESS | 30285 | [#1] audio-beat-detection Cycles:10 Average:139.80 Median:136.00 stddev:24.09 (17.7%) stddev-sans-first:25.41 01:59:31 INFO - PROCESS | 30285 | Values: 147.0 121.0 123.0 183.0 123.0 124.0 145.0 124.0 181.0 127.0 01:59:31 INFO - PROCESS | 30285 | 01:59:31 INFO - PROCESS | 30285 | [#2] audio-dft Cycles:10 Average:160.90 Median:161.00 stddev:0.74 (0.5%) stddev-sans-first:0.78 01:59:31 INFO - PROCESS | 30285 | Values: 161.0 161.0 160.0 160.0 162.0 160.0 161.0 161.0 162.0 161.0 01:59:31 INFO - PROCESS | 30285 | 01:59:31 INFO - PROCESS | 30285 | [#3] audio-fft Cycles:10 Average:81.40 Median:75.00 stddev:13.00 (17.3%) stddev-sans-first:13.51 01:59:31 INFO - PROCESS | 30285 | Values: 74.0 75.0 74.0 106.0 76.0 76.0 75.0 75.0 106.0 77.0 01:59:31 INFO - PROCESS | 30285 | 01:59:31 INFO - PROCESS | 30285 | [#4] audio-oscillator Cycles:10 Average:97.40 Median:85.50 stddev:19.09 (22.3%) stddev-sans-first:19.71 01:59:31 INFO - PROCESS | 30285 | Values: 85.0 85.0 132.0 86.0 86.0 87.0 123.0 86.0 85.0 119.0 01:59:31 INFO - PROCESS | 30285 | 01:59:31 INFO - PROCESS | 30285 | [#5] imaging-gaussian-blur Cycles:10 Average:116.60 Median:117.00 stddev:0.52 (0.4%) stddev-sans-first:0.53 01:59:31 INFO - PROCESS | 30285 | Values: 117.0 116.0 117.0 117.0 116.0 116.0 117.0 117.0 117.0 116.0 01:59:31 INFO - PROCESS | 30285 | 01:59:31 INFO - PROCESS | 30285 | [#6] imaging-darkroom Cycles:10 Average:146.10 Median:146.00 stddev:0.88 (0.6%) stddev-sans-first:0.93 01:59:31 INFO - PROCESS | 30285 | Values: 146.0 145.0 146.0 146.0 145.0 146.0 146.0 148.0 147.0 146.0 01:59:31 INFO - PROCESS | 30285 | 01:59:31 INFO - PROCESS | 30285 | [#7] imaging-desaturate Cycles:10 Average:95.20 Median:95.00 stddev:0.63 (0.7%) stddev-sans-first:0.60 01:59:31 INFO - PROCESS | 30285 | Values: 96.0 95.0 96.0 96.0 95.0 95.0 95.0 95.0 95.0 94.0 01:59:31 INFO - PROCESS | 30285 | 01:59:31 INFO - PROCESS | 30285 | [#8] json-parse-financial Cycles:10 Average:64.60 Median:64.00 stddev:3.10 (4.8%) stddev-sans-first:3.23 01:59:31 INFO - PROCESS | 30285 | Values: 63.0 62.0 63.0 64.0 64.0 65.0 73.0 64.0 65.0 63.0 01:59:31 INFO - PROCESS | 30285 | 01:59:31 INFO - PROCESS | 30285 | [#9] json-stringify-tinderbox Cycles:10 Average:65.50 Median:66.00 stddev:0.85 (1.3%) stddev-sans-first:0.71 01:59:31 INFO - PROCESS | 30285 | Values: 67.0 66.0 65.0 64.0 66.0 65.0 65.0 66.0 65.0 66.0 01:59:31 INFO - PROCESS | 30285 | 01:59:31 INFO - PROCESS | 30285 | [#10] stanford-crypto-aes Cycles:10 Average:81.70 Median:81.50 stddev:3.62 (4.4%) stddev-sans-first:3.49 01:59:31 INFO - PROCESS | 30285 | Values: 86.0 79.0 88.0 85.0 79.0 79.0 79.0 79.0 79.0 84.0 01:59:31 INFO - PROCESS | 30285 | 01:59:31 INFO - PROCESS | 30285 | [#11] stanford-crypto-ccm Cycles:10 Average:131.30 Median:130.00 stddev:6.48 (5.0%) stddev-sans-first:6.85 01:59:31 INFO - PROCESS | 30285 | Values: 133.0 129.0 130.0 129.0 127.0 131.0 128.0 127.0 149.0 130.0 01:59:31 INFO - PROCESS | 30285 | 01:59:31 INFO - PROCESS | 30285 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.90 Median:167.00 stddev:2.56 (1.5%) stddev-sans-first:2.69 01:59:31 INFO - PROCESS | 30285 | Values: 166.0 165.0 168.0 166.0 167.0 173.0 167.0 167.0 163.0 167.0 01:59:31 INFO - PROCESS | 30285 | 01:59:31 INFO - PROCESS | 30285 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.00 Median:68.00 stddev:1.70 (2.5%) stddev-sans-first:1.76 01:59:31 INFO - PROCESS | 30285 | Values: 67.0 67.0 71.0 68.0 71.0 68.0 67.0 67.0 66.0 68.0 01:59:31 INFO - PROCESS | 30285 | -------- Summary: end -------- 01:59:31 INFO - PROCESS | 30285 | 01:59:32 INFO - TEST-INFO | 30285: exit 0 01:59:32 INFO - TEST-OK | kraken | took 63349ms 01:59:32 INFO - SUITE-END | took 1183s 01:59:32 INFO - Completed test suite (00:19:43) 01:59:32 INFO - javascript benchmark 01:59:32 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2602.726713695063, 2606.413556949686, 2647.563152079907, 2652.3899503114294, 2660.9467810643787, 4518.118966801475, 4564.817407303713, 4696.624270058392, 4706.505816889225, 4725.385699859022, 4721.362616046833, 4746.050789842028, 4751.762411879409, 4823.155947709154, 4827.6137908967285, 4180.644645205161, 4291.6566674665955, 4346.913061738764, 4394.472663280395, 4521.728696278234, 4430.6898517103655, 4450.376947227391, 4468.396766436538, 4478.126765280772, 4489.618382437497, 4154.459920210354, 4210.7473551587, 4213.073123912734, 4291.463567054118, 4351.869443916656, 4334.111507141029, 4335.56644335567, 4346.913061738779, 4389.956100438987, 4428.933565996513, 4341.370501277297, 4354.521002689701, 4371.628411585019, 4395.714278571914, 4399.274119770234, 4521.502634710212, 4740.502247264022, 4756.07256584966, 4803.7357945313, 4815.229563269895, 4957.5042495750495, 5041.647084704072, 5047.545720885138, 5055.241713742931, 5102.770375333119, 5065.214891691757, 5081.517255860687, 5102.132637451605, 5110.616703747234, 5119.257707632372, 4528.43394575678, 4669.719816811003, 4761.59526440253, 4764.690295130806, 4769.785359658824, 5161.561267292247, 5173.068847607392, 5216.765245563959, 5228.2941802856885, 5239.345081864767, 4953.628477864175, 4957.454679985199, 4960.479149689303, 5005.399352077774, 5008.774605142778, 4824.927626085611, 5036.445990940993, 5099.847004589868, 5100.285959965608, 5101.234814777813, 5027.271045698353, 5061.291419201315, 5063.443021267658, 5088.338515992971, 5092.567131793765, 5029.798808047711, 5042.873928151752, 5043.899122017612, 5049.722265275445, 5096.949030509648, 4076.0, 4103.507579090468, 4317.417148684887, 4331.148240595059, 4358.673099517486, 3747.6814470769746, 3762.473253744478, 3783.39465685489, 3824.6749026333073, 3882.398228274622, 4379.255526560492, 4386.978065109654, 4388.473383193973, 4415.116976604692, 4433.556644335541, 4801.039792041605, 5111.233315002779, 5120.206368012964, 5122.257272695513, 5163.122269214243, 4174.937375939363, 4294.763787991628, 4324.070324880166, 4332.1985432695055, 4368.08270263242, 4533.048059907456, 4554.817807287738, 4563.247064234364, 4564.748938808398, 4603.815847366068, 4739.194336962656, 4740.786664600032, 4745.976270118766, 4748.548887855648, 4801.231802911518, 4864.610831133572, 5005.0, 5031.597472202142, 5036.269740887477, 5088.338515992898, 4710.8351207707565, 4740.454847692559, 4740.928886066781, 4743.739094349844, 4788.784504697227, 4949.430815456269, 4949.554540091409, 4951.356323677899, 4955.0, 5001.049800537886, 4906.754662266943, 5088.414832294334, 5095.821646242363, 5127.051495473349, 5180.248863914787, 4961.801527938842, 4974.701517908937, 4974.726390048582, 4976.651634385558, 5017.698938063728, 5012.3233363495465, 5022.648414610942, 5024.321716568216, 5028.371453568304, 5076.923846142385, 4643.698159619614, 4863.562279394871, 4899.0, 4905.8037678492465, 4941.876453088702, 4990.351254336913, 4998.300237966615, 5011.924821127613, 5014.87462813418, 5061.316722242597, 4887.486813884491, 4903.31353610502, 4908.852734417973, 4910.091633047898, 4962.230854217602], "value": 4648.408977932524, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [552.8049733834642, 579.6145563200472, 591.1398914579288, 591.8964181268277, 593.1932571702483, 28217.717822821793, 30298.242543936412, 30680.53979190314, 30744.692553074492, 30909.22726931828, 31370.686293137118, 42961.570384296145, 43992.78003609985, 44053.55946440535, 44112.3383149253, 7607.581583012946, 7704.691812327528, 7716.6913323467, 7722.034745656793, 7727.459077864552, 7497.925020749804, 7691.692332306701, 7735.72924947627, 7821.648025838851, 7824.06111266646, 7666.2717041881215, 7721.922780772176, 9933.652322168726, 10106.292559520836, 10130.138938190263, 10103.989601039912, 10137.695869123938, 10149.89850101497, 10155.238357123171, 10168.79662406755, 9798.853017204749, 9848.35985660937, 9851.507424628811, 9857.507124643811, 9865.50672466381, 29541.40917181644, 36689.88136008442, 37026.25947481062, 37071.629283707356, 40747.796261018804, 3060.4491191585507, 3071.9442616298993, 3094.3347180356104, 3172.904812855618, 3203.823789691566, 28632.0, 28931.421371572662, 29062.85468572665, 29082.0, 29553.408931821243, 3207.83960801959, 3231.2083539532728, 3360.899173024813, 3364.34395292918, 3403.710684591813, 251512.99999999817, 252038.91844326205, 253149.0, 253408.0, 254395.0, 47239.0, 54901.725491372286, 55311.0, 55374.44625553693, 55415.445845541835, 55472.0, 56618.43381566214, 56771.0, 62375.0, 62739.372606273355, 13090.08369414151, 13213.0, 13214.669633259053, 13229.206247625174, 13253.66865828362, 9898.851517227247, 9971.252156088178, 9973.451460169761, 9979.351342162736, 9983.400995940267, 15928.681426371408, 17400.0, 17463.825361746472, 17501.47495575135, 17571.297148114187, 6254.998400511792, 6803.829904252334, 7001.0, 7142.357187853118, 7230.313120253568, 811.6834434570522, 878.7188099808, 898.820235952812, 912.3567884641312, 1017.8269694152012, 28695.56956645652, 28732.856335718607, 28815.855920720263, 29266.561001584992, 29361.265968350963, 911.0618256124079, 930.734740598926, 950.7337945375306, 1024.533837104116, 1052.1109662335314, 14893.47872824446, 15256.313465894062, 15330.463433779763, 15635.530934071996, 15672.138032408327, 388901.0, 393378.0, 394341.0, 394558.0, 394950.0, 34097.488537671954, 34138.31723365519, 43545.34681979773, 48284.5171548287, 49014.26478602824, 1170.0814860334597, 1196.772613203506, 1209.9455524501243, 1227.1961864978452, 1233.3956361382857, 27095.0, 29192.0, 29334.853325733235, 29974.550381743855, 30042.69957300399, 42649.57350426456, 43121.568784311756, 43203.56796432121, 43222.78388608037, 43233.3514997269, 390417.0, 400430.0, 401892.0, 621574.0, 622521.0, 38834.41748373833, 38936.026599335244, 39169.0, 39397.80301098476, 39792.403113952736, 42053.57946420619, 42300.78849605732, 42688.78655606702, 42878.571214287454, 43967.0, 36538.8173059133, 36870.63129368672, 37111.628883710815, 37464.812675936446, 37878.62121378861, 36818.815905920295, 37170.25659486849, 37470.812645936596, 37477.0, 37827.43258851174, 35032.82483587566, 35797.64202357943, 36072.27855442929, 36371.45442818413, 36855.81572092122, 31143.221419464695, 33032.0, 33116.33767324688, 33726.0, 33728.83135584307, 22886.198983035512, 23365.182218622267, 23511.529769404857, 23581.0, 23856.88071559631], "value": 18027.967871929988, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [334.7706820827735, 358.3048885162784, 370.20221422833794, 373.97943113128775, 385.87073330434305, 12713.300768457731, 16332.265048072835, 16485.67028659426, 16791.664166716688, 17667.381641642543, 16495.83504164958, 17401.651966960653, 17857.285708571642, 18019.549511262227, 18050.90974545129, 4889.291052797351, 5104.974475127619, 5277.234800953859, 5283.07546179419, 5294.867628309304, 3744.644258795424, 5212.791488340481, 5306.575473962093, 5901.822945311626, 6196.9690151549175, 6228.283747369042, 6237.251529816405, 6241.812745617639, 6254.249490061199, 6255.593386429868, 6033.909491357633, 6213.378662133773, 6221.160143380631, 6225.688715564249, 6237.033259844731, 4806.8077276909235, 5450.964316779799, 5453.3728621208675, 5456.863578410572, 5463.2078348639625, 19941.202351905908, 20242.190312387487, 20426.69359959602, 20668.276610318717, 20851.5829683407, 1861.6835138026568, 1928.421473557941, 2140.1011575138477, 2291.163725240292, 2344.3904584808074, 8837.38138330317, 8920.553972301359, 8949.239314658196, 9432.575534100983, 9654.613815447445, 1689.510042087793, 1690.2562872336255, 1715.1424287856073, 1721.5782133377402, 1798.235749806327, 63655.0, 64736.99999999953, 68327.65836170787, 68831.65584172047, 69029.3097069033, 9153.63385464574, 9444.480553569481, 9504.382215155993, 9555.331126821056, 9567.186789123003, 12652.17760845542, 12670.873291267153, 12670.936645316715, 12712.55506057302, 12745.0, 6254.718537665816, 6442.48460123189, 6512.218533775977, 6520.510961677893, 6598.571092879043, 6020.939790602126, 6057.4851137653795, 6180.227471566054, 6188.040853667689, 6246.625202487865, 4154.439150714676, 4157.833686652561, 4245.0, 4292.957070429318, 4311.417958575552, 1605.3819279577447, 1645.522798388454, 1702.5318037539776, 1730.6884760742935, 1784.1079460269866, 720.9170945341248, 837.3050368194384, 958.0563145301873, 966.6278482784179, 980.548947484151, 17094.40169594058, 18104.456866294033, 18123.21346472916, 18378.264869405342, 18451.723224151647, 843.5782108945527, 861.1130535548396, 867.8394497018072, 871.7994861181963, 873.1050673060038, 7750.612469376509, 9606.459533101042, 10013.447915573099, 10084.747881303027, 10342.741431464274, 363143.0, 367163.0, 370189.0, 370193.0, 372571.0, 51455.0, 68815.0, 73955.0, 73977.0, 74479.62760186165, 912.4434095201922, 919.1268295119529, 931.5528546297815, 949.2880339745191, 952.476138124029, 10939.671809845717, 14882.553523393883, 15457.84542154609, 16038.518844434686, 16890.915545422195, 34442.13894652654, 36210.0, 38272.2345553093, 38398.616013839506, 38485.807570961966, 48380.274295886295, 50849.49150508447, 50877.0, 52120.73939630278, 52309.47690523046, 6626.469882409492, 6797.354251346114, 6844.486663500158, 7043.929560704532, 7248.637568121468, 8919.73240802751, 11078.5568577256, 11103.333799972028, 12186.0, 12329.136960412685, 10108.545115469675, 10186.541605627914, 10365.689029329133, 10562.947185264025, 10747.623833165802, 9741.123298903132, 10209.336393134423, 10342.172626189737, 10611.840822387516, 10965.396903170084, 8577.571121443778, 9050.728478145666, 9996.850047249152, 10437.947810260901, 10441.843372349573, 7432.0, 7484.588347640932, 7596.886046709194, 7614.124375696866, 7766.495177813424, 9426.0, 10043.497825108569, 10346.32748871321, 10468.371897686164, 10557.683269501927], "value": 8092.001965792077, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1368.5004973184784, 1413.109740863256, 1478.6599082211092, 1644.5559698881295, 1680.9075500847448, 7342.302481264278, 7838.72564460244, 7856.685732570704, 7863.0, 7871.173526779678, 6375.904361434582, 7205.495615306931, 7381.557106573609, 7400.185979542246, 7708.57602831844, 2147.066026278566, 2175.5540114276537, 2181.3892110209167, 2182.2689399051337, 2182.7380714314304, 2113.0, 2153.0741781034226, 2188.3763127508664, 2190.080166330137, 2192.1121945612053, 2149.656055031195, 2152.2251989283845, 2156.105216335219, 2159.0500179920787, 2192.3642143778366, 2129.7763734807777, 2133.8292936565194, 2134.3063504361144, 2134.658454647249, 2135.6049130910674, 2077.574097310048, 2112.493001679601, 2115.1962254343407, 2115.693224482457, 2116.619008578455, 7596.050493688289, 7835.764927052198, 7953.12515623281, 7958.084820245688, 7958.243966823143, 4442.355858400514, 4575.0, 4586.495485496593, 4586.610138138262, 4590.724556526619, 2750.2024412920227, 2834.6031555582235, 2902.954936222979, 2906.9709302906913, 2911.432270707213, 841.8947631546057, 883.3683916000026, 902.5036230073433, 903.4759839293195, 907.981840363189, 10667.359958402521, 10681.519331630017, 10732.517036733288, 10741.624043158452, 10784.083352914933, 3897.2595206910596, 4041.8585349513214, 4181.435506206623, 4191.266528357525, 4192.392103145027, 9154.084591540792, 9155.81688366229, 9162.908370916339, 9163.679271225608, 9172.403793753518, 4457.465104187453, 4461.0, 4465.754383508938, 4467.173572889026, 4471.642268618502, 3272.2310257089566, 4008.118213992917, 4015.0, 4018.3771515415156, 4165.45849039629, 3521.6126226115107, 3559.982200088983, 3669.761465504787, 3677.687396571268, 3692.7415080944074, 1120.1151090638468, 1165.7959857025157, 1174.3247632611283, 1180.2741314091772, 1181.3797756178076, 748.2069006852754, 758.6623952341265, 778.2567647896245, 778.6457161991281, 786.6420778545748, 5265.131253343232, 5434.619576629598, 5524.944750552443, 5533.114701647717, 5667.4615911488345, 870.2733217763113, 879.8856148700756, 890.3900827932887, 908.2688435809237, 924.7040946897063, 3310.9172270692943, 3344.5484859544135, 3380.577427821522, 3395.1002984209204, 3537.239493508908, 9372.90627093734, 9650.806983860284, 9719.076687714656, 9724.659636912675, 9727.173190278763, 9667.70996870081, 9675.758106047406, 9679.951600242095, 9693.9030609693, 9698.078682525149, 7373.225811289737, 7410.814729631695, 7494.5128566643, 7497.362724168507, 7503.662335194929, 5350.411454739898, 5403.162509810985, 5425.891482170414, 5476.808311709071, 5523.696196709219, 8807.163319484638, 9032.548372581476, 9050.954745226232, 9100.22648074921, 9183.494907780136, 8447.493150410995, 8829.028806831115, 8838.513881736566, 9179.449233046038, 9183.448993060438, 5866.266716660418, 6373.171487706569, 6616.933830661825, 6772.55978361405, 6775.830604234737, 7502.549847008979, 7528.811779705551, 7585.544867307979, 7599.772006839804, 7607.923920760722, 6612.140421745335, 6727.529072965041, 6765.458763298826, 6871.0, 6945.861082778417, 6370.044493326038, 6493.967530162319, 6634.635095069818, 6748.966255168692, 6836.555623884432, 6337.683115844097, 6429.485641148791, 6437.485001199986, 6443.96778016107, 6450.451711604566, 4643.8142474302, 4666.696664716783, 4754.286856971482, 4783.784729687103, 4863.440704318907, 4620.491745907881, 4726.841923728708, 4737.3130896020575, 4776.498467660985, 4864.173090574682], "value": 4114.161039054022, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [282.8628115364048, 283.15009430086184, 290.51484021683785, 301.1162238829037, 301.47994709126743, 4905.386826646669, 4963.404391473019, 4978.87552811179, 4991.875203119924, 5013.749312534377, 4661.696989695676, 4706.764661766915, 4769.356136921515, 4836.806527738886, 4948.663860757593, 3827.062369719423, 3828.770273783568, 3845.38473844185, 3851.4415409765566, 3872.7095467839886, 3858.054776579728, 3860.2665493556274, 3860.285847118292, 3877.806109694518, 3884.242572698325, 3656.7074634029345, 3687.3547129252406, 3766.114962983697, 3826.770393776382, 3831.4635950966886, 3812.7331086823933, 3827.311084004878, 3831.4444405561317, 3832.176082142325, 3846.538415390143, 3368.5620869286836, 3378.527006219131, 3381.966180338214, 3389.068006298263, 3389.745769067305, 4645.605123564501, 4741.762911854428, 4767.451743049559, 4787.8563643090765, 4789.137955168068, 4335.371371151197, 4374.540673229329, 4378.934315985262, 4380.518143004267, 4381.4961279452955, 5133.537981581676, 5135.87160320995, 5136.974315128437, 5141.434442211354, 5142.280080788692, 3690.372636651776, 3690.630936906288, 3695.334839728848, 3704.0, 3714.647108524713, 5108.948910510921, 5329.360476742816, 5335.973320133413, 5342.572594192456, 5342.626016178869, 3794.354959656865, 3794.5256842894637, 3800.4489349044234, 3808.504894363715, 3829.9042523937123, 3849.6517124554, 3859.749116307431, 3862.24686186191, 3880.2821478026653, 3909.667678247381, 4246.8513602024395, 4389.648828093745, 4439.267520859086, 4453.153900758845, 4453.977730111394, 5069.315642388304, 5086.847394578169, 5099.490050994945, 5109.540141387293, 5145.871353216125, 1257.534712156508, 1336.8729970652773, 1343.6036369270894, 1344.4218985836185, 1350.4260689207047, 906.1255888067914, 926.092429419173, 939.8778158839307, 942.472215559289, 943.8537026760863, 4422.84520041797, 4440.977795111004, 4472.300525800175, 4479.745671212066, 4480.462344518679, 5204.869878252998, 5221.451747566527, 5232.529072383504, 5248.737563121828, 5271.736413179326, 4346.348047792856, 4457.487388950312, 4462.553744625577, 4470.083632855257, 4471.831161886547, 2782.3322402623226, 2795.4968105740754, 2804.8737806798745, 2812.1001279590337, 2814.915552533427, 4913.115639184981, 4936.703797772145, 4942.308076869242, 4947.268456040357, 4947.5794557462295, 5157.226416037549, 5291.232771248147, 5311.256424100629, 5318.22885681574, 5345.86635334112, 4895.437024742129, 4907.950920490821, 4912.214045752662, 4914.262860570943, 4947.293183851565, 5052.520010598987, 5266.10476219036, 5266.710330931835, 5268.525832675077, 5274.815381461631, 5061.4938506150565, 5087.186050231945, 5097.209932460474, 5101.954099409686, 5113.948860511347, 4884.4871288515615, 4905.4358748742925, 4911.705297682151, 4912.508749125059, 4934.827281045146, 3756.323861704919, 3827.1962887793875, 3844.827327664977, 3847.942280865733, 3862.0537968197973, 5198.740062996911, 5207.9063396687125, 5213.191955246943, 5218.921716174184, 5228.947710522998, 5054.342935418372, 5081.18701007837, 5085.4406015337545, 5086.753745332418, 5134.922976155286, 4905.435874874435, 5091.516305950929, 5106.974465127651, 5112.923306150485, 5131.102057139911, 4858.4898585648, 5078.0, 5108.066788572853, 5112.3865136183895, 5122.385313762373, 5072.28987941681, 5185.351831021122, 5205.479452054764, 5206.349206349207, 5214.7914083436235, 5108.642395032312, 5114.667546609458, 5124.205748109049, 5125.718085505333, 5136.332276803992], "value": 3870.35720608869, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [617.4597227426002, 625.8599365199207, 650.2684479960046, 664.4850241063173, 702.5047341624153, 11758.118141139417, 12361.072919531038, 13372.197668139894, 13548.729025419485, 13597.252151131685, 11334.149938754606, 11338.036266917323, 11341.37622430766, 11345.37600431976, 11347.262427942178, 643.276314146585, 776.9650365733527, 788.6727008291552, 792.0732742691036, 793.5635400529715, 753.7060546386914, 766.2414209932155, 766.3409467857638, 780.5940910726447, 782.5265714242886, 807.5356669914793, 808.9635966381527, 809.1948511231333, 809.2837838512929, 809.5102463009882, 665.986680266392, 805.4200975297776, 807.3258828877865, 807.7132617920641, 809.7004108479871, 557.9804706835241, 559.0524061715394, 574.4399210769467, 582.9883402331972, 584.3659629302213, 11945.761084778256, 11954.163208575403, 11962.282263064157, 11987.820182697267, 11988.100892433102, 11704.47329870158, 12214.57248996281, 12225.572104976372, 12321.0, 12622.495100195982, 9516.857247141299, 9970.800583988279, 11293.265937714104, 11312.943435282854, 11444.94277528607, 4079.8572049978106, 4166.104287578185, 4174.457320548309, 4177.26897792885, 4180.059486615518, 58707.70646146742, 59606.403935960516, 60436.69781651108, 60516.697416513074, 60576.69711651414, 9412.247020238365, 9696.6121355145, 9733.9026609733, 9851.50742462874, 10154.0, 17148.657026859393, 17768.822311776974, 17780.46658600244, 17784.733229001577, 18434.170462329228, 6778.830529236809, 6946.270641582662, 6966.198887127944, 7013.92986070143, 7258.237885022102, 3669.706423486168, 3742.288965096623, 3743.644353786386, 3801.5248093988253, 3814.5613254475534, 2860.699626539225, 3012.0813151988855, 3013.5329024001317, 3018.019143778281, 3018.607581014454, 521.119308368863, 530.1993989076523, 531.702246741818, 535.9062164121341, 552.9225908372831, 500.8172017213691, 526.6103083718021, 536.4019118682719, 562.1174755633698, 571.3229822660161, 7931.524108553505, 8169.754907352789, 8181.71364002269, 8266.751997440086, 8400.747977560683, 498.2924247568462, 503.1270745256976, 506.43279526929336, 512.595049910574, 523.2386877093819, 3814.8664796731982, 4158.189153115113, 4556.225441674923, 4606.792694328763, 4660.557247061523, 95536.0, 97168.0, 97198.0, 97305.0, 104705.47647261867, 49269.75365123223, 49694.25458618124, 50116.24825627619, 64888.675556621914, 87602.56198719093, 76868.0, 79045.0, 79186.60406698045, 81705.9999999988, 82471.58764206141, 8348.666053357798, 8383.832323353621, 8652.956735216283, 8985.685501007434, 9127.132922372364, 44930.0, 45342.0, 45694.31458528058, 46263.537364625925, 47789.0, 62817.37182628115, 67822.66088669524, 68330.31669683439, 69678.0, 69822.0, 10829.67510974672, 11033.944830275797, 11221.326720396803, 11519.712007199887, 11904.642860714192, 11927.0, 12343.0, 12360.320182390054, 12472.314022728837, 12575.748485030064, 12242.632721018384, 12575.434105465092, 12692.619221423372, 12697.55558555446, 12708.046896482616, 11629.0, 11905.285682859056, 12155.939220303842, 12179.512819487121, 12569.308688021882, 11271.943640281746, 11489.253198542068, 11876.0, 12308.26150430977, 12320.199187052813, 9817.950910245689, 10244.846327305246, 10300.330478518872, 10759.13926885863, 10819.134469242284, 10147.188224942134, 10384.896151038392, 10453.020889046586, 10880.238383313408, 10888.61889833852], "value": 6515.286551657438, "unit": "score"}], "name": "dromaeo_css", "value": 6425.207615630226, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 116.0, 112.0, 114.0, 114.0, 114.0, 114.0, 114.0, 113.0, 112.0], "value": 112.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 121.0, 123.0, 183.0, 123.0, 124.0, 145.0, 124.0, 181.0, 127.0], "value": 139.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 160.0, 160.0, 162.0, 160.0, 161.0, 161.0, 162.0, 161.0], "value": 160.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 74.0, 106.0, 76.0, 76.0, 75.0, 75.0, 106.0, 77.0], "value": 81.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 132.0, 86.0, 86.0, 87.0, 123.0, 86.0, 85.0, 119.0], "value": 97.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 117.0, 117.0, 116.0, 116.0, 117.0, 117.0, 117.0, 116.0], "value": 116.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 145.0, 146.0, 146.0, 145.0, 146.0, 146.0, 148.0, 147.0, 146.0], "value": 146.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 96.0, 96.0, 95.0, 95.0, 95.0, 95.0, 95.0, 94.0], "value": 95.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 62.0, 63.0, 64.0, 64.0, 65.0, 73.0, 64.0, 65.0, 63.0], "value": 64.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 65.0, 64.0, 66.0, 65.0, 65.0, 66.0, 65.0, 66.0], "value": 65.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 79.0, 88.0, 85.0, 79.0, 79.0, 79.0, 79.0, 79.0, 84.0], "value": 81.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 129.0, 130.0, 129.0, 127.0, 131.0, 128.0, 127.0, 149.0, 130.0], "value": 131.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 165.0, 168.0, 166.0, 167.0, 173.0, 167.0, 167.0, 163.0, 167.0], "value": 166.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 71.0, 68.0, 71.0, 68.0, 67.0, 67.0, 66.0, 68.0], "value": 68.0, "unit": "score"}], "name": "kraken", "value": 1528.1000000000001, "alertThreshold": 2.0}]} 01:59:32 INFO - Return code: 0 01:59:32 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 01:59:32 INFO - # TBPL SUCCESS # 01:59:32 INFO - Running post-action listener: _resource_record_post_action 01:59:32 INFO - [mozharness: 2016-08-07 08:59:32.545644Z] Finished run-tests step (success) 01:59:32 INFO - Running post-run listener: _resource_record_post_run 01:59:32 INFO - Total resource usage - Wall time: 1200s; CPU: 13.0%; Read bytes: 4096; Write bytes: 537546752; Read time: 40; Write time: 416912 01:59:32 INFO - TinderboxPrint: CPU usage
13.0% 01:59:32 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 40 01:59:32 INFO - TinderboxPrint: I/O write bytes / time
537,546,752 / 416,912 01:59:32 INFO - TinderboxPrint: CPU idle
8,334.1 (87.0%) 01:59:32 INFO - TinderboxPrint: CPU user
1,199.4 (12.5%) 01:59:32 INFO - TinderboxPrint: Swap in / out
0 / 0 01:59:32 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 250433536; Read time: 0; Write time: 237372 01:59:32 INFO - run-tests - Wall time: 1184s; CPU: 13.0%; Read bytes: 0; Write bytes: 287113216; Read time: 0; Write time: 179540 01:59:33 INFO - Running post-run listener: _upload_blobber_files 01:59:33 INFO - Blob upload gear active. 01:59:33 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 01:59:33 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 01:59:33 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'] 01:59:33 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 01:59:33 INFO - (blobuploader) - INFO - Open directory for files ... 01:59:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 01:59:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:59:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:59:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 01:59:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:59:34 INFO - (blobuploader) - INFO - Done attempting. 01:59:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 01:59:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:59:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:59:37 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 01:59:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:59:37 INFO - (blobuploader) - INFO - Done attempting. 01:59:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 01:59:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:59:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:59:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 01:59:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:59:38 INFO - (blobuploader) - INFO - Done attempting. 01:59:38 INFO - (blobuploader) - INFO - Iteration through files over. 01:59:38 INFO - Return code: 0 01:59:38 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 01:59:38 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 01:59:38 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5b05504af0e41c1f9f51fdc28c755e5b466e49f292c6461cc4a1b5769dfcd9128264652ae05cd00f73a7b68a68f8fdacb9d65c4dd9a19d4892fd9defb4bda583", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/dde38af15b73b28327389038dfdc2d7453c9eea5c17fd0df7886c0b06401039d9db0c6c1424bbbaff0dc907362ee6722a77ee527eab7176f80ad85ac887aa075", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/788d9cfa60e960c026c9690ef354ca6399e2dd7b68edb040c91bbb003b77a85a6e9b333591ec0eacd2fe5d3df3dcc781468f47beb9341944abe97b3f85fe4ca6"} 01:59:38 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 01:59:38 INFO - Writing to file /builds/slave/test/properties/blobber_files 01:59:38 INFO - Contents: 01:59:38 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5b05504af0e41c1f9f51fdc28c755e5b466e49f292c6461cc4a1b5769dfcd9128264652ae05cd00f73a7b68a68f8fdacb9d65c4dd9a19d4892fd9defb4bda583", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/dde38af15b73b28327389038dfdc2d7453c9eea5c17fd0df7886c0b06401039d9db0c6c1424bbbaff0dc907362ee6722a77ee527eab7176f80ad85ac887aa075", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/788d9cfa60e960c026c9690ef354ca6399e2dd7b68edb040c91bbb003b77a85a6e9b333591ec0eacd2fe5d3df3dcc781468f47beb9341944abe97b3f85fe4ca6"} 01:59:38 INFO - Running post-run listener: copy_logs_to_upload_dir 01:59:38 INFO - Copying logs to upload dir... 01:59:38 INFO - mkdir: /builds/slave/test/build/upload/logs 01:59:38 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1236.600201 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 36 secs) (at 2016-08-07 01:59:38.365494) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-07 01:59:38.369644) ========= 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=31acecf42b6a79ec49471d4a000001bb-1470550271.716546-1247145742 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5b05504af0e41c1f9f51fdc28c755e5b466e49f292c6461cc4a1b5769dfcd9128264652ae05cd00f73a7b68a68f8fdacb9d65c4dd9a19d4892fd9defb4bda583", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/dde38af15b73b28327389038dfdc2d7453c9eea5c17fd0df7886c0b06401039d9db0c6c1424bbbaff0dc907362ee6722a77ee527eab7176f80ad85ac887aa075", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/788d9cfa60e960c026c9690ef354ca6399e2dd7b68edb040c91bbb003b77a85a6e9b333591ec0eacd2fe5d3df3dcc781468f47beb9341944abe97b3f85fe4ca6"} build_url:https://queue.taskcluster.net/v1/task/cfzoeDjPRu6yBZ4FEc73hw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011052 build_url: 'https://queue.taskcluster.net/v1/task/cfzoeDjPRu6yBZ4FEc73hw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5b05504af0e41c1f9f51fdc28c755e5b466e49f292c6461cc4a1b5769dfcd9128264652ae05cd00f73a7b68a68f8fdacb9d65c4dd9a19d4892fd9defb4bda583", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/dde38af15b73b28327389038dfdc2d7453c9eea5c17fd0df7886c0b06401039d9db0c6c1424bbbaff0dc907362ee6722a77ee527eab7176f80ad85ac887aa075", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/788d9cfa60e960c026c9690ef354ca6399e2dd7b68edb040c91bbb003b77a85a6e9b333591ec0eacd2fe5d3df3dcc781468f47beb9341944abe97b3f85fe4ca6"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-07 01:59:38.395554) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-07 01:59:38.395964) ========= 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=31acecf42b6a79ec49471d4a000001bb-1470550271.716546-1247145742 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004329 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-07 01:59:38.477911) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-07 01:59:38.481237) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-07 01:59:38.481526) ========= ========= Total master_lag: 0.32 =========