builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-002 starttime: 1481903522.13 results: success (0) buildid: 20161216070349 builduid: d6e0a216762947fa8a4c718bec1bff70 revision: 917fc78005ddd0f15013e8a9e0b473468a8b2f14 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-16 07:52:02.130633) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-16 07:52:02.131067) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-16 07:52:02.131351) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-16 07:52:02.259745) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-16 07:52:02.260047) ========= 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=a0d2d2de893abaa8702a9954000001a7-1481896745.736146-1060331009 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-12-16 07:52:02-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 19.8M=0.001s 2016-12-16 07:52:02 (19.8 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.151784 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-16 07:52:02.454763) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-16 07:52:02.455047) ========= 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=a0d2d2de893abaa8702a9954000001a7-1481896745.736146-1060331009 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017189 ========= master_lag: 0.08 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-16 07:52:02.556396) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-16 07:52:02.556761) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 917fc78005ddd0f15013e8a9e0b473468a8b2f14 --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 917fc78005ddd0f15013e8a9e0b473468a8b2f14 --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=a0d2d2de893abaa8702a9954000001a7-1481896745.736146-1060331009 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-12-16 07:52:02,747 truncating revision to first 12 chars 2016-12-16 07:52:02,747 Setting DEBUG logging. 2016-12-16 07:52:02,747 attempt 1/10 2016-12-16 07:52:02,747 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/917fc78005dd?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-16 07:52:03,051 unpacking tar archive at: mozilla-central-917fc78005dd/testing/mozharness/ program finished with exit code 0 elapsedTime=0.709304 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-16 07:52:03.288005) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-16 07:52:03.288331) ========= script_repo_revision: 917fc78005ddd0f15013e8a9e0b473468a8b2f14 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-16 07:52:03.288773) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-16 07:52:03.289047) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-16 07:52:03.302738) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 46 secs) (at 2016-12-16 07:52:03.303016) ========= /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=a0d2d2de893abaa8702a9954000001a7-1481896745.736146-1060331009 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 07:52:03 INFO - MultiFileLogger online at 20161216 07:52:03 in /builds/slave/test 07:52:03 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 07:52:03 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:52:03 INFO - {'append_to_log': False, 07:52:03 INFO - 'base_work_dir': '/builds/slave/test', 07:52:03 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 07:52:03 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:52:03 INFO - 'branch': 'Firefox-Non-PGO', 07:52:03 INFO - 'buildbot_json_path': 'buildprops.json', 07:52:03 INFO - 'config_files': ('talos/linux_config.py',), 07:52:03 INFO - 'default_actions': ('clobber', 07:52:03 INFO - 'read-buildbot-config', 07:52:03 INFO - 'download-and-extract', 07:52:03 INFO - 'populate-webroot', 07:52:03 INFO - 'create-virtualenv', 07:52:03 INFO - 'install', 07:52:03 INFO - 'run-tests'), 07:52:03 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:52:03 INFO - 'download_minidump_stackwalk': True, 07:52:03 INFO - 'download_symbols': 'ondemand', 07:52:03 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:52:03 INFO - 'tooltool.py': '/tools/tooltool.py', 07:52:03 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:52:03 INFO - '/tools/misc-python/virtualenv.py')}, 07:52:03 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:52:03 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:52:03 INFO - 'installer_path': 'installer.exe', 07:52:03 INFO - 'log_level': 'info', 07:52:03 INFO - 'log_name': 'talos', 07:52:03 INFO - 'log_to_console': True, 07:52:03 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 07:52:03 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 07:52:03 INFO - 'opt_config_files': (), 07:52:03 INFO - 'pip_index': False, 07:52:03 INFO - 'sps_profile': False, 07:52:03 INFO - 'sps_profile_interval': 0, 07:52:03 INFO - 'suite': 'dromaeojs', 07:52:03 INFO - 'system_bits': '32', 07:52:03 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:52:03 INFO - 'title': 'talos-linux64-ix-002', 07:52:03 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:52:03 INFO - 'use_talos_json': True, 07:52:03 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:52:03 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:52:03 INFO - 'work_dir': 'build'} 07:52:03 INFO - [mozharness: 2016-12-16 15:52:03.585348Z] Running clobber step. 07:52:03 INFO - Running pre-action listener: _resource_record_pre_action 07:52:03 INFO - Running main action method: clobber 07:52:03 INFO - rmtree: /builds/slave/test/build 07:52:03 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:52:04 INFO - Running post-action listener: _resource_record_post_action 07:52:04 INFO - [mozharness: 2016-12-16 15:52:04.042870Z] Finished clobber step (success) 07:52:04 INFO - [mozharness: 2016-12-16 15:52:04.042970Z] Running read-buildbot-config step. 07:52:04 INFO - Running pre-action listener: _resource_record_pre_action 07:52:04 INFO - Running main action method: read_buildbot_config 07:52:04 INFO - Using buildbot properties: 07:52:04 INFO - { 07:52:04 INFO - "project": "", 07:52:04 INFO - "product": "firefox", 07:52:04 INFO - "script_repo_revision": "production", 07:52:04 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 07:52:04 INFO - "repository": "", 07:52:04 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 07:52:04 INFO - "buildid": "20161216070349", 07:52:04 INFO - "pgo_build": "False", 07:52:04 INFO - "basedir": "/builds/slave/test", 07:52:04 INFO - "buildnumber": 348, 07:52:04 INFO - "slavename": "talos-linux64-ix-002", 07:52:04 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 07:52:04 INFO - "platform": "ubuntu64_hw", 07:52:04 INFO - "scheduler": "tests-mozilla-central-linux64-talos", 07:52:04 INFO - "branch": "mozilla-central", 07:52:04 INFO - "repo_path": "mozilla-central", 07:52:04 INFO - "revision": "917fc78005ddd0f15013e8a9e0b473468a8b2f14", 07:52:04 INFO - "stage_platform": "linux64", 07:52:04 INFO - "builduid": "d6e0a216762947fa8a4c718bec1bff70", 07:52:04 INFO - "slavebuilddir": "test" 07:52:04 INFO - } 07:52:04 INFO - Found installer url https://queue.taskcluster.net/v1/task/eKuB0yAhTdyySFIFm7Uc5g/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2. 07:52:04 INFO - Running post-action listener: _resource_record_post_action 07:52:04 INFO - [mozharness: 2016-12-16 15:52:04.045411Z] Finished read-buildbot-config step (success) 07:52:04 INFO - [mozharness: 2016-12-16 15:52:04.045500Z] Running download-and-extract step. 07:52:04 INFO - Running pre-action listener: _resource_record_pre_action 07:52:04 INFO - Running main action method: download_and_extract 07:52:04 INFO - mkdir: /builds/slave/test/build/tests 07:52:04 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')]} 07:52:04 INFO - trying https://queue.taskcluster.net/v1/task/eKuB0yAhTdyySFIFm7Uc5g/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 07:52:04 INFO - Downloading https://queue.taskcluster.net/v1/task/eKuB0yAhTdyySFIFm7Uc5g/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 07:52:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eKuB0yAhTdyySFIFm7Uc5g/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 07:52:04 INFO - Downloaded 1361 bytes. 07:52:04 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 07:52:04 INFO - Using the following test package requirements: 07:52:04 INFO - {u'common': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 07:52:04 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 07:52:04 INFO - u'firefox-53.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 07:52:04 INFO - u'gtest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 07:52:04 INFO - u'firefox-53.0a1.en-US.linux-x86_64.gtest.tests.zip'], 07:52:04 INFO - u'jittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 07:52:04 INFO - u'jsshell-linux-x86_64.zip'], 07:52:04 INFO - u'mochitest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 07:52:04 INFO - u'firefox-53.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 07:52:04 INFO - u'mozbase': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 07:52:04 INFO - u'reftest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 07:52:04 INFO - u'firefox-53.0a1.en-US.linux-x86_64.reftest.tests.zip'], 07:52:04 INFO - u'talos': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 07:52:04 INFO - u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'], 07:52:04 INFO - u'web-platform': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 07:52:04 INFO - u'firefox-53.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 07:52:04 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 07:52:04 INFO - u'firefox-53.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 07:52:04 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 07:52:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eKuB0yAhTdyySFIFm7Uc5g/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 07:52:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eKuB0yAhTdyySFIFm7Uc5g/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 07:52:04 INFO - Fetch https://queue.taskcluster.net/v1/task/eKuB0yAhTdyySFIFm7Uc5g/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 07:52:05 INFO - Expected file size: 20213373 07:52:05 INFO - Obtained file size: 20213373 07:52:09 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 07:52:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eKuB0yAhTdyySFIFm7Uc5g/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 07:52:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eKuB0yAhTdyySFIFm7Uc5g/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 07:52:09 INFO - Fetch https://queue.taskcluster.net/v1/task/eKuB0yAhTdyySFIFm7Uc5g/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 07:52:10 INFO - Expected file size: 20213373 07:52:10 INFO - Obtained file size: 20213373 07:52:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eKuB0yAhTdyySFIFm7Uc5g/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip 07:52:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eKuB0yAhTdyySFIFm7Uc5g/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 07:52:14 INFO - Fetch https://queue.taskcluster.net/v1/task/eKuB0yAhTdyySFIFm7Uc5g/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip into memory 07:52:15 INFO - Expected file size: 14409734 07:52:15 INFO - Obtained file size: 14409734 07:52:15 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')]} 07:52:15 INFO - trying https://queue.taskcluster.net/v1/task/eKuB0yAhTdyySFIFm7Uc5g/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 07:52:15 INFO - Downloading https://queue.taskcluster.net/v1/task/eKuB0yAhTdyySFIFm7Uc5g/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 07:52:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eKuB0yAhTdyySFIFm7Uc5g/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 07:52:16 INFO - Downloaded 55603982 bytes. 07:52:16 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/eKuB0yAhTdyySFIFm7Uc5g/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 07:52:16 INFO - mkdir: /builds/slave/test/properties 07:52:16 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:52:16 INFO - Writing to file /builds/slave/test/properties/build_url 07:52:16 INFO - Contents: 07:52:16 INFO - build_url:https://queue.taskcluster.net/v1/task/eKuB0yAhTdyySFIFm7Uc5g/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 07:52:17 INFO - Running post-action listener: _resource_record_post_action 07:52:17 INFO - Running post-action listener: set_extra_try_arguments 07:52:17 INFO - [mozharness: 2016-12-16 15:52:17.593364Z] Finished download-and-extract step (success) 07:52:17 INFO - [mozharness: 2016-12-16 15:52:17.593592Z] Running populate-webroot step. 07:52:17 INFO - Running pre-action listener: _resource_record_pre_action 07:52:17 INFO - Running main action method: populate_webroot 07:52:17 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:52:17 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:52:17 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:52:17 INFO - '--tppagecycles', 07:52:17 INFO - '7'], 07:52:17 INFO - 'tests': ['tsvgm']}}, 07:52:17 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:52:17 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:52:17 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:52:17 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:52:17 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:52:17 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:52:17 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 07:52:17 INFO - 'talos_options': ['--disable-e10s'], 07:52:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:52:17 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 07:52:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:52:17 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 07:52:17 INFO - 'talos_options': ['--disable-e10s'], 07:52:17 INFO - 'tests': ['damp', 'tps']}, 07:52:17 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 07:52:17 INFO - 'tests': ['damp', 'tps']}, 07:52:17 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:52:17 INFO - 'tests': ['dromaeo_dom']}, 07:52:17 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:52:17 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:52:17 INFO - 'tests': ['basic_compositor_video']}, 07:52:17 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:52:17 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:52:17 INFO - 'tests': ['a11yr', 07:52:17 INFO - 'ts_paint', 07:52:17 INFO - 'tpaint', 07:52:17 INFO - 'sessionrestore', 07:52:17 INFO - 'sessionrestore_no_auto_restore', 07:52:17 INFO - 'tabpaint']}, 07:52:17 INFO - 'other-e10s': {'tests': ['a11yr', 07:52:17 INFO - 'ts_paint', 07:52:17 INFO - 'tpaint', 07:52:17 INFO - 'sessionrestore', 07:52:17 INFO - 'sessionrestore_no_auto_restore', 07:52:17 INFO - 'tabpaint']}, 07:52:17 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:52:17 INFO - 'ts_paint', 07:52:17 INFO - 'tpaint', 07:52:17 INFO - 'sessionrestore', 07:52:17 INFO - 'sessionrestore_no_auto_restore', 07:52:17 INFO - 'tabpaint']}, 07:52:17 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:52:17 INFO - 'ts_paint', 07:52:17 INFO - 'tpaint', 07:52:17 INFO - 'sessionrestore', 07:52:17 INFO - 'sessionrestore_no_auto_restore', 07:52:17 INFO - 'tabpaint']}, 07:52:17 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:52:17 INFO - 'tests': ['a11yr', 07:52:17 INFO - 'ts_paint', 07:52:17 INFO - 'tpaint', 07:52:17 INFO - 'sessionrestore', 07:52:17 INFO - 'sessionrestore_no_auto_restore', 07:52:17 INFO - 'tabpaint']}, 07:52:17 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:52:17 INFO - 'tests': ['a11yr', 07:52:17 INFO - 'ts_paint', 07:52:17 INFO - 'tpaint', 07:52:17 INFO - 'sessionrestore', 07:52:17 INFO - 'sessionrestore_no_auto_restore', 07:52:17 INFO - 'tabpaint']}, 07:52:17 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:52:17 INFO - 'tests': ['tsvgx', 07:52:17 INFO - 'tsvgr_opacity', 07:52:17 INFO - 'tart', 07:52:17 INFO - 'tscrollx', 07:52:17 INFO - 'cart', 07:52:17 INFO - 'tsvg_static']}, 07:52:17 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:52:17 INFO - 'tsvgr_opacity', 07:52:17 INFO - 'tart', 07:52:17 INFO - 'tscrollx', 07:52:17 INFO - 'cart', 07:52:17 INFO - 'tsvg_static']}, 07:52:17 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 07:52:17 INFO - 'talos_options': ['--disable-e10s'], 07:52:17 INFO - 'tests': ['tp5o']}, 07:52:17 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 07:52:17 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 07:52:17 INFO - 'talos_options': ['--disable-e10s', 07:52:17 INFO - '--xperf_path', 07:52:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:52:17 INFO - 'tests': ['tp5n']}, 07:52:17 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 07:52:17 INFO - 'talos_options': ['--xperf_path', 07:52:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:52:17 INFO - 'tests': ['tp5n']}}, 07:52:17 INFO - 'talos.zip': {'path': '', 07:52:17 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:52:17 INFO - Running post-action listener: _resource_record_post_action 07:52:17 INFO - [mozharness: 2016-12-16 15:52:17.615323Z] Finished populate-webroot step (success) 07:52:17 INFO - [mozharness: 2016-12-16 15:52:17.615412Z] Running create-virtualenv step. 07:52:17 INFO - Running pre-action listener: _resource_record_pre_action 07:52:17 INFO - Running main action method: create_virtualenv 07:52:17 INFO - Creating virtualenv /builds/slave/test/build/venv 07:52:17 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 07:52:17 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:52:17 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:52:17 INFO - Using real prefix '/usr' 07:52:17 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:52:18 INFO - Installing distribute.............................................................................................................................................................................................done. 07:52:20 INFO - Installing pip.................done. 07:52:20 INFO - Return code: 0 07:52:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 07:52:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 07:52:20 INFO - Reading from file tmpfile_stdout 07:52:20 INFO - Output received: 07:52:20 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 07:52:20 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:52:20 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')]} 07:52:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:52:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:52:20 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')]} 07:52:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:52:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:52:20 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 0x7f73f16e0e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12cba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1469120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14f9f20>, '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 0x1466c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1466f90>, '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': 'a0d2d2de893abaa8702a9954000001a7-1481896745.736146-1060331009', '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 07:52:20 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 07:52:20 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 07:52:20 INFO - Using env: {'DISPLAY': ':0', 07:52:20 INFO - 'HOME': '/home/cltbld', 07:52:20 INFO - 'LANG': 'en_US.UTF-8', 07:52:20 INFO - 'LANGUAGE': 'en_US:en', 07:52:20 INFO - 'LOGNAME': 'cltbld', 07:52:20 INFO - 'MAIL': '/var/mail/cltbld', 07:52:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:52:20 INFO - 'MOZ_NO_REMOTE': '1', 07:52:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:52:20 INFO - 'NO_EM_RESTART': '1', 07:52:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:52:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:52:20 INFO - 'PWD': '/builds/slave/test', 07:52:20 INFO - 'SHELL': '/bin/bash', 07:52:20 INFO - 'SHLVL': '1', 07:52:20 INFO - 'TERM': 'linux', 07:52:20 INFO - 'TMOUT': '86400', 07:52:20 INFO - 'USER': 'cltbld', 07:52:20 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1481896745.736146-1060331009', 07:52:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:52:20 INFO - '_': '/tools/buildbot/bin/python'} 07:52:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:52:20 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 07:52:20 INFO - Cleaning up... 07:52:20 INFO - Return code: 0 07:52:20 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:52:20 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')]} 07:52:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:52:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:52:20 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')]} 07:52:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:52:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:52:20 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 0x7f73f16e0e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12cba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1469120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14f9f20>, '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 0x1466c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1466f90>, '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': 'a0d2d2de893abaa8702a9954000001a7-1481896745.736146-1060331009', '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 07:52:20 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 07:52:20 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 07:52:20 INFO - Using env: {'DISPLAY': ':0', 07:52:20 INFO - 'HOME': '/home/cltbld', 07:52:20 INFO - 'LANG': 'en_US.UTF-8', 07:52:20 INFO - 'LANGUAGE': 'en_US:en', 07:52:20 INFO - 'LOGNAME': 'cltbld', 07:52:20 INFO - 'MAIL': '/var/mail/cltbld', 07:52:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:52:20 INFO - 'MOZ_NO_REMOTE': '1', 07:52:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:52:20 INFO - 'NO_EM_RESTART': '1', 07:52:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:52:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:52:20 INFO - 'PWD': '/builds/slave/test', 07:52:20 INFO - 'SHELL': '/bin/bash', 07:52:20 INFO - 'SHLVL': '1', 07:52:20 INFO - 'TERM': 'linux', 07:52:20 INFO - 'TMOUT': '86400', 07:52:20 INFO - 'USER': 'cltbld', 07:52:20 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1481896745.736146-1060331009', 07:52:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:52:20 INFO - '_': '/tools/buildbot/bin/python'} 07:52:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:52:20 INFO - Downloading/unpacking psutil>=3.1.1 07:52: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 07:52: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 07:52: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 07:52: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 07:52: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 07:52: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 07:52:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:52:23 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:52:23 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:52:23 INFO - Installing collected packages: psutil 07:52:23 INFO - Running setup.py install for psutil 07:52:23 INFO - building 'psutil._psutil_linux' extension 07:52:23 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 07:52:24 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 07:52:24 INFO - building 'psutil._psutil_posix' extension 07:52:24 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 07:52:24 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 07:52:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:52:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:52:24 INFO - Successfully installed psutil 07:52:24 INFO - Cleaning up... 07:52:24 INFO - Return code: 0 07:52:24 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:52: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')]} 07:52:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:52:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:52: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')]} 07:52:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:52:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:52:24 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 0x7f73f16e0e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12cba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1469120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14f9f20>, '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 0x1466c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1466f90>, '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': 'a0d2d2de893abaa8702a9954000001a7-1481896745.736146-1060331009', '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 07:52:24 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 07:52:24 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 07:52:24 INFO - Using env: {'DISPLAY': ':0', 07:52:24 INFO - 'HOME': '/home/cltbld', 07:52:24 INFO - 'LANG': 'en_US.UTF-8', 07:52:24 INFO - 'LANGUAGE': 'en_US:en', 07:52:24 INFO - 'LOGNAME': 'cltbld', 07:52:24 INFO - 'MAIL': '/var/mail/cltbld', 07:52:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:52:24 INFO - 'MOZ_NO_REMOTE': '1', 07:52:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:52:24 INFO - 'NO_EM_RESTART': '1', 07:52:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:52:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:52:24 INFO - 'PWD': '/builds/slave/test', 07:52:24 INFO - 'SHELL': '/bin/bash', 07:52:24 INFO - 'SHLVL': '1', 07:52:24 INFO - 'TERM': 'linux', 07:52:24 INFO - 'TMOUT': '86400', 07:52:24 INFO - 'USER': 'cltbld', 07:52:24 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1481896745.736146-1060331009', 07:52:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:52:24 INFO - '_': '/tools/buildbot/bin/python'} 07:52:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:52:24 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:52:24 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 07:52:24 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 07:52:24 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 07:52:24 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 07:52:24 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 07:52:24 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 07:52:26 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:52:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:52:26 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:52:26 INFO - Installing collected packages: mozsystemmonitor 07:52:26 INFO - Running setup.py install for mozsystemmonitor 07:52:26 INFO - Successfully installed mozsystemmonitor 07:52:26 INFO - Cleaning up... 07:52:26 INFO - Return code: 0 07:52:26 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:52:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:52:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:52:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:52:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:52:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:52:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:52:26 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 0x7f73f16e0e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12cba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1469120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14f9f20>, '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 0x1466c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1466f90>, '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': 'a0d2d2de893abaa8702a9954000001a7-1481896745.736146-1060331009', '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 07:52:26 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 07:52:26 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 07:52:26 INFO - Using env: {'DISPLAY': ':0', 07:52:26 INFO - 'HOME': '/home/cltbld', 07:52:26 INFO - 'LANG': 'en_US.UTF-8', 07:52:26 INFO - 'LANGUAGE': 'en_US:en', 07:52:26 INFO - 'LOGNAME': 'cltbld', 07:52:26 INFO - 'MAIL': '/var/mail/cltbld', 07:52:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:52:26 INFO - 'MOZ_NO_REMOTE': '1', 07:52:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:52:26 INFO - 'NO_EM_RESTART': '1', 07:52:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:52:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:52:26 INFO - 'PWD': '/builds/slave/test', 07:52:26 INFO - 'SHELL': '/bin/bash', 07:52:26 INFO - 'SHLVL': '1', 07:52:26 INFO - 'TERM': 'linux', 07:52:26 INFO - 'TMOUT': '86400', 07:52:26 INFO - 'USER': 'cltbld', 07:52:26 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1481896745.736146-1060331009', 07:52:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:52:26 INFO - '_': '/tools/buildbot/bin/python'} 07:52:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:52:27 INFO - Downloading/unpacking jsonschema==2.5.1 07:52:27 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 07:52:27 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 07:52:27 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 07:52:27 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 07:52:27 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 07:52:27 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 07:52:29 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:52:29 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:52: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 07:52: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 07:52: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 07:52: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 07:52: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 07:52: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 07:52:29 INFO - Downloading functools32-3.2.3-2.tar.gz 07:52:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:52:29 INFO - warning: no files found matching '*.txt' 07:52:29 INFO - no previously-included directories found matching 'build' 07:52:29 INFO - no previously-included directories found matching 'dist' 07:52:29 INFO - no previously-included directories found matching '.git*' 07:52:29 INFO - Installing collected packages: jsonschema, functools32 07:52:29 INFO - Running setup.py install for functools32 07:52:29 INFO - warning: no files found matching '*.txt' 07:52:29 INFO - no previously-included directories found matching 'build' 07:52:29 INFO - no previously-included directories found matching 'dist' 07:52:29 INFO - no previously-included directories found matching '.git*' 07:52:29 INFO - Successfully installed jsonschema functools32 07:52:29 INFO - Cleaning up... 07:52:29 INFO - Return code: 0 07:52:29 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:52:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:52:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:52:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:52:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:52:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:52:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:52:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f73f16e0e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12cba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1469120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14f9f20>, '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 0x1466c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1466f90>, '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': 'a0d2d2de893abaa8702a9954000001a7-1481896745.736146-1060331009', '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 07:52:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 07:52:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 07:52:29 INFO - Using env: {'DISPLAY': ':0', 07:52:29 INFO - 'HOME': '/home/cltbld', 07:52:29 INFO - 'LANG': 'en_US.UTF-8', 07:52:29 INFO - 'LANGUAGE': 'en_US:en', 07:52:29 INFO - 'LOGNAME': 'cltbld', 07:52:29 INFO - 'MAIL': '/var/mail/cltbld', 07:52:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:52:29 INFO - 'MOZ_NO_REMOTE': '1', 07:52:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:52:29 INFO - 'NO_EM_RESTART': '1', 07:52:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:52:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:52:29 INFO - 'PWD': '/builds/slave/test', 07:52:29 INFO - 'SHELL': '/bin/bash', 07:52:29 INFO - 'SHLVL': '1', 07:52:29 INFO - 'TERM': 'linux', 07:52:29 INFO - 'TMOUT': '86400', 07:52:29 INFO - 'USER': 'cltbld', 07:52:29 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1481896745.736146-1060331009', 07:52:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:52:29 INFO - '_': '/tools/buildbot/bin/python'} 07:52:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:52:29 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:52:29 INFO - Cleaning up... 07:52:29 INFO - Return code: 0 07:52:29 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:52:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:52:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:52:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:52:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:52:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:52:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:52:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f73f16e0e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12cba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1469120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14f9f20>, '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 0x1466c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1466f90>, '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': 'a0d2d2de893abaa8702a9954000001a7-1481896745.736146-1060331009', '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 07:52:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 07:52:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 07:52:29 INFO - Using env: {'DISPLAY': ':0', 07:52:29 INFO - 'HOME': '/home/cltbld', 07:52:29 INFO - 'LANG': 'en_US.UTF-8', 07:52:29 INFO - 'LANGUAGE': 'en_US:en', 07:52:29 INFO - 'LOGNAME': 'cltbld', 07:52:29 INFO - 'MAIL': '/var/mail/cltbld', 07:52:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:52:29 INFO - 'MOZ_NO_REMOTE': '1', 07:52:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:52:29 INFO - 'NO_EM_RESTART': '1', 07:52:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:52:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:52:29 INFO - 'PWD': '/builds/slave/test', 07:52:29 INFO - 'SHELL': '/bin/bash', 07:52:29 INFO - 'SHLVL': '1', 07:52:29 INFO - 'TERM': 'linux', 07:52:29 INFO - 'TMOUT': '86400', 07:52:29 INFO - 'USER': 'cltbld', 07:52:29 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1481896745.736146-1060331009', 07:52:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:52:29 INFO - '_': '/tools/buildbot/bin/python'} 07:52:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:52:29 INFO - Downloading/unpacking blobuploader==1.2.4 07:52: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 07:52: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 07:52: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 07:52: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 07:52: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 07:52: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 07:52:31 INFO - Downloading blobuploader-1.2.4.tar.gz 07:52:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:52:32 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:52:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:52:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:52:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:52:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:52:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:52:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:52:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:52:32 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:52:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:52:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:52:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:52:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:52:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:52:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:52:32 INFO - Downloading docopt-0.6.1.tar.gz 07:52:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:52:32 INFO - Installing collected packages: blobuploader, requests, docopt 07:52:32 INFO - Running setup.py install for blobuploader 07:52:32 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:52:32 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:52:32 INFO - Running setup.py install for requests 07:52:33 INFO - Running setup.py install for docopt 07:52:33 INFO - Successfully installed blobuploader requests docopt 07:52:33 INFO - Cleaning up... 07:52:33 INFO - Return code: 0 07:52:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:52:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:52:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:52:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:52:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:52:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:52:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:52:33 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 0x7f73f16e0e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12cba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1469120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14f9f20>, '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 0x1466c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1466f90>, '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': 'a0d2d2de893abaa8702a9954000001a7-1481896745.736146-1060331009', '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 07:52:33 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 07:52:33 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 07:52:33 INFO - Using env: {'DISPLAY': ':0', 07:52:33 INFO - 'HOME': '/home/cltbld', 07:52:33 INFO - 'LANG': 'en_US.UTF-8', 07:52:33 INFO - 'LANGUAGE': 'en_US:en', 07:52:33 INFO - 'LOGNAME': 'cltbld', 07:52:33 INFO - 'MAIL': '/var/mail/cltbld', 07:52:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:52:33 INFO - 'MOZ_NO_REMOTE': '1', 07:52:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:52:33 INFO - 'NO_EM_RESTART': '1', 07:52:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:52:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:52:33 INFO - 'PWD': '/builds/slave/test', 07:52:33 INFO - 'SHELL': '/bin/bash', 07:52:33 INFO - 'SHLVL': '1', 07:52:33 INFO - 'TERM': 'linux', 07:52:33 INFO - 'TMOUT': '86400', 07:52:33 INFO - 'USER': 'cltbld', 07:52:33 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1481896745.736146-1060331009', 07:52:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:52:33 INFO - '_': '/tools/buildbot/bin/python'} 07:52:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:52:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:52:33 INFO - Running setup.py (path:/tmp/pip-pT59Tf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:52:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:52:33 INFO - Running setup.py (path:/tmp/pip-gtCnlJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:52:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:52:33 INFO - Running setup.py (path:/tmp/pip-ejJK7D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:52:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:52:33 INFO - Running setup.py (path:/tmp/pip-A8gZ6G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:52:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:52:33 INFO - Running setup.py (path:/tmp/pip-jVcglT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:52:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:52:33 INFO - Running setup.py (path:/tmp/pip-FQWq4N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:52:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:52:33 INFO - Running setup.py (path:/tmp/pip-lvTUu9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:52:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:52:34 INFO - Running setup.py (path:/tmp/pip-pSqE4d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:52:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:52:34 INFO - Running setup.py (path:/tmp/pip-v1zZnv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:52:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:52:34 INFO - Running setup.py (path:/tmp/pip-9DW0x9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:52:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:52:34 INFO - Running setup.py (path:/tmp/pip-211OXF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:52:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:52:34 INFO - Running setup.py (path:/tmp/pip-7bPwjX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:52:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:52:34 INFO - Running setup.py (path:/tmp/pip-ThFNyk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:52:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:52:34 INFO - Running setup.py (path:/tmp/pip-Hg1_Od-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:52:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:52:34 INFO - Running setup.py (path:/tmp/pip-2plwRo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:52:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:52:34 INFO - Running setup.py (path:/tmp/pip-aQS6pP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:52:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:52:34 INFO - Running setup.py (path:/tmp/pip-FTNXve-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:52:34 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:52:34 INFO - Running setup.py install for manifestparser 07:52:34 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:52:34 INFO - Running setup.py install for mozcrash 07:52:34 INFO - Running setup.py install for mozdebug 07:52:35 INFO - Running setup.py install for mozdevice 07:52:35 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:52:35 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:52:35 INFO - Running setup.py install for mozfile 07:52:35 INFO - Running setup.py install for mozhttpd 07:52:35 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:52:35 INFO - Running setup.py install for mozinfo 07:52:35 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:52:35 INFO - Running setup.py install for mozInstall 07:52:35 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:52:35 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:52:35 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:52:35 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:52:35 INFO - Running setup.py install for mozleak 07:52:35 INFO - Running setup.py install for mozlog 07:52:35 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:52:35 INFO - Running setup.py install for moznetwork 07:52:35 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:52:35 INFO - Running setup.py install for mozprocess 07:52:36 INFO - Running setup.py install for mozprofile 07:52:36 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:52:36 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:52:36 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:52:36 INFO - Running setup.py install for mozrunner 07:52:36 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:52:36 INFO - Running setup.py install for mozscreenshot 07:52:36 INFO - Running setup.py install for moztest 07:52:36 INFO - Running setup.py install for mozversion 07:52:36 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:52:36 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:52:36 INFO - Cleaning up... 07:52:36 INFO - Return code: 0 07:52:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:52:36 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')]} 07:52:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:52:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:52:36 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')]} 07:52:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:52:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:52:36 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 0x7f73f16e0e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12cba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1469120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14f9f20>, '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 0x1466c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1466f90>, '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': 'a0d2d2de893abaa8702a9954000001a7-1481896745.736146-1060331009', '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 07:52:36 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 07:52:36 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 07:52:36 INFO - Using env: {'DISPLAY': ':0', 07:52:36 INFO - 'HOME': '/home/cltbld', 07:52:36 INFO - 'LANG': 'en_US.UTF-8', 07:52:36 INFO - 'LANGUAGE': 'en_US:en', 07:52:36 INFO - 'LOGNAME': 'cltbld', 07:52:36 INFO - 'MAIL': '/var/mail/cltbld', 07:52:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:52:36 INFO - 'MOZ_NO_REMOTE': '1', 07:52:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:52:36 INFO - 'NO_EM_RESTART': '1', 07:52:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:52:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:52:36 INFO - 'PWD': '/builds/slave/test', 07:52:36 INFO - 'SHELL': '/bin/bash', 07:52:36 INFO - 'SHLVL': '1', 07:52:36 INFO - 'TERM': 'linux', 07:52:36 INFO - 'TMOUT': '86400', 07:52:36 INFO - 'USER': 'cltbld', 07:52:36 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1481896745.736146-1060331009', 07:52:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:52:36 INFO - '_': '/tools/buildbot/bin/python'} 07:52:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:52:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:52:36 INFO - Running setup.py (path:/tmp/pip-lMJmtE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:52:36 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)) 07:52:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:52:36 INFO - Running setup.py (path:/tmp/pip-QrQExM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:52:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:52:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:52:36 INFO - Running setup.py (path:/tmp/pip-yVAVwf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:52:36 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)) 07:52:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:52:37 INFO - Running setup.py (path:/tmp/pip-2EoJBb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:52:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:52:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:52:37 INFO - Running setup.py (path:/tmp/pip-QIGwTO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:52:37 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)) 07:52:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:52:37 INFO - Running setup.py (path:/tmp/pip-dUdOrA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:52:37 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)) 07:52:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:52:37 INFO - Running setup.py (path:/tmp/pip-FJ6_Cw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:52:37 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)) 07:52:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:52:37 INFO - Running setup.py (path:/tmp/pip-SKOAfp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:52:37 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)) 07:52:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:52:37 INFO - Running setup.py (path:/tmp/pip-IbzDa8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:52:37 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)) 07:52:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:52:37 INFO - Running setup.py (path:/tmp/pip-sUCugH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:52:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:52:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:52:37 INFO - Running setup.py (path:/tmp/pip-Kvlcsh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:52:37 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)) 07:52:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:52:37 INFO - Running setup.py (path:/tmp/pip-4itftX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:52:37 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)) 07:52:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:52:37 INFO - Running setup.py (path:/tmp/pip-z1PAPD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:52:37 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)) 07:52:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:52:37 INFO - Running setup.py (path:/tmp/pip-H_33mE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:52:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 07:52:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:52:37 INFO - Running setup.py (path:/tmp/pip-dUeBmn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:52:37 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)) 07:52:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:52:37 INFO - Running setup.py (path:/tmp/pip-XNzAgg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:52:37 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)) 07:52:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:52:37 INFO - Running setup.py (path:/tmp/pip-AGnzzn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:52:38 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)) 07:52:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:52:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:52:38 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)) 07:52:38 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:52:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:52:38 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:52:38 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 07:52:38 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 07:52:38 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 07:52:38 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 07:52:38 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 07:52:38 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 07:52:39 INFO - Downloading blessings-1.6.tar.gz 07:52:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:52:40 INFO - Installing collected packages: blessings 07:52:40 INFO - Running setup.py install for blessings 07:52:40 INFO - Successfully installed blessings 07:52:40 INFO - Cleaning up... 07:52:40 INFO - Return code: 0 07:52:40 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:52:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:52:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:52:40 INFO - Reading from file tmpfile_stdout 07:52:40 INFO - Current package versions: 07:52:40 INFO - argparse == 1.2.1 07:52:40 INFO - blessings == 1.6 07:52:40 INFO - blobuploader == 1.2.4 07:52:40 INFO - docopt == 0.6.1 07:52:40 INFO - functools32 == 3.2.3-2 07:52:40 INFO - jsonschema == 2.5.1 07:52:40 INFO - manifestparser == 1.1 07:52:40 INFO - mozInstall == 1.12 07:52:40 INFO - mozcrash == 1.0 07:52:40 INFO - mozdebug == 0.1 07:52:40 INFO - mozdevice == 0.49 07:52:40 INFO - mozfile == 1.2 07:52:40 INFO - mozhttpd == 0.7 07:52:40 INFO - mozinfo == 0.9 07:52:40 INFO - mozleak == 0.1 07:52:40 INFO - mozlog == 3.4 07:52:40 INFO - moznetwork == 0.27 07:52:40 INFO - mozprocess == 0.23 07:52:40 INFO - mozprofile == 0.28 07:52:40 INFO - mozrunner == 6.13 07:52:40 INFO - mozscreenshot == 0.1 07:52:40 INFO - mozsystemmonitor == 0.3 07:52:40 INFO - moztest == 0.7 07:52:40 INFO - mozversion == 1.4 07:52:40 INFO - psutil == 3.1.1 07:52:40 INFO - requests == 1.2.3 07:52:40 INFO - wsgiref == 0.1.2 07:52:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:52:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:52:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:52:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:52:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:52:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:52:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:52:40 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 0x7f73f16e0e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12cba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1469120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14f9f20>, '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 0x1466c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1466f90>, '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': 'a0d2d2de893abaa8702a9954000001a7-1481896745.736146-1060331009', '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 07:52:40 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 07:52:40 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 07:52:40 INFO - Using env: {'DISPLAY': ':0', 07:52:40 INFO - 'HOME': '/home/cltbld', 07:52:40 INFO - 'LANG': 'en_US.UTF-8', 07:52:40 INFO - 'LANGUAGE': 'en_US:en', 07:52:40 INFO - 'LOGNAME': 'cltbld', 07:52:40 INFO - 'MAIL': '/var/mail/cltbld', 07:52:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:52:40 INFO - 'MOZ_NO_REMOTE': '1', 07:52:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:52:40 INFO - 'NO_EM_RESTART': '1', 07:52:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:52:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:52:40 INFO - 'PWD': '/builds/slave/test', 07:52:40 INFO - 'SHELL': '/bin/bash', 07:52:40 INFO - 'SHLVL': '1', 07:52:40 INFO - 'TERM': 'linux', 07:52:40 INFO - 'TMOUT': '86400', 07:52:40 INFO - 'USER': 'cltbld', 07:52:40 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1481896745.736146-1060331009', 07:52:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:52:40 INFO - '_': '/tools/buildbot/bin/python'} 07:52:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:52:40 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)) 07:52:40 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)) 07:52:40 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)) 07:52:40 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)) 07:52:40 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)) 07:52:40 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)) 07:52:40 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)) 07:52:40 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)) 07:52:40 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)) 07:52:40 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)) 07:52:40 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)) 07:52:40 INFO - Cleaning up... 07:52:40 INFO - Return code: 0 07:52:40 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:52:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:52:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:52:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:52:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:52:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:52:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:52:40 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 0x7f73f16e0e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12cba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1469120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14f9f20>, '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 0x1466c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1466f90>, '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': 'a0d2d2de893abaa8702a9954000001a7-1481896745.736146-1060331009', '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 07:52:40 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 07:52:40 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 07:52:40 INFO - Using env: {'DISPLAY': ':0', 07:52:40 INFO - 'HOME': '/home/cltbld', 07:52:40 INFO - 'LANG': 'en_US.UTF-8', 07:52:40 INFO - 'LANGUAGE': 'en_US:en', 07:52:40 INFO - 'LOGNAME': 'cltbld', 07:52:40 INFO - 'MAIL': '/var/mail/cltbld', 07:52:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:52:40 INFO - 'MOZ_NO_REMOTE': '1', 07:52:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:52:40 INFO - 'NO_EM_RESTART': '1', 07:52:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:52:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:52:40 INFO - 'PWD': '/builds/slave/test', 07:52:40 INFO - 'SHELL': '/bin/bash', 07:52:40 INFO - 'SHLVL': '1', 07:52:40 INFO - 'TERM': 'linux', 07:52:40 INFO - 'TMOUT': '86400', 07:52:40 INFO - 'USER': 'cltbld', 07:52:40 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1481896745.736146-1060331009', 07:52:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:52:40 INFO - '_': '/tools/buildbot/bin/python'} 07:52:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:52:40 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:52:40 INFO - Cleaning up... 07:52:40 INFO - Return code: 0 07:52:40 INFO - Running post-action listener: _resource_record_post_action 07:52:40 INFO - Running post-action listener: _start_resource_monitoring 07:52:40 INFO - Starting resource monitoring. 07:52:40 INFO - [mozharness: 2016-12-16 15:52:40.961972Z] Finished create-virtualenv step (success) 07:52:40 INFO - [mozharness: 2016-12-16 15:52:40.962436Z] Running install step. 07:52:40 INFO - Running pre-action listener: _resource_record_pre_action 07:52:40 INFO - Running main action method: install 07:52:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:52:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:52:41 INFO - Reading from file tmpfile_stdout 07:52:41 INFO - Detecting whether we're running mozinstall >=1.0... 07:52:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:52:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:52:41 INFO - Reading from file tmpfile_stdout 07:52:41 INFO - Output received: 07:52:41 INFO - Usage: mozinstall [options] installer 07:52:41 INFO - Options: 07:52:41 INFO - -h, --help show this help message and exit 07:52:41 INFO - -d DEST, --destination=DEST 07:52:41 INFO - Directory to install application into. [default: 07:52:41 INFO - "/builds/slave/test"] 07:52:41 INFO - --app=APP Application being installed. [default: firefox] 07:52:41 INFO - mkdir: /builds/slave/test/build/application 07:52:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 07:52:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 07:52:57 INFO - Reading from file tmpfile_stdout 07:52:57 INFO - Output received: 07:52:57 INFO - /builds/slave/test/build/application/firefox/firefox 07:52:57 INFO - Running post-action listener: _resource_record_post_action 07:52:57 INFO - [mozharness: 2016-12-16 15:52:57.280248Z] Finished install step (success) 07:52:57 INFO - [mozharness: 2016-12-16 15:52:57.280392Z] Running run-tests step. 07:52:57 INFO - Running pre-action listener: _resource_record_pre_action 07:52:57 INFO - Running main action method: run_tests 07:52:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:52:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:52:57 INFO - Python 2.7.3 07:52:57 INFO - Return code: 0 07:52:57 INFO - grabbing minidump binary from tooltool 07:52:57 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')]} 07:52:57 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 0x14f9f20>, '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 0x1466c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1466f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:52:57 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 07:52:57 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 07:52:57 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 07:52:57 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:52:57 INFO - Return code: 0 07:52:57 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 07:52:57 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:52:57 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:52:57 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 07:52:57 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:52:57 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/eKuB0yAhTdyySFIFm7Uc5g/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-002', '--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 07:52:57 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/eKuB0yAhTdyySFIFm7Uc5g/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-002 --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 07:52:57 INFO - Using env: {'DISPLAY': ':0', 07:52:57 INFO - 'HOME': '/home/cltbld', 07:52:57 INFO - 'LANG': 'en_US.UTF-8', 07:52:57 INFO - 'LANGUAGE': 'en_US:en', 07:52:57 INFO - 'LOGNAME': 'cltbld', 07:52:57 INFO - 'MAIL': '/var/mail/cltbld', 07:52:57 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:52:57 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 07:52:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:52:57 INFO - 'MOZ_NO_REMOTE': '1', 07:52:57 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:52:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:52:57 INFO - 'NO_EM_RESTART': '1', 07:52:57 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:52:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:52:57 INFO - 'PWD': '/builds/slave/test', 07:52:57 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:52:57 INFO - 'SHELL': '/bin/bash', 07:52:57 INFO - 'SHLVL': '1', 07:52:57 INFO - 'TERM': 'linux', 07:52:57 INFO - 'TMOUT': '86400', 07:52:57 INFO - 'USER': 'cltbld', 07:52:57 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1481896745.736146-1060331009', 07:52:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:52:57 INFO - '_': '/tools/buildbot/bin/python'} 07:52:57 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/eKuB0yAhTdyySFIFm7Uc5g/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-002', '--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 07:52:57 INFO - mozversion application_buildid: 20161216070349 07:52:57 INFO - mozversion application_changeset: 917fc78005ddd0f15013e8a9e0b473468a8b2f14 07:52:57 INFO - mozversion application_display_name: Nightly 07:52:57 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:52:57 INFO - mozversion application_name: Firefox 07:52:57 INFO - mozversion application_remotingname: firefox 07:52:57 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 07:52:57 INFO - mozversion application_vendor: Mozilla 07:52:57 INFO - mozversion application_version: 53.0a1 07:52:57 INFO - mozversion platform_buildid: 20161216070349 07:52:57 INFO - mozversion platform_changeset: 917fc78005ddd0f15013e8a9e0b473468a8b2f14 07:52:57 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 07:52:57 INFO - mozversion platform_version: 53.0a1 07:52:57 INFO - using testdate: 1481903577 07:52:57 INFO - actual date: 1481903577 07:52:57 INFO - starting webserver on 'localhost:51343' 07:52:57 INFO - SUITE-START | Running 2 tests 07:52:57 INFO - TEST-START | dromaeo_css 07:52:57 INFO - operating with platform_type : linux_ 07:52:57 INFO - Initialising browser for dromaeo_css test... 07:52:57 INFO - TEST-INFO | started process 20546 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpkmuh2H/profile http://localhost:51343/getInfo.html) 07:53:01 INFO - PROCESS | 20546 | __metrics Screen width/height:1600/1200 07:53:01 INFO - PROCESS | 20546 | colorDepth:24 07:53:01 INFO - PROCESS | 20546 | Browser inner width/height: 1024/697 07:53:01 INFO - PROCESS | 20546 | __metrics 07:53:02 INFO - PROCESS | 20546 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 07:53:02 INFO - PROCESS | 20546 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:53:03 INFO - PROCESS | 20546 | console.error: PushService: 07:53:03 INFO - PROCESS | 20546 | stateChangeProcessEnqueue: Error transitioning state 07:53:03 INFO - PROCESS | 20546 | UnknownError 07:53:03 INFO - TEST-INFO | 20546: exit 0 07:53:03 INFO - Browser initialized. 07:53:03 INFO - Running cycle 1/1 for dromaeo_css test... 07:53:03 INFO - TEST-INFO | started process 20629 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpkmuh2H/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:53:15 INFO - PROCESS | 20629 | RSS: Main: 156332032 07:53:15 INFO - PROCESS | 20629 | 07:54:06 INFO - PROCESS | 20629 | 1481903646628 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] 07:56:20 INFO - PROCESS | 20629 | Cycle 1(1): loaded http://localhost:51343/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51343/tests/dromaeo/cssquery-ext.html) 07:56:22 INFO - PROCESS | 20629 | RSS: Main: 290533376 07:56:22 INFO - PROCESS | 20629 | 07:59:25 INFO - PROCESS | 20629 | Cycle 1(1): loaded http://localhost:51343/tests/dromaeo/cssquery-ext.html (next: http://localhost:51343/tests/dromaeo/cssquery-jquery.html) 07:59:25 INFO - PROCESS | 20629 | RSS: Main: 256987136 07:59:25 INFO - PROCESS | 20629 | 08:02:28 INFO - PROCESS | 20629 | Cycle 1(1): loaded http://localhost:51343/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51343/tests/dromaeo/cssquery-mootools.html) 08:02:28 INFO - PROCESS | 20629 | RSS: Main: 216731648 08:02:28 INFO - PROCESS | 20629 | 08:05:31 INFO - PROCESS | 20629 | Cycle 1(1): loaded http://localhost:51343/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51343/tests/dromaeo/cssquery-prototype.html) 08:05:31 INFO - PROCESS | 20629 | RSS: Main: 190877696 08:05:31 INFO - PROCESS | 20629 | 08:08:34 INFO - PROCESS | 20629 | Cycle 1(1): loaded http://localhost:51343/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51343/tests/dromaeo/cssquery-yui.html) 08:08:35 INFO - PROCESS | 20629 | RSS: Main: 189878272 08:08:35 INFO - PROCESS | 20629 | 08:11:38 INFO - PROCESS | 20629 | Cycle 1(1): loaded http://localhost:51343/tests/dromaeo/cssquery-yui.html (next: http://localhost:51343/tests/dromaeo/cssquery-dojo.html) 08:11:38 INFO - PROCESS | 20629 | RSS: Main: 283144192 08:11:38 INFO - PROCESS | 20629 | 08:11:38 INFO - PROCESS | 20629 | __start_tp_report 08:11:38 INFO - PROCESS | 20629 | _x_x_mozilla_page_load 08:11:38 INFO - PROCESS | 20629 | _x_x_mozilla_page_load_details 08:11:38 INFO - PROCESS | 20629 | |i|pagename|runs| 08:11:38 INFO - PROCESS | 20629 | |0;dojo.html;2591.688997320322;2615.843049417036;2634.157069737684;2643.6166755820404;2652.920412387628;4711.293306004092;4730.739809310487;4735.857924262274;4759;4786.3059856320815;4684.601808846252;4737.218358970775;4758.547937945898;4786.736729479877;4788.114198873202;4313.2451820931365;4345.543717909622;4358.9234283444175;4374.234508960935;4437.090396468732;4028.186317350767;4476.932846007313;4477.552244775529;4494.9325760113625;4537.795799189044;4298.441202643637;4337.175936572041;4451.933221001687;4499.865004049884;4535.954640453603;4322.459692538433;4357.302831546937;4374.4531933508315;4381.452318460192;4417.536158703322;4333.263345231287;4334.848280310205;4340.414044104069;4372.366006929009;4413.249747542925;4729;4742.956549559091;4763.118823017753;4774.546418090275;4794.544518270759;4965.106280869442;5004.624653151028;5009.899802003976;5079.492050794891;5081.872953176163;5094.566961808251;5098.643094983353;5104.081265372231;5104.719240441774;5106.693598384109;4767.927216376357;4770.427548694144;4775.092732380871;4776.14029474691;4778.307145463958;5154.458781827854;5205.323307969939;5208.062548741187;5212.635115541952;5214.817481388133;4962.305277261186;4992.151334273182;5003.399592048978;5004.724740139254;5008.724520151354;5081.491850814963;5084.313617661643;5094.77073531692;5097.541221290102;5116.283720279164;4968.826091086867;4969.105560999054;4976.2038073908;5036.06832735945;5053.84838454847;5012.548870601663;5018.996200759862;5021.748912554358;5022.824201152942;5062.088824011713;4178.6865985051245;4209.7474151551005;4213.6207741302815;4272.16692744918;4311.051568654891;3714.3499887519574;3767.8869633911027;3779.130799916035;3823.4073718573663;3872.9806350968065;4475.507694153641;4475.753833539122;4488.461384633865;4498.370228168059;4515.661325400608;5004.349434573555;5144.408393034774;5144.768485418165;5146.30524879144;5171;4367.235733746582;4375.409319741858;4389.934150987738;4401.2517871961845;4415.425994620743;4602.148602508547;4603.9079218415445;4614.653900957374;4645.349651048856;4658.091672123971;4820.542048505386;4824.95175048245;4833.661643685049;4835.178019736723;4878.317035615088;4991.07665081961;5061.620378471555;5064.265681476239;5075.416327122281;5122.282880396822;4788.856334309976;4811.663183577116;4822.517748225149;4836.492168322276;4851.95148048515;5035.471275516018;5041.621878359065;5046.2153384961275;5061.772220250024;5091.745412729423;5031.7735701894235;5041.8487445376695;5125.205593133071;5127.128388173945;5174.508421699932;4929.507049295042;4946.109700253989;4968.55283024515;4983.227599722049;5043.344365232496;4962.578180854668;4978.751062446936;4979.153543897619;4985.6011519077665;5035.59715222774;4783.306420569068;4861.927071094007;4918.5573298403315;4922.655414120977;4924.138275801792;4918.581920536794;4941.604671626189;4947.950520494893;4986.401631804207;5053.494650534917;4860.173770459101;4867.7322747249245;4868.2940973557925;4869.561739443467;4911.238757992517 08:11:38 INFO - PROCESS | 20629 | |1;ext.html;511.1974200505206;519.5479932458761;529.6054439442615;535.4458135829416;543.1553933633202;29202.853985730068;38074.619253807454;39454.60545394545;39510.20979580407;39638.60361396385;41104.38343424851;41505.792471037676;41623.791881040626;41626.7918660407;41680.374794378105;7579.999999999986;7757.5733334666575;7762.8447431051345;7767.6116194190345;7771;7402.407807375397;7605.125410577799;9583.185429238523;9833.4099954003;9838.803223935516;9916.752081197992;9971.252156088287;9982.35114717541;9995.400275983427;9996;9829.115379615832;9884.209263258923;9889.703308900744;9895.901040989569;9897.109260166548;9671.371360861593;9693.418394896258;9718.222542196607;9722.270829687732;9743.317967742261;36896.077598059994;37310.62689373099;37328.066798329986;37346.199035072204;37370;2414.1550457339968;2618.947621047568;2674.0941543288955;2680.209338245222;2879.827210367385;28049.719502804914;28592.14223573296;28687.85656071727;28934.85532572345;29979;3065.5401689746495;3105.208171916147;3156.9842150789327;3183.2360233544014;3186.9840650796596;183751;183787;184180;184723;184729.0763546187;53049;53257.2011419829;53432.198517022276;53435.46564534305;53540.73229633905;55778;56002.719986400625;56008.43991560032;56021.43978560162;56024.719876400355;13111.475540978254;13116.344182790823;13127.474901004043;13137.540186093442;13283.667908302177;9962.053604907522;10284.177265818716;10711.571537138583;10806.891931080745;10808.56765729362;16359.91820040916;16609;16683.916580417263;17156.31374745021;17264.827351726322;6331.873362532723;6360.7137678804565;6404.5516813823515;6494.383033611799;6494.902576461357;743.353282644103;863.8833757442791;936.4802534593366;974.0794948773341;1007.8941711120227;24386.390340241636;28686.569701454497;28786.424271514454;29307;29318;888.4846788862444;927.6057675487891;941.764558860285;951.6241084771476;1020.6325722739808;12584.622461326175;13451.932740336235;13506.797398039038;13660.24868632215;13718.93140534311;340261;341825;343060.3950348215;343794;344738;42833.57166428296;42850;42881.57118428776;42986.78506607509;42995.57004429916;1013.8935411781804;1101.8787933327487;1102.8566286382718;1179.6991767099335;1193.3376975778353;24067.157649482186;25861.612075819256;26780.330491737863;28226.576601350585;29111.52552033016;41622.79188604037;42283.15433691248;42284.57715422929;42327.57672423359;42370.78814605907;329274;333972.27263409935;334360;334545;334750;22474.77525224727;23130.76869231286;23403;23437.648435273142;24698.259052228463;35851.82074089613;36146.819265903505;36439.453408198366;36458.63541364553;36952.81523592365;31115.377692446476;31592;31668.208294792814;32053;32356;30534.694653054074;31266.53100203544;31883.68116318807;32580.674193257764;32598.348033038732;28857;29147.854260729408;29311.26721831886;29982.850085749433;30730.84634576813;27685.446291073662;28991.130266092052;29425.70574294315;29524.852375737984;29632;22731;22758.431039224153;22866.885665571564;23040.539189216455;23160 08:11:38 INFO - PROCESS | 20629 | |2;jquery.html;347.88172021512713;352.2655263775031;371.18339652763916;375.91353988582614;386.29308365690787;12598.559050433236;12676.936615316921;13910.51313204038;14649.414023439076;16742.74885876713;16314.7552786708;16533.33866645333;17035.57411064724;17167.227474763666;17207.311707531717;4899.583535399486;4936.80252789888;5069.569086627641;5101.923471147936;5108.412532558747;4980.6762560433635;5066.214736715797;5070.239464080399;5072.137736584789;5073.0361231366105;4893.730844803535;5039.949600503985;5041.470645582216;5106.31064806252;5178.171492561191;5179.326687530634;5215.973920130375;5217.191335343028;5219.817306394258;5223;4986.152354099811;5008;5308.124159513675;5344.198370244494;5357.651752636066;11081.612143574936;11199.776004479947;11238.438078096062;11240.550377984871;11252;1719.6732620802009;1721.535185499908;1804.4225847728733;2067.865588736742;2152;8514.872276915852;8559;8592.570371481463;8790.692325768632;8994.550272486415;1509.2001239343165;1511.214168632316;1514.5380658899032;1545.0806769971848;1575.4643421236835;63289.19094498007;66960.66519667419;67097;67422.66288668575;68532.9999999995;9834.606615735434;10060.547275372626;10079.899201007896;10100.949495252624;10166.949165254126;13696.315184240948;13800.171989680652;13806.757391834783;13821.930890345484;13824.792628110587;7164.319389657974;7182.7845164644095;7297.16082650502;7360.116785985716;7550.395968322521;6693.832654183587;6764.154480689914;6773.45812335012;6925.549839260432;6952.965235173893;3825.9617403826164;4109.794510274474;4283.100548884707;4331.155424692155;4365.585269399402;1721;1789.4363275568155;1797.0957996296556;1839.8160183981763;1892.129620374643;795.8487887301394;849.2865992566151;862.344618090256;863.9524826134623;866.8136350684633;16100.75848862268;16186.271617776993;16729.665406692042;17182.828171718375;17218.483445496655;815.7104227999093;820.5445977482555;822.8971378577678;824.5011767880346;844.5439462690078;9064.180065592804;9127.726168214964;9442.622295108118;9487.241020718326;9544.42733435996;363943;368195;370179;371590;376489;59092;70447.64776176086;72326.27673723301;72438.63780681168;73267;887.8002449448823;893.65147592439;937.2408349237139;942.6417961174847;946.3328353510758;15236.466723664618;15359.769603455734;15923.601909952344;16231.918840405722;16261.398176291737;32572;35169;35524.46713299251;36096.4585531212;36259.637403625624;38749.80625096969;46816.7659161702;48849.511504885915;49235.75382123067;50493.49506504888;7095.467839911924;7201.963990180225;7493.363064139391;7668.654910528928;7745.22547745221;11194.272372295774;12080.939595301967;12216.938915305367;12299.323537205539;12301.863024928944;5253.605979551472;6295.779647712308;8441.480533504027;10229.539670714687;10564.049235568837;10025.298229123891;10259.948700256451;10265.538050787583;10413.531391087268;10526.842097368393;10137.594496220363;10362.326448781107;10399;10998.12015038779;11074.169437292074;6695.296993815774;6797.422219111431;6840.657967101525;7149.463790215651;7734.342580880688;7895.7236496722335;9429.62281508732;9630.374025688308;9884.703458896245;9959.502024898871 08:11:38 INFO - PROCESS | 20629 | |3;mootools.html;1286.6333095067905;1299.2659147581617;1366.6788304748388;1622.98125486595;1662.6924019056469;6942.722291108356;7369.852602947944;7370.1892791792925;7372.668229929653;7374.483786134972;6200.689965501718;6405.359464053592;6414.422701956823;6537.8365540861505;7212.963935180317;1881.2004897918398;2007.8594498385119;2079.833613310939;2081.677340012293;2094.1623350659784;2125.288028510443;2137.8610390324657;2142.5286436983915;2145.731783527059;2148.34475484977;2051.05651400356;2121.3317804891485;2124.60694771467;2128.6700561413004;2128.723265975421;2049.4568939231117;2051.825594824442;2074.481379655094;2084.8853313067775;2087.6033553624916;2084.697718830776;2106.420734298065;2112.0601332407086;2128.5317230209484;2141.7536983246814;7298.379637730799;7356.043714317105;7362;7376.077990251219;7378.667959941762;4412.382266482695;4588.220002599566;4590.747508887043;4593.379893714339;4597.586217240431;2897.27568107973;2935.823850568973;2950.022048367666;2967.4361871244396;2979.8063125896747;887.2946007923651;888.8044630181349;910.7677542226756;930.2883294279882;931.3329140837892;9608.183304419133;9689.370190937567;9692.660756873476;9696.90303096967;9708.708738737849;3984.30274701926;4030.314846476106;4130.153318569686;4168.333066709312;4171.353440216772;8469.872951905727;8480.787980300418;8489.405741598152;8491.065982741893;8510;4469.08383781324;4470.262406702923;4471.19518486669;4477.485089214717;4481.574250446202;4030.979845100756;4064.634182923551;4079.204555111784;4084.7753373564146;4158.168366326747;2982.209714425679;3031.4998025326017;3177.3327601204005;3232.7413806895393;3260.510923361467;966.9951650241704;969.3408482232009;976.5654283843621;978.6379039755336;982.1847866270978;802.1577343788998;821.1747194070041;827.8344331133798;830.2735106781566;836.0302049622409;4700.55344742249;4817.735024573612;4915.606751459874;4923.975380123076;4937.21004639256;774.705611867498;783.8197214640551;793.8332950080433;805.7743831727125;863.5380071661631;3083.8435586655005;3153.44814657434;3248.5776849009476;3248.797944760454;3248.8375581220844;8501.787455313666;8618.138186181457;8640.179182977607;8722.694705685271;8745.12548745133;8477.618507167192;8683.652653893774;8686.826263474695;8687.087855775053;8689.391742578086;6541;6665.500087493458;6668.199816022108;6669.066330713704;6669.899951500732;4719.10337035962;4734.597559207506;4770.260609605517;4806.807727690853;4808.134535783593;7889.0138732658415;7920.168382320004;8004.679812807422;8060.03279606451;8064.3548516119745;7901.209879012283;8106.797330066795;8203.876941845996;8216.835663286582;8232.259096681328;5912.172295878494;6108.664023478751;6156.630602163885;6268.686565671607;6273.96863015682;6064.818055458343;6243.7814676486105;6606.53754237218;6838.14523184602;6868.759593414206;6263.404976527223;6322.0516922462;6359.49124070064;6370.649614271259;6390.329015453496;6204.441600255998;6300.432961033529;6347.238331400262;6452.645104519296;6489.1888513935755;5441.26542916717;5488.588355873246;5542.0855558832345;5560.416156303691;5570.61005729595;4062.309407400808;4084.162746636992;4147.6267135957905;4163.12574359388;4240.957590424056;4170.291050521358;4218.76796776168;4221.451211342506;4266.0827921996915;4346.130773845307 08:11:38 INFO - PROCESS | 20629 | |4;prototype.html;347.13563227563367;357.84433771309483;361.2305788670132;361.7034032093683;363.33147009502494;4825.2279635258365;4831.37192165018;4831.589314908233;4847.878803029926;4855.688963979728;4774.355462012627;4775.450823155338;4780.928286075712;4782.33047373368;4794.808207671689;3445.0870519312402;3592.6227746086674;3684.852605895761;3837.4458344370573;3908.374660054392;3799.563050249229;3812.961870381302;3816.8282427290837;3833.9808300958457;3849.2493963677093;3832.463455116272;3834.1404418856832;3834.233153369323;3839.5584507781546;3841.7310788244836;3654.2143439160577;3712.257548490286;3757.2861156380336;3833.731638785286;3863.594322596115;3467.323871844991;3474.9305013899834;3475.7566970312087;3481.781376518198;3487.808170550619;4584.7992963377765;4671.5094915034115;4675.368825208621;4691.929621055653;4696.256780651566;4271.444712187395;4336.891577710551;4381.145676593065;4389.626881715058;4394.62645675118;4964.329815474937;4976.527229913152;4980.377452818398;4994.525520075587;5006.524380183877;3775.452559378875;3797.468354430382;3797.6012518685416;3802.2205447883116;3802.90492737681;5061.924071138899;5218.9739051304505;5230.189320655304;5240.213967904807;5247.081760691902;3863.671587915059;3868.9806550967064;3886.8639597613947;3890;3898.317794385971;3920.176762879827;3927.332353499855;3931.803409829497;3943.6253555912717;3953.723239373274;4364.585364390377;4368.60682538573;4377.8248870044845;4380.890477738082;4381.590125431;4773.95226047742;4938.950610493921;4960.379952505937;4990.50094990498;4990.600751939836;1331.973360532784;1333.3133436280323;1339.8794108530278;1339.9330033498286;1344.675494637781;899.6896070855545;917.7200953709049;971.2569884038716;976.8534719792088;979.4661909259328;4359.215341238544;4414.938256803748;4446.732166595059;4447.488538818077;4474.418325617649;5147.356580427447;5148.253503242009;5148.9742551287;5152.007194424351;5156.123459011977;4297.109271920345;4439.600435960714;4445.644348452116;4451.198784218807;4460.264056430718;2643.1013455424863;2646.603009548583;2680.8793604287853;2692.5018871508832;2699.7705195058247;4733.355316406417;4747.2879068140055;4755.595774359149;4756.619470442356;4772.618190544748;4961.2558116282125;5113.923291150636;5138.563222126161;5174.586033117342;5192.209686928488;4674.392328997209;4676.976615116971;4687.523430119501;4699.577038066522;4701.64737644678;5002.874928126826;5006.849794506171;5007.674501157413;5016.122178618654;5017.67385119966;4660.6271498279375;4910.631702622389;4919.827806026772;4932.235503496964;4948.480409556944;4673.845154520753;4694.859154225379;4697.718136911796;4700.083483720641;4713.175194341045;3795.7342985991822;3819.2049736623744;3819.5034645495907;3822.7706337619834;3825.0819803247578;4761.952380476151;4997.400311962588;5006.474320196473;5007.949920500749;5012.669108864396;4832.782524786463;4845;4845.200541910686;4847.830325938575;4856.320115183807;4822.697871574585;4834.806607735652;4840.419149701917;4863.392075990501;4884.242942343943;4654.091193004372;4763.476017637988;4779.091972525209;4811.27830825379;4826.203676393497;4775.785089671042;4844.61243100544;4860.222364421676;4919.942212424346;4920.458749537476;4886.951130488792;4887.633427492881;4894.2658601210105;4906.681065730716;4910.263460480956 08:11:38 INFO - PROCESS | 20629 | |5;yui.html;569.3566270114769;573.2175580332847;586.0915580849683;587.6797145555672;680.3978479046045;12267;13298.069135160518;13393.799093013587;13419.530316438926;13428.798568021475;12598;13599.18404895707;13646.317684115804;13663.726725465509;13744.518941837037;618.8236352639499;647.132841991731;774.3418094619557;785.2893131715806;785.3834739729314;749.2357795049047;774.2025713515088;778.7546922719325;785.5836406704456;785.787837283933;829.713748756678;830.5182993863575;831.476170012891;831.9417640765149;831.9542425166613;642.6015870160484;671.6205343980636;819.1317203764029;819.5983967855708;819.8811172380001;498.4367664539084;498.6210480035163;498.84286449768206;572.9054705973509;602.7167231401235;13420.463181472729;13493.595192144252;13505.86494135056;13525.120867143603;13533;11744.765104697859;11777.293362398283;12133;12134;12713.93643031779;9954.701358959242;10067.748306292327;10079.5464204111;10134.087932086148;10286.485675716185;3960.4257382679352;3967.841286348543;3987.760734355948;4020.0954785173103;4028.8992775180564;57609.711951439975;60422.69788651029;60514.69742651258;61467.385326146614;62361;9452.338336316392;9532.52337383128;9577.664781732607;9682.70951871445;9861.556229969668;18999.24003039863;19359.612807743768;19419.51451213731;19612.803871961383;19779.307724229584;5983.491403230687;6000.879982400327;6141.355157708376;6441.935580644227;6496.512761542903;3457.049311439324;3567.4827150063097;3586.7847929124337;3601.747877648592;3697.1681371691157;2693.3805224798407;2822.618946442245;2848.074375827864;2883;2889.8729495496773;531.7952588253551;542.2625229687621;543.0577947261498;543.741722681731;548.2707998362168;504.9747512624354;540.4568408749182;581.2763109928167;582.3303201318517;583.4136692623971;7865.842683146305;7887;7981.561014144162;8105.067917189481;8230.079229346242;550.3780727777665;572.7382556341595;575.3529111767983;582.4495851420452;592.2330581896455;3899.259140763246;3915.6084391561185;3923.136909879822;3936.9212615747524;4109.938350924738;67451;68384.65807671029;68793;69021;70298;53125.7343713279;59175;59176;59937.40062599405;61839.381606183364;40871.38692919609;43780;46047.309290360674;46947.53052469432;47831.5216847834;7621.771346859603;8337.166283371615;8345.248927596545;8502.14978502145;8502.872456913028;33627;34637.82681086579;35015.299694006484;36163.095922602144;36951.26097478089;41918;46434.767826160656;47311.29033064576;48094.27858582194;48397;10949.233553651167;11010.779784404107;11070.169737269576;11086.223964322742;11575.942120289345;11998.700032499257;12087.939560302142;12092.75814483723;12133.69665758363;12628.052896033018;11392.829107563559;11615.535378584762;11683.474243658886;11863.34751588671;11967.521299147937;11203.551857925917;11265.774684506428;11546.48040838181;11662.53349865996;11777.646670599896;10731.731706707395;10924.50839712199;11042.116630669689;11058.22592418523;11388.177891537314;9427.434353938786;9439.81120377575;9556.665516706882;9607.51962401863;9863.753406164904;9118.772030699285;9290.72127836166;9483.478408687588;9871.16095131893;9871.841010876451 08:11:38 INFO - PROCESS | 20629 | __end_tp_report 08:11:38 INFO - PROCESS | 20629 | __start_cc_report 08:11:38 INFO - PROCESS | 20629 | _x_x_mozilla_cycle_collect,1573 08:11:38 INFO - PROCESS | 20629 | __end_cc_report 08:11:38 INFO - PROCESS | 20629 | __startTimestamp1481904698888__endTimestamp 08:11:38 INFO - PROCESS | 20629 | 08:11:38 INFO - PROCESS | 20629 | ------- Summary: start ------- 08:11:38 INFO - PROCESS | 20629 | Number of tests: 6 08:11:38 INFO - PROCESS | 20629 | 08:11:38 INFO - PROCESS | 20629 | [#0] dojo.html Cycles:165 Average:4704.62 Median:4835.18 stddev:492.52 (10.2%) stddev-sans-first:465.30 08:11:38 INFO - PROCESS | 20629 | Values: 2591.7 2615.8 2634.2 2643.6 2652.9 4711.3 4730.7 4735.9 4759.0 4786.3 4684.6 4737.2 4758.5 4786.7 4788.1 4313.2 4345.5 4358.9 4374.2 4437.1 4028.2 4476.9 4477.6 4494.9 4537.8 4298.4 4337.2 4451.9 4499.9 4536.0 4322.5 4357.3 4374.5 4381.5 4417.5 4333.3 4334.8 4340.4 4372.4 4413.2 4729.0 4743.0 4763.1 4774.5 4794.5 4965.1 5004.6 5009.9 5079.5 5081.9 5094.6 5098.6 5104.1 5104.7 5106.7 4767.9 4770.4 4775.1 4776.1 4778.3 5154.5 5205.3 5208.1 5212.6 5214.8 4962.3 4992.2 5003.4 5004.7 5008.7 5081.5 5084.3 5094.8 5097.5 5116.3 4968.8 4969.1 4976.2 5036.1 5053.8 5012.5 5019.0 5021.7 5022.8 5062.1 4178.7 4209.7 4213.6 4272.2 4311.1 3714.3 3767.9 3779.1 3823.4 3873.0 4475.5 4475.8 4488.5 4498.4 4515.7 5004.3 5144.4 5144.8 5146.3 5171.0 4367.2 4375.4 4389.9 4401.3 4415.4 4602.1 4603.9 4614.7 4645.3 4658.1 4820.5 4825.0 4833.7 4835.2 4878.3 4991.1 5061.6 5064.3 5075.4 5122.3 4788.9 4811.7 4822.5 4836.5 4852.0 5035.5 5041.6 5046.2 5061.8 5091.7 5031.8 5041.8 5125.2 5127.1 5174.5 4929.5 4946.1 4968.6 4983.2 5043.3 4962.6 4978.8 4979.2 4985.6 5035.6 4783.3 4861.9 4918.6 4922.7 4924.1 4918.6 4941.6 4948.0 4986.4 5053.5 4860.2 4867.7 4868.3 4869.6 4911.2 08:11:38 INFO - PROCESS | 20629 | 08:11:38 INFO - PROCESS | 20629 | [#1] ext.html Cycles:180 Average:43751.47 Median:334647.50 stddev:77941.08 (23.3%) stddev-sans-first:78092.11 08:11:38 INFO - PROCESS | 20629 | Values: 511.2 519.5 529.6 535.4 543.2 29202.9 38074.6 39454.6 39510.2 39638.6 41104.4 41505.8 41623.8 41626.8 41680.4 7580.0 7757.6 7762.8 7767.6 7771.0 7402.4 7605.1 9583.2 9833.4 9838.8 9916.8 9971.3 9982.4 9995.4 9996.0 9829.1 9884.2 9889.7 9895.9 9897.1 9671.4 9693.4 9718.2 9722.3 9743.3 36896.1 37310.6 37328.1 37346.2 37370.0 2414.2 2618.9 2674.1 2680.2 2879.8 28049.7 28592.1 28687.9 28934.9 29979.0 3065.5 3105.2 3157.0 3183.2 3187.0 183751.0 183787.0 184180.0 184723.0 184729.1 53049.0 53257.2 53432.2 53435.5 53540.7 55778.0 56002.7 56008.4 56021.4 56024.7 13111.5 13116.3 13127.5 13137.5 13283.7 9962.1 10284.2 10711.6 10806.9 10808.6 16359.9 16609.0 16683.9 17156.3 17264.8 6331.9 6360.7 6404.6 6494.4 6494.9 743.4 863.9 936.5 974.1 1007.9 24386.4 28686.6 28786.4 29307.0 29318.0 888.5 927.6 941.8 951.6 1020.6 12584.6 13451.9 13506.8 13660.2 13718.9 340261.0 341825.0 343060.4 343794.0 344738.0 42833.6 42850.0 42881.6 42986.8 42995.6 1013.9 1101.9 1102.9 1179.7 1193.3 24067.2 25861.6 26780.3 28226.6 29111.5 41622.8 42283.2 42284.6 42327.6 42370.8 329274.0 333972.3 334360.0 334545.0 334750.0 22474.8 23130.8 23403.0 23437.6 24698.3 35851.8 36146.8 36439.5 36458.6 36952.8 31115.4 31592.0 31668.2 32053.0 32356.0 30534.7 31266.5 31883.7 32580.7 32598.3 28857.0 29147.9 29311.3 29982.9 30730.8 27685.4 28991.1 29425.7 29524.9 29632.0 22731.0 22758.4 22866.9 23040.5 23160.0 08:11:38 INFO - PROCESS | 20629 | 08:11:38 INFO - PROCESS | 20629 | [#2] jquery.html Cycles:180 Average:22824.64 Median:27067.67 stddev:61055.57 (225.6%) stddev-sans-first:61203.52 08:11:38 INFO - PROCESS | 20629 | Values: 347.9 352.3 371.2 375.9 386.3 12598.6 12676.9 13910.5 14649.4 16742.7 16314.8 16533.3 17035.6 17167.2 17207.3 4899.6 4936.8 5069.6 5101.9 5108.4 4980.7 5066.2 5070.2 5072.1 5073.0 4893.7 5039.9 5041.5 5106.3 5178.2 5179.3 5216.0 5217.2 5219.8 5223.0 4986.2 5008.0 5308.1 5344.2 5357.7 11081.6 11199.8 11238.4 11240.6 11252.0 1719.7 1721.5 1804.4 2067.9 2152.0 8514.9 8559.0 8592.6 8790.7 8994.6 1509.2 1511.2 1514.5 1545.1 1575.5 63289.2 66960.7 67097.0 67422.7 68533.0 9834.6 10060.5 10079.9 10100.9 10166.9 13696.3 13800.2 13806.8 13821.9 13824.8 7164.3 7182.8 7297.2 7360.1 7550.4 6693.8 6764.2 6773.5 6925.5 6953.0 3826.0 4109.8 4283.1 4331.2 4365.6 1721.0 1789.4 1797.1 1839.8 1892.1 795.8 849.3 862.3 864.0 866.8 16100.8 16186.3 16729.7 17182.8 17218.5 815.7 820.5 822.9 824.5 844.5 9064.2 9127.7 9442.6 9487.2 9544.4 363943.0 368195.0 370179.0 371590.0 376489.0 59092.0 70447.6 72326.3 72438.6 73267.0 887.8 893.7 937.2 942.6 946.3 15236.5 15359.8 15923.6 16231.9 16261.4 32572.0 35169.0 35524.5 36096.5 36259.6 38749.8 46816.8 48849.5 49235.8 50493.5 7095.5 7202.0 7493.4 7668.7 7745.2 11194.3 12080.9 12216.9 12299.3 12301.9 5253.6 6295.8 8441.5 10229.5 10564.0 10025.3 10259.9 10265.5 10413.5 10526.8 10137.6 10362.3 10399.0 10998.1 11074.2 6695.3 6797.4 6840.7 7149.5 7734.3 7895.7 9429.6 9630.4 9884.7 9959.5 08:11:38 INFO - PROCESS | 20629 | 08:11:38 INFO - PROCESS | 20629 | [#3] mootools.html Cycles:180 Average:4728.60 Median:5988.50 stddev:2577.74 (43.0%) stddev-sans-first:2572.00 08:11:38 INFO - PROCESS | 20629 | Values: 1286.6 1299.3 1366.7 1623.0 1662.7 6942.7 7369.9 7370.2 7372.7 7374.5 6200.7 6405.4 6414.4 6537.8 7213.0 1881.2 2007.9 2079.8 2081.7 2094.2 2125.3 2137.9 2142.5 2145.7 2148.3 2051.1 2121.3 2124.6 2128.7 2128.7 2049.5 2051.8 2074.5 2084.9 2087.6 2084.7 2106.4 2112.1 2128.5 2141.8 7298.4 7356.0 7362.0 7376.1 7378.7 4412.4 4588.2 4590.7 4593.4 4597.6 2897.3 2935.8 2950.0 2967.4 2979.8 887.3 888.8 910.8 930.3 931.3 9608.2 9689.4 9692.7 9696.9 9708.7 3984.3 4030.3 4130.2 4168.3 4171.4 8469.9 8480.8 8489.4 8491.1 8510.0 4469.1 4470.3 4471.2 4477.5 4481.6 4031.0 4064.6 4079.2 4084.8 4158.2 2982.2 3031.5 3177.3 3232.7 3260.5 967.0 969.3 976.6 978.6 982.2 802.2 821.2 827.8 830.3 836.0 4700.6 4817.7 4915.6 4924.0 4937.2 774.7 783.8 793.8 805.8 863.5 3083.8 3153.4 3248.6 3248.8 3248.8 8501.8 8618.1 8640.2 8722.7 8745.1 8477.6 8683.7 8686.8 8687.1 8689.4 6541.0 6665.5 6668.2 6669.1 6669.9 4719.1 4734.6 4770.3 4806.8 4808.1 7889.0 7920.2 8004.7 8060.0 8064.4 7901.2 8106.8 8203.9 8216.8 8232.3 5912.2 6108.7 6156.6 6268.7 6274.0 6064.8 6243.8 6606.5 6838.1 6868.8 6263.4 6322.1 6359.5 6370.6 6390.3 6204.4 6300.4 6347.2 6452.6 6489.2 5441.3 5488.6 5542.1 5560.4 5570.6 4062.3 4084.2 4147.6 4163.1 4241.0 4170.3 4218.8 4221.5 4266.1 4346.1 08:11:38 INFO - PROCESS | 20629 | 08:11:38 INFO - PROCESS | 20629 | [#4] prototype.html Cycles:180 Average:4160.68 Median:4695.56 stddev:1148.03 (24.4%) stddev-sans-first:1115.00 08:11:38 INFO - PROCESS | 20629 | Values: 347.1 357.8 361.2 361.7 363.3 4825.2 4831.4 4831.6 4847.9 4855.7 4774.4 4775.5 4780.9 4782.3 4794.8 3445.1 3592.6 3684.9 3837.4 3908.4 3799.6 3813.0 3816.8 3834.0 3849.2 3832.5 3834.1 3834.2 3839.6 3841.7 3654.2 3712.3 3757.3 3833.7 3863.6 3467.3 3474.9 3475.8 3481.8 3487.8 4584.8 4671.5 4675.4 4691.9 4696.3 4271.4 4336.9 4381.1 4389.6 4394.6 4964.3 4976.5 4980.4 4994.5 5006.5 3775.5 3797.5 3797.6 3802.2 3802.9 5061.9 5219.0 5230.2 5240.2 5247.1 3863.7 3869.0 3886.9 3890.0 3898.3 3920.2 3927.3 3931.8 3943.6 3953.7 4364.6 4368.6 4377.8 4380.9 4381.6 4774.0 4939.0 4960.4 4990.5 4990.6 1332.0 1333.3 1339.9 1339.9 1344.7 899.7 917.7 971.3 976.9 979.5 4359.2 4414.9 4446.7 4447.5 4474.4 5147.4 5148.3 5149.0 5152.0 5156.1 4297.1 4439.6 4445.6 4451.2 4460.3 2643.1 2646.6 2680.9 2692.5 2699.8 4733.4 4747.3 4755.6 4756.6 4772.6 4961.3 5113.9 5138.6 5174.6 5192.2 4674.4 4677.0 4687.5 4699.6 4701.6 5002.9 5006.8 5007.7 5016.1 5017.7 4660.6 4910.6 4919.8 4932.2 4948.5 4673.8 4694.9 4697.7 4700.1 4713.2 3795.7 3819.2 3819.5 3822.8 3825.1 4762.0 4997.4 5006.5 5007.9 5012.7 4832.8 4845.0 4845.2 4847.8 4856.3 4822.7 4834.8 4840.4 4863.4 4884.2 4654.1 4763.5 4779.1 4811.3 4826.2 4775.8 4844.6 4860.2 4919.9 4920.5 4887.0 4887.6 4894.3 4906.7 4910.3 08:11:38 INFO - PROCESS | 20629 | 08:11:38 INFO - PROCESS | 20629 | [#5] yui.html Cycles:180 Average:14596.82 Median:498.73 stddev:18268.23 (3662.9%) stddev-sans-first:18289.11 08:11:38 INFO - PROCESS | 20629 | Values: 569.4 573.2 586.1 587.7 680.4 12267.0 13298.1 13393.8 13419.5 13428.8 12598.0 13599.2 13646.3 13663.7 13744.5 618.8 647.1 774.3 785.3 785.4 749.2 774.2 778.8 785.6 785.8 829.7 830.5 831.5 831.9 832.0 642.6 671.6 819.1 819.6 819.9 498.4 498.6 498.8 572.9 602.7 13420.5 13493.6 13505.9 13525.1 13533.0 11744.8 11777.3 12133.0 12134.0 12713.9 9954.7 10067.7 10079.5 10134.1 10286.5 3960.4 3967.8 3987.8 4020.1 4028.9 57609.7 60422.7 60514.7 61467.4 62361.0 9452.3 9532.5 9577.7 9682.7 9861.6 18999.2 19359.6 19419.5 19612.8 19779.3 5983.5 6000.9 6141.4 6441.9 6496.5 3457.0 3567.5 3586.8 3601.7 3697.2 2693.4 2822.6 2848.1 2883.0 2889.9 531.8 542.3 543.1 543.7 548.3 505.0 540.5 581.3 582.3 583.4 7865.8 7887.0 7981.6 8105.1 8230.1 550.4 572.7 575.4 582.4 592.2 3899.3 3915.6 3923.1 3936.9 4109.9 67451.0 68384.7 68793.0 69021.0 70298.0 53125.7 59175.0 59176.0 59937.4 61839.4 40871.4 43780.0 46047.3 46947.5 47831.5 7621.8 8337.2 8345.2 8502.1 8502.9 33627.0 34637.8 35015.3 36163.1 36951.3 41918.0 46434.8 47311.3 48094.3 48397.0 10949.2 11010.8 11070.2 11086.2 11575.9 11998.7 12087.9 12092.8 12133.7 12628.1 11392.8 11615.5 11683.5 11863.3 11967.5 11203.6 11265.8 11546.5 11662.5 11777.6 10731.7 10924.5 11042.1 11058.2 11388.2 9427.4 9439.8 9556.7 9607.5 9863.8 9118.8 9290.7 9483.5 9871.2 9871.8 08:11:38 INFO - PROCESS | 20629 | -------- Summary: end -------- 08:11:38 INFO - PROCESS | 20629 | 08:11:39 INFO - TEST-INFO | 20629: exit 0 08:11:39 INFO - TEST-OK | dromaeo_css | took 1122200ms 08:11:39 INFO - TEST-START | kraken 08:11:39 INFO - operating with platform_type : linux_ 08:11:39 INFO - Initialising browser for kraken test... 08:11:39 INFO - TEST-INFO | started process 20935 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpV_buaR/profile http://localhost:51343/getInfo.html) 08:11:43 INFO - PROCESS | 20935 | __metrics Screen width/height:1600/1200 08:11:43 INFO - PROCESS | 20935 | colorDepth:24 08:11:43 INFO - PROCESS | 20935 | Browser inner width/height: 1024/697 08:11:43 INFO - PROCESS | 20935 | __metrics 08:11:44 INFO - PROCESS | 20935 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 08:11:44 INFO - PROCESS | 20935 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:11:44 INFO - PROCESS | 20935 | console.error: PushService: 08:11:44 INFO - PROCESS | 20935 | stateChangeProcessEnqueue: Error transitioning state 08:11:44 INFO - PROCESS | 20935 | UnknownError 08:11:45 INFO - TEST-INFO | 20935: exit 0 08:11:45 INFO - Browser initialized. 08:11:45 INFO - Running cycle 1/1 for kraken test... 08:11:45 INFO - TEST-INFO | started process 21018 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpV_buaR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:11:57 INFO - PROCESS | 21018 | RSS: Main: 155791360 08:11:57 INFO - PROCESS | 21018 | 08:12:00 INFO - PROCESS | 21018 | Cycle 1(1): loaded http://localhost:51343/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51343/tests/kraken/driver.html?testName=audio-beat-detection) 08:12:01 INFO - PROCESS | 21018 | RSS: Main: 209551360 08:12:01 INFO - PROCESS | 21018 | 08:12:04 INFO - PROCESS | 21018 | Cycle 1(1): loaded http://localhost:51343/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51343/tests/kraken/driver.html?testName=audio-dft) 08:12:04 INFO - PROCESS | 21018 | RSS: Main: 188190720 08:12:04 INFO - PROCESS | 21018 | 08:12:07 INFO - PROCESS | 21018 | Cycle 1(1): loaded http://localhost:51343/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51343/tests/kraken/driver.html?testName=audio-fft) 08:12:08 INFO - PROCESS | 21018 | RSS: Main: 270508032 08:12:08 INFO - PROCESS | 21018 | 08:12:10 INFO - PROCESS | 21018 | Cycle 1(1): loaded http://localhost:51343/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51343/tests/kraken/driver.html?testName=audio-oscillator) 08:12:11 INFO - PROCESS | 21018 | RSS: Main: 181649408 08:12:11 INFO - PROCESS | 21018 | 08:12:13 INFO - PROCESS | 21018 | Cycle 1(1): loaded http://localhost:51343/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51343/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:12:13 INFO - PROCESS | 21018 | RSS: Main: 185012224 08:12:13 INFO - PROCESS | 21018 | 08:12:17 INFO - PROCESS | 21018 | Cycle 1(1): loaded http://localhost:51343/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51343/tests/kraken/driver.html?testName=imaging-darkroom) 08:12:17 INFO - PROCESS | 21018 | RSS: Main: 224153600 08:12:17 INFO - PROCESS | 21018 | 08:12:21 INFO - PROCESS | 21018 | Cycle 1(1): loaded http://localhost:51343/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51343/tests/kraken/driver.html?testName=imaging-desaturate) 08:12:21 INFO - PROCESS | 21018 | RSS: Main: 218464256 08:12:21 INFO - PROCESS | 21018 | 08:12:24 INFO - PROCESS | 21018 | Cycle 1(1): loaded http://localhost:51343/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51343/tests/kraken/driver.html?testName=json-parse-financial) 08:12:25 INFO - PROCESS | 21018 | RSS: Main: 219860992 08:12:25 INFO - PROCESS | 21018 | 08:12:27 INFO - PROCESS | 21018 | Cycle 1(1): loaded http://localhost:51343/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51343/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:12:27 INFO - PROCESS | 21018 | RSS: Main: 183078912 08:12:27 INFO - PROCESS | 21018 | 08:12:29 INFO - PROCESS | 21018 | Cycle 1(1): loaded http://localhost:51343/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51343/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:12:30 INFO - PROCESS | 21018 | RSS: Main: 191172608 08:12:30 INFO - PROCESS | 21018 | 08:12:32 INFO - PROCESS | 21018 | Cycle 1(1): loaded http://localhost:51343/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51343/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:12:32 INFO - PROCESS | 21018 | RSS: Main: 199114752 08:12:32 INFO - PROCESS | 21018 | 08:12:35 INFO - PROCESS | 21018 | Cycle 1(1): loaded http://localhost:51343/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51343/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:12:36 INFO - PROCESS | 21018 | RSS: Main: 208171008 08:12:36 INFO - PROCESS | 21018 | 08:12:39 INFO - PROCESS | 21018 | Cycle 1(1): loaded http://localhost:51343/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51343/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:12:40 INFO - PROCESS | 21018 | RSS: Main: 204607488 08:12:40 INFO - PROCESS | 21018 | 08:12:41 INFO - PROCESS | 21018 | Cycle 1(1): loaded http://localhost:51343/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51343/tests/kraken/driver.html?testName=ai-astar) 08:12:42 INFO - PROCESS | 21018 | RSS: Main: 256008192 08:12:42 INFO - PROCESS | 21018 | 08:12:42 INFO - PROCESS | 21018 | __start_tp_report 08:12:42 INFO - PROCESS | 21018 | _x_x_mozilla_page_load 08:12:42 INFO - PROCESS | 21018 | _x_x_mozilla_page_load_details 08:12:42 INFO - PROCESS | 21018 | |i|pagename|runs| 08:12:42 INFO - PROCESS | 21018 | |0;ai-astar;107;115;115;115;116;117;116;117;116;116 08:12:42 INFO - PROCESS | 21018 | |1;audio-beat-detection;149;125;125;163;127;125;125;128;156;127 08:12:42 INFO - PROCESS | 21018 | |2;audio-dft;162;162;162;162;163;163;162;163;161;163 08:12:42 INFO - PROCESS | 21018 | |3;audio-fft;75;76;76;100;78;77;76;75;91;77 08:12:42 INFO - PROCESS | 21018 | |4;audio-oscillator;84;84;107;86;85;84;106;85;84;104 08:12:42 INFO - PROCESS | 21018 | |5;imaging-gaussian-blur;127;128;127;128;127;127;127;127;127;127 08:12:42 INFO - PROCESS | 21018 | |6;imaging-darkroom;147;146;147;147;146;147;146;146;147;146 08:12:42 INFO - PROCESS | 21018 | |7;imaging-desaturate;95;95;95;96;95;95;95;96;95;96 08:12:42 INFO - PROCESS | 21018 | |8;json-parse-financial;65;64;64;64;65;65;65;66;67;67 08:12:42 INFO - PROCESS | 21018 | |9;json-stringify-tinderbox;66;67;66;66;66;66;66;67;67;67 08:12:42 INFO - PROCESS | 21018 | |10;stanford-crypto-aes;94;89;91;85;85;85;85;84;84;84 08:12:42 INFO - PROCESS | 21018 | |11;stanford-crypto-ccm;146;137;139;134;143;147;137;132;186;144 08:12:42 INFO - PROCESS | 21018 | |12;stanford-crypto-pbkdf2;185;182;176;176;179;176;177;176;177;176 08:12:42 INFO - PROCESS | 21018 | |13;stanford-crypto-sha256-iterative;69;70;67;66;68;67;67;67;67;66 08:12:42 INFO - PROCESS | 21018 | __end_tp_report 08:12:42 INFO - PROCESS | 21018 | __start_cc_report 08:12:42 INFO - PROCESS | 21018 | _x_x_mozilla_cycle_collect,1956 08:12:42 INFO - PROCESS | 21018 | __end_cc_report 08:12:42 INFO - PROCESS | 21018 | __startTimestamp1481904762147__endTimestamp 08:12:42 INFO - PROCESS | 21018 | 08:12:42 INFO - PROCESS | 21018 | ------- Summary: start ------- 08:12:42 INFO - PROCESS | 21018 | Number of tests: 14 08:12:42 INFO - PROCESS | 21018 | 08:12:42 INFO - PROCESS | 21018 | [#0] ai-astar Cycles:10 Average:115.00 Median:116.00 stddev:2.91 (2.5%) stddev-sans-first:0.78 08:12:42 INFO - PROCESS | 21018 | Values: 107.0 115.0 115.0 115.0 116.0 117.0 116.0 117.0 116.0 116.0 08:12:42 INFO - PROCESS | 21018 | 08:12:42 INFO - PROCESS | 21018 | [#1] audio-beat-detection Cycles:10 Average:135.00 Median:127.50 stddev:14.90 (11.7%) stddev-sans-first:14.92 08:12:42 INFO - PROCESS | 21018 | Values: 149.0 125.0 125.0 163.0 127.0 125.0 125.0 128.0 156.0 127.0 08:12:42 INFO - PROCESS | 21018 | 08:12:42 INFO - PROCESS | 21018 | [#2] audio-dft Cycles:10 Average:162.30 Median:162.50 stddev:0.67 (0.4%) stddev-sans-first:0.71 08:12:42 INFO - PROCESS | 21018 | Values: 162.0 162.0 162.0 162.0 163.0 163.0 162.0 163.0 161.0 163.0 08:12:42 INFO - PROCESS | 21018 | 08:12:42 INFO - PROCESS | 21018 | [#3] audio-fft Cycles:10 Average:80.10 Median:76.50 stddev:8.44 (11.0%) stddev-sans-first:8.75 08:12:42 INFO - PROCESS | 21018 | Values: 75.0 76.0 76.0 100.0 78.0 77.0 76.0 75.0 91.0 77.0 08:12:42 INFO - PROCESS | 21018 | 08:12:42 INFO - PROCESS | 21018 | [#4] audio-oscillator Cycles:10 Average:90.90 Median:84.00 stddev:10.24 (12.2%) stddev-sans-first:10.55 08:12:42 INFO - PROCESS | 21018 | Values: 84.0 84.0 107.0 86.0 85.0 84.0 106.0 85.0 84.0 104.0 08:12:42 INFO - PROCESS | 21018 | 08:12:42 INFO - PROCESS | 21018 | [#5] imaging-gaussian-blur Cycles:10 Average:127.20 Median:127.00 stddev:0.42 (0.3%) stddev-sans-first:0.44 08:12:42 INFO - PROCESS | 21018 | Values: 127.0 128.0 127.0 128.0 127.0 127.0 127.0 127.0 127.0 127.0 08:12:42 INFO - PROCESS | 21018 | 08:12:42 INFO - PROCESS | 21018 | [#6] imaging-darkroom Cycles:10 Average:146.50 Median:147.00 stddev:0.53 (0.4%) stddev-sans-first:0.53 08:12:42 INFO - PROCESS | 21018 | Values: 147.0 146.0 147.0 147.0 146.0 147.0 146.0 146.0 147.0 146.0 08:12:42 INFO - PROCESS | 21018 | 08:12:42 INFO - PROCESS | 21018 | [#7] imaging-desaturate Cycles:10 Average:95.30 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 08:12:42 INFO - PROCESS | 21018 | Values: 95.0 95.0 95.0 96.0 95.0 95.0 95.0 96.0 95.0 96.0 08:12:42 INFO - PROCESS | 21018 | 08:12:42 INFO - PROCESS | 21018 | [#8] json-parse-financial Cycles:10 Average:65.20 Median:65.00 stddev:1.14 (1.7%) stddev-sans-first:1.20 08:12:42 INFO - PROCESS | 21018 | Values: 65.0 64.0 64.0 64.0 65.0 65.0 65.0 66.0 67.0 67.0 08:12:42 INFO - PROCESS | 21018 | 08:12:42 INFO - PROCESS | 21018 | [#9] json-stringify-tinderbox Cycles:10 Average:66.40 Median:66.50 stddev:0.52 (0.8%) stddev-sans-first:0.53 08:12:42 INFO - PROCESS | 21018 | Values: 66.0 67.0 66.0 66.0 66.0 66.0 66.0 67.0 67.0 67.0 08:12:42 INFO - PROCESS | 21018 | 08:12:42 INFO - PROCESS | 21018 | [#10] stanford-crypto-aes Cycles:10 Average:86.60 Median:85.00 stddev:3.50 (4.1%) stddev-sans-first:2.49 08:12:42 INFO - PROCESS | 21018 | Values: 94.0 89.0 91.0 85.0 85.0 85.0 85.0 84.0 84.0 84.0 08:12:42 INFO - PROCESS | 21018 | 08:12:42 INFO - PROCESS | 21018 | [#11] stanford-crypto-ccm Cycles:10 Average:144.50 Median:143.50 stddev:15.43 (10.8%) stddev-sans-first:16.36 08:12:42 INFO - PROCESS | 21018 | Values: 146.0 137.0 139.0 134.0 143.0 147.0 137.0 132.0 186.0 144.0 08:12:42 INFO - PROCESS | 21018 | 08:12:42 INFO - PROCESS | 21018 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:178.00 Median:177.00 stddev:3.13 (1.8%) stddev-sans-first:2.05 08:12:42 INFO - PROCESS | 21018 | Values: 185.0 182.0 176.0 176.0 179.0 176.0 177.0 176.0 177.0 176.0 08:12:42 INFO - PROCESS | 21018 | 08:12:42 INFO - PROCESS | 21018 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.40 Median:67.00 stddev:1.26 (1.9%) stddev-sans-first:1.20 08:12:42 INFO - PROCESS | 21018 | Values: 69.0 70.0 67.0 66.0 68.0 67.0 67.0 67.0 67.0 66.0 08:12:42 INFO - PROCESS | 21018 | -------- Summary: end -------- 08:12:42 INFO - PROCESS | 21018 | 08:12:43 INFO - TEST-INFO | 21018: exit 0 08:12:43 INFO - TEST-OK | kraken | took 63561ms 08:12:43 INFO - SUITE-END | took 1185s 08:12:43 INFO - Completed test suite (00:19:46) 08:12:43 INFO - javascript benchmark 08:12:43 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2591.688997320322, 2615.843049417036, 2634.157069737684, 2643.6166755820404, 2652.920412387628, 4711.293306004092, 4730.739809310487, 4735.857924262274, 4759.0, 4786.3059856320815, 4684.601808846252, 4737.218358970775, 4758.547937945898, 4786.736729479877, 4788.114198873202, 4313.2451820931365, 4345.543717909622, 4358.9234283444175, 4374.234508960935, 4437.090396468732, 4028.186317350767, 4476.932846007313, 4477.552244775529, 4494.9325760113625, 4537.795799189044, 4298.441202643637, 4337.175936572041, 4451.933221001687, 4499.865004049884, 4535.954640453603, 4322.459692538433, 4357.302831546937, 4374.4531933508315, 4381.452318460192, 4417.536158703322, 4333.263345231287, 4334.848280310205, 4340.414044104069, 4372.366006929009, 4413.249747542925, 4729.0, 4742.956549559091, 4763.118823017753, 4774.546418090275, 4794.544518270759, 4965.106280869442, 5004.624653151028, 5009.899802003976, 5079.492050794891, 5081.872953176163, 5094.566961808251, 5098.643094983353, 5104.081265372231, 5104.719240441774, 5106.693598384109, 4767.927216376357, 4770.427548694144, 4775.092732380871, 4776.14029474691, 4778.307145463958, 5154.458781827854, 5205.323307969939, 5208.062548741187, 5212.635115541952, 5214.817481388133, 4962.305277261186, 4992.151334273182, 5003.399592048978, 5004.724740139254, 5008.724520151354, 5081.491850814963, 5084.313617661643, 5094.77073531692, 5097.541221290102, 5116.283720279164, 4968.826091086867, 4969.105560999054, 4976.2038073908, 5036.06832735945, 5053.84838454847, 5012.548870601663, 5018.996200759862, 5021.748912554358, 5022.824201152942, 5062.088824011713, 4178.6865985051245, 4209.7474151551005, 4213.6207741302815, 4272.16692744918, 4311.051568654891, 3714.3499887519574, 3767.8869633911027, 3779.130799916035, 3823.4073718573663, 3872.9806350968065, 4475.507694153641, 4475.753833539122, 4488.461384633865, 4498.370228168059, 4515.661325400608, 5004.349434573555, 5144.408393034774, 5144.768485418165, 5146.30524879144, 5171.0, 4367.235733746582, 4375.409319741858, 4389.934150987738, 4401.2517871961845, 4415.425994620743, 4602.148602508547, 4603.9079218415445, 4614.653900957374, 4645.349651048856, 4658.091672123971, 4820.542048505386, 4824.95175048245, 4833.661643685049, 4835.178019736723, 4878.317035615088, 4991.07665081961, 5061.620378471555, 5064.265681476239, 5075.416327122281, 5122.282880396822, 4788.856334309976, 4811.663183577116, 4822.517748225149, 4836.492168322276, 4851.95148048515, 5035.471275516018, 5041.621878359065, 5046.2153384961275, 5061.772220250024, 5091.745412729423, 5031.7735701894235, 5041.8487445376695, 5125.205593133071, 5127.128388173945, 5174.508421699932, 4929.507049295042, 4946.109700253989, 4968.55283024515, 4983.227599722049, 5043.344365232496, 4962.578180854668, 4978.751062446936, 4979.153543897619, 4985.6011519077665, 5035.59715222774, 4783.306420569068, 4861.927071094007, 4918.5573298403315, 4922.655414120977, 4924.138275801792, 4918.581920536794, 4941.604671626189, 4947.950520494893, 4986.401631804207, 5053.494650534917, 4860.173770459101, 4867.7322747249245, 4868.2940973557925, 4869.561739443467, 4911.238757992517], "value": 4672.885670591579, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [511.1974200505206, 519.5479932458761, 529.6054439442615, 535.4458135829416, 543.1553933633202, 29202.853985730068, 38074.619253807454, 39454.60545394545, 39510.20979580407, 39638.60361396385, 41104.38343424851, 41505.792471037676, 41623.791881040626, 41626.7918660407, 41680.374794378105, 7579.999999999986, 7757.5733334666575, 7762.8447431051345, 7767.6116194190345, 7771.0, 7402.407807375397, 7605.125410577799, 9583.185429238523, 9833.4099954003, 9838.803223935516, 9916.752081197992, 9971.252156088287, 9982.35114717541, 9995.400275983427, 9996.0, 9829.115379615832, 9884.209263258923, 9889.703308900744, 9895.901040989569, 9897.109260166548, 9671.371360861593, 9693.418394896258, 9718.222542196607, 9722.270829687732, 9743.317967742261, 36896.077598059994, 37310.62689373099, 37328.066798329986, 37346.199035072204, 37370.0, 2414.1550457339968, 2618.947621047568, 2674.0941543288955, 2680.209338245222, 2879.827210367385, 28049.719502804914, 28592.14223573296, 28687.85656071727, 28934.85532572345, 29979.0, 3065.5401689746495, 3105.208171916147, 3156.9842150789327, 3183.2360233544014, 3186.9840650796596, 183751.0, 183787.0, 184180.0, 184723.0, 184729.0763546187, 53049.0, 53257.2011419829, 53432.198517022276, 53435.46564534305, 53540.73229633905, 55778.0, 56002.719986400625, 56008.43991560032, 56021.43978560162, 56024.719876400355, 13111.475540978254, 13116.344182790823, 13127.474901004043, 13137.540186093442, 13283.667908302177, 9962.053604907522, 10284.177265818716, 10711.571537138583, 10806.891931080745, 10808.56765729362, 16359.91820040916, 16609.0, 16683.916580417263, 17156.31374745021, 17264.827351726322, 6331.873362532723, 6360.7137678804565, 6404.5516813823515, 6494.383033611799, 6494.902576461357, 743.353282644103, 863.8833757442791, 936.4802534593366, 974.0794948773341, 1007.8941711120227, 24386.390340241636, 28686.569701454497, 28786.424271514454, 29307.0, 29318.0, 888.4846788862444, 927.6057675487891, 941.764558860285, 951.6241084771476, 1020.6325722739808, 12584.622461326175, 13451.932740336235, 13506.797398039038, 13660.24868632215, 13718.93140534311, 340261.0, 341825.0, 343060.3950348215, 343794.0, 344738.0, 42833.57166428296, 42850.0, 42881.57118428776, 42986.78506607509, 42995.57004429916, 1013.8935411781804, 1101.8787933327487, 1102.8566286382718, 1179.6991767099335, 1193.3376975778353, 24067.157649482186, 25861.612075819256, 26780.330491737863, 28226.576601350585, 29111.52552033016, 41622.79188604037, 42283.15433691248, 42284.57715422929, 42327.57672423359, 42370.78814605907, 329274.0, 333972.27263409935, 334360.0, 334545.0, 334750.0, 22474.77525224727, 23130.76869231286, 23403.0, 23437.648435273142, 24698.259052228463, 35851.82074089613, 36146.819265903505, 36439.453408198366, 36458.63541364553, 36952.81523592365, 31115.377692446476, 31592.0, 31668.208294792814, 32053.0, 32356.0, 30534.694653054074, 31266.53100203544, 31883.68116318807, 32580.674193257764, 32598.348033038732, 28857.0, 29147.854260729408, 29311.26721831886, 29982.850085749433, 30730.84634576813, 27685.446291073662, 28991.130266092052, 29425.70574294315, 29524.852375737984, 29632.0, 22731.0, 22758.431039224153, 22866.885665571564, 23040.539189216455, 23160.0], "value": 16814.281370010158, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [347.88172021512713, 352.2655263775031, 371.18339652763916, 375.91353988582614, 386.29308365690787, 12598.559050433236, 12676.936615316921, 13910.51313204038, 14649.414023439076, 16742.74885876713, 16314.7552786708, 16533.33866645333, 17035.57411064724, 17167.227474763666, 17207.311707531717, 4899.583535399486, 4936.80252789888, 5069.569086627641, 5101.923471147936, 5108.412532558747, 4980.6762560433635, 5066.214736715797, 5070.239464080399, 5072.137736584789, 5073.0361231366105, 4893.730844803535, 5039.949600503985, 5041.470645582216, 5106.31064806252, 5178.171492561191, 5179.326687530634, 5215.973920130375, 5217.191335343028, 5219.817306394258, 5223.0, 4986.152354099811, 5008.0, 5308.124159513675, 5344.198370244494, 5357.651752636066, 11081.612143574936, 11199.776004479947, 11238.438078096062, 11240.550377984871, 11252.0, 1719.6732620802009, 1721.535185499908, 1804.4225847728733, 2067.865588736742, 2152.0, 8514.872276915852, 8559.0, 8592.570371481463, 8790.692325768632, 8994.550272486415, 1509.2001239343165, 1511.214168632316, 1514.5380658899032, 1545.0806769971848, 1575.4643421236835, 63289.19094498007, 66960.66519667419, 67097.0, 67422.66288668575, 68532.9999999995, 9834.606615735434, 10060.547275372626, 10079.899201007896, 10100.949495252624, 10166.949165254126, 13696.315184240948, 13800.171989680652, 13806.757391834783, 13821.930890345484, 13824.792628110587, 7164.319389657974, 7182.7845164644095, 7297.16082650502, 7360.116785985716, 7550.395968322521, 6693.832654183587, 6764.154480689914, 6773.45812335012, 6925.549839260432, 6952.965235173893, 3825.9617403826164, 4109.794510274474, 4283.100548884707, 4331.155424692155, 4365.585269399402, 1721.0, 1789.4363275568155, 1797.0957996296556, 1839.8160183981763, 1892.129620374643, 795.8487887301394, 849.2865992566151, 862.344618090256, 863.9524826134623, 866.8136350684633, 16100.75848862268, 16186.271617776993, 16729.665406692042, 17182.828171718375, 17218.483445496655, 815.7104227999093, 820.5445977482555, 822.8971378577678, 824.5011767880346, 844.5439462690078, 9064.180065592804, 9127.726168214964, 9442.622295108118, 9487.241020718326, 9544.42733435996, 363943.0, 368195.0, 370179.0, 371590.0, 376489.0, 59092.0, 70447.64776176086, 72326.27673723301, 72438.63780681168, 73267.0, 887.8002449448823, 893.65147592439, 937.2408349237139, 942.6417961174847, 946.3328353510758, 15236.466723664618, 15359.769603455734, 15923.601909952344, 16231.918840405722, 16261.398176291737, 32572.0, 35169.0, 35524.46713299251, 36096.4585531212, 36259.637403625624, 38749.80625096969, 46816.7659161702, 48849.511504885915, 49235.75382123067, 50493.49506504888, 7095.467839911924, 7201.963990180225, 7493.363064139391, 7668.654910528928, 7745.22547745221, 11194.272372295774, 12080.939595301967, 12216.938915305367, 12299.323537205539, 12301.863024928944, 5253.605979551472, 6295.779647712308, 8441.480533504027, 10229.539670714687, 10564.049235568837, 10025.298229123891, 10259.948700256451, 10265.538050787583, 10413.531391087268, 10526.842097368393, 10137.594496220363, 10362.326448781107, 10399.0, 10998.12015038779, 11074.169437292074, 6695.296993815774, 6797.422219111431, 6840.657967101525, 7149.463790215651, 7734.342580880688, 7895.7236496722335, 9429.62281508732, 9630.374025688308, 9884.703458896245, 9959.502024898871], "value": 7754.5154458932575, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1286.6333095067905, 1299.2659147581617, 1366.6788304748388, 1622.98125486595, 1662.6924019056469, 6942.722291108356, 7369.852602947944, 7370.1892791792925, 7372.668229929653, 7374.483786134972, 6200.689965501718, 6405.359464053592, 6414.422701956823, 6537.8365540861505, 7212.963935180317, 1881.2004897918398, 2007.8594498385119, 2079.833613310939, 2081.677340012293, 2094.1623350659784, 2125.288028510443, 2137.8610390324657, 2142.5286436983915, 2145.731783527059, 2148.34475484977, 2051.05651400356, 2121.3317804891485, 2124.60694771467, 2128.6700561413004, 2128.723265975421, 2049.4568939231117, 2051.825594824442, 2074.481379655094, 2084.8853313067775, 2087.6033553624916, 2084.697718830776, 2106.420734298065, 2112.0601332407086, 2128.5317230209484, 2141.7536983246814, 7298.379637730799, 7356.043714317105, 7362.0, 7376.077990251219, 7378.667959941762, 4412.382266482695, 4588.220002599566, 4590.747508887043, 4593.379893714339, 4597.586217240431, 2897.27568107973, 2935.823850568973, 2950.022048367666, 2967.4361871244396, 2979.8063125896747, 887.2946007923651, 888.8044630181349, 910.7677542226756, 930.2883294279882, 931.3329140837892, 9608.183304419133, 9689.370190937567, 9692.660756873476, 9696.90303096967, 9708.708738737849, 3984.30274701926, 4030.314846476106, 4130.153318569686, 4168.333066709312, 4171.353440216772, 8469.872951905727, 8480.787980300418, 8489.405741598152, 8491.065982741893, 8510.0, 4469.08383781324, 4470.262406702923, 4471.19518486669, 4477.485089214717, 4481.574250446202, 4030.979845100756, 4064.634182923551, 4079.204555111784, 4084.7753373564146, 4158.168366326747, 2982.209714425679, 3031.4998025326017, 3177.3327601204005, 3232.7413806895393, 3260.510923361467, 966.9951650241704, 969.3408482232009, 976.5654283843621, 978.6379039755336, 982.1847866270978, 802.1577343788998, 821.1747194070041, 827.8344331133798, 830.2735106781566, 836.0302049622409, 4700.55344742249, 4817.735024573612, 4915.606751459874, 4923.975380123076, 4937.21004639256, 774.705611867498, 783.8197214640551, 793.8332950080433, 805.7743831727125, 863.5380071661631, 3083.8435586655005, 3153.44814657434, 3248.5776849009476, 3248.797944760454, 3248.8375581220844, 8501.787455313666, 8618.138186181457, 8640.179182977607, 8722.694705685271, 8745.12548745133, 8477.618507167192, 8683.652653893774, 8686.826263474695, 8687.087855775053, 8689.391742578086, 6541.0, 6665.500087493458, 6668.199816022108, 6669.066330713704, 6669.899951500732, 4719.10337035962, 4734.597559207506, 4770.260609605517, 4806.807727690853, 4808.134535783593, 7889.0138732658415, 7920.168382320004, 8004.679812807422, 8060.03279606451, 8064.3548516119745, 7901.209879012283, 8106.797330066795, 8203.876941845996, 8216.835663286582, 8232.259096681328, 5912.172295878494, 6108.664023478751, 6156.630602163885, 6268.686565671607, 6273.96863015682, 6064.818055458343, 6243.7814676486105, 6606.53754237218, 6838.14523184602, 6868.759593414206, 6263.404976527223, 6322.0516922462, 6359.49124070064, 6370.649614271259, 6390.329015453496, 6204.441600255998, 6300.432961033529, 6347.238331400262, 6452.645104519296, 6489.1888513935755, 5441.26542916717, 5488.588355873246, 5542.0855558832345, 5560.416156303691, 5570.61005729595, 4062.309407400808, 4084.162746636992, 4147.6267135957905, 4163.12574359388, 4240.957590424056, 4170.291050521358, 4218.76796776168, 4221.451211342506, 4266.0827921996915, 4346.130773845307], "value": 3847.487483745325, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [347.13563227563367, 357.84433771309483, 361.2305788670132, 361.7034032093683, 363.33147009502494, 4825.2279635258365, 4831.37192165018, 4831.589314908233, 4847.878803029926, 4855.688963979728, 4774.355462012627, 4775.450823155338, 4780.928286075712, 4782.33047373368, 4794.808207671689, 3445.0870519312402, 3592.6227746086674, 3684.852605895761, 3837.4458344370573, 3908.374660054392, 3799.563050249229, 3812.961870381302, 3816.8282427290837, 3833.9808300958457, 3849.2493963677093, 3832.463455116272, 3834.1404418856832, 3834.233153369323, 3839.5584507781546, 3841.7310788244836, 3654.2143439160577, 3712.257548490286, 3757.2861156380336, 3833.731638785286, 3863.594322596115, 3467.323871844991, 3474.9305013899834, 3475.7566970312087, 3481.781376518198, 3487.808170550619, 4584.7992963377765, 4671.5094915034115, 4675.368825208621, 4691.929621055653, 4696.256780651566, 4271.444712187395, 4336.891577710551, 4381.145676593065, 4389.626881715058, 4394.62645675118, 4964.329815474937, 4976.527229913152, 4980.377452818398, 4994.525520075587, 5006.524380183877, 3775.452559378875, 3797.468354430382, 3797.6012518685416, 3802.2205447883116, 3802.90492737681, 5061.924071138899, 5218.9739051304505, 5230.189320655304, 5240.213967904807, 5247.081760691902, 3863.671587915059, 3868.9806550967064, 3886.8639597613947, 3890.0, 3898.317794385971, 3920.176762879827, 3927.332353499855, 3931.803409829497, 3943.6253555912717, 3953.723239373274, 4364.585364390377, 4368.60682538573, 4377.8248870044845, 4380.890477738082, 4381.590125431, 4773.95226047742, 4938.950610493921, 4960.379952505937, 4990.50094990498, 4990.600751939836, 1331.973360532784, 1333.3133436280323, 1339.8794108530278, 1339.9330033498286, 1344.675494637781, 899.6896070855545, 917.7200953709049, 971.2569884038716, 976.8534719792088, 979.4661909259328, 4359.215341238544, 4414.938256803748, 4446.732166595059, 4447.488538818077, 4474.418325617649, 5147.356580427447, 5148.253503242009, 5148.9742551287, 5152.007194424351, 5156.123459011977, 4297.109271920345, 4439.600435960714, 4445.644348452116, 4451.198784218807, 4460.264056430718, 2643.1013455424863, 2646.603009548583, 2680.8793604287853, 2692.5018871508832, 2699.7705195058247, 4733.355316406417, 4747.2879068140055, 4755.595774359149, 4756.619470442356, 4772.618190544748, 4961.2558116282125, 5113.923291150636, 5138.563222126161, 5174.586033117342, 5192.209686928488, 4674.392328997209, 4676.976615116971, 4687.523430119501, 4699.577038066522, 4701.64737644678, 5002.874928126826, 5006.849794506171, 5007.674501157413, 5016.122178618654, 5017.67385119966, 4660.6271498279375, 4910.631702622389, 4919.827806026772, 4932.235503496964, 4948.480409556944, 4673.845154520753, 4694.859154225379, 4697.718136911796, 4700.083483720641, 4713.175194341045, 3795.7342985991822, 3819.2049736623744, 3819.5034645495907, 3822.7706337619834, 3825.0819803247578, 4761.952380476151, 4997.400311962588, 5006.474320196473, 5007.949920500749, 5012.669108864396, 4832.782524786463, 4845.0, 4845.200541910686, 4847.830325938575, 4856.320115183807, 4822.697871574585, 4834.806607735652, 4840.419149701917, 4863.392075990501, 4884.242942343943, 4654.091193004372, 4763.476017637988, 4779.091972525209, 4811.27830825379, 4826.203676393497, 4775.785089671042, 4844.61243100544, 4860.222364421676, 4919.942212424346, 4920.458749537476, 4886.951130488792, 4887.633427492881, 4894.2658601210105, 4906.681065730716, 4910.263460480956], "value": 3817.062415902572, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [569.3566270114769, 573.2175580332847, 586.0915580849683, 587.6797145555672, 680.3978479046045, 12267.0, 13298.069135160518, 13393.799093013587, 13419.530316438926, 13428.798568021475, 12598.0, 13599.18404895707, 13646.317684115804, 13663.726725465509, 13744.518941837037, 618.8236352639499, 647.132841991731, 774.3418094619557, 785.2893131715806, 785.3834739729314, 749.2357795049047, 774.2025713515088, 778.7546922719325, 785.5836406704456, 785.787837283933, 829.713748756678, 830.5182993863575, 831.476170012891, 831.9417640765149, 831.9542425166613, 642.6015870160484, 671.6205343980636, 819.1317203764029, 819.5983967855708, 819.8811172380001, 498.4367664539084, 498.6210480035163, 498.84286449768206, 572.9054705973509, 602.7167231401235, 13420.463181472729, 13493.595192144252, 13505.86494135056, 13525.120867143603, 13533.0, 11744.765104697859, 11777.293362398283, 12133.0, 12134.0, 12713.93643031779, 9954.701358959242, 10067.748306292327, 10079.5464204111, 10134.087932086148, 10286.485675716185, 3960.4257382679352, 3967.841286348543, 3987.760734355948, 4020.0954785173103, 4028.8992775180564, 57609.711951439975, 60422.69788651029, 60514.69742651258, 61467.385326146614, 62361.0, 9452.338336316392, 9532.52337383128, 9577.664781732607, 9682.70951871445, 9861.556229969668, 18999.24003039863, 19359.612807743768, 19419.51451213731, 19612.803871961383, 19779.307724229584, 5983.491403230687, 6000.879982400327, 6141.355157708376, 6441.935580644227, 6496.512761542903, 3457.049311439324, 3567.4827150063097, 3586.7847929124337, 3601.747877648592, 3697.1681371691157, 2693.3805224798407, 2822.618946442245, 2848.074375827864, 2883.0, 2889.8729495496773, 531.7952588253551, 542.2625229687621, 543.0577947261498, 543.741722681731, 548.2707998362168, 504.9747512624354, 540.4568408749182, 581.2763109928167, 582.3303201318517, 583.4136692623971, 7865.842683146305, 7887.0, 7981.561014144162, 8105.067917189481, 8230.079229346242, 550.3780727777665, 572.7382556341595, 575.3529111767983, 582.4495851420452, 592.2330581896455, 3899.259140763246, 3915.6084391561185, 3923.136909879822, 3936.9212615747524, 4109.938350924738, 67451.0, 68384.65807671029, 68793.0, 69021.0, 70298.0, 53125.7343713279, 59175.0, 59176.0, 59937.40062599405, 61839.381606183364, 40871.38692919609, 43780.0, 46047.309290360674, 46947.53052469432, 47831.5216847834, 7621.771346859603, 8337.166283371615, 8345.248927596545, 8502.14978502145, 8502.872456913028, 33627.0, 34637.82681086579, 35015.299694006484, 36163.095922602144, 36951.26097478089, 41918.0, 46434.767826160656, 47311.29033064576, 48094.27858582194, 48397.0, 10949.233553651167, 11010.779784404107, 11070.169737269576, 11086.223964322742, 11575.942120289345, 11998.700032499257, 12087.939560302142, 12092.75814483723, 12133.69665758363, 12628.052896033018, 11392.829107563559, 11615.535378584762, 11683.474243658886, 11863.34751588671, 11967.521299147937, 11203.551857925917, 11265.774684506428, 11546.48040838181, 11662.53349865996, 11777.646670599896, 10731.731706707395, 10924.50839712199, 11042.116630669689, 11058.22592418523, 11388.177891537314, 9427.434353938786, 9439.81120377575, 9556.665516706882, 9607.51962401863, 9863.753406164904, 9118.772030699285, 9290.72127836166, 9483.478408687588, 9871.16095131893, 9871.841010876451], "value": 6139.031632338383, "unit": "score"}], "name": "dromaeo_css", "value": 6165.651897846369, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 115.0, 115.0, 115.0, 116.0, 117.0, 116.0, 117.0, 116.0, 116.0], "value": 115.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 125.0, 125.0, 163.0, 127.0, 125.0, 125.0, 128.0, 156.0, 127.0], "value": 135.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 162.0, 162.0, 162.0, 163.0, 163.0, 162.0, 163.0, 161.0, 163.0], "value": 162.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 76.0, 100.0, 78.0, 77.0, 76.0, 75.0, 91.0, 77.0], "value": 80.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 107.0, 86.0, 85.0, 84.0, 106.0, 85.0, 84.0, 104.0], "value": 90.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 128.0, 127.0, 128.0, 127.0, 127.0, 127.0, 127.0, 127.0, 127.0], "value": 127.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 147.0, 147.0, 146.0, 147.0, 146.0, 146.0, 147.0, 146.0], "value": 146.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 96.0, 95.0, 95.0, 95.0, 96.0, 95.0, 96.0], "value": 95.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 64.0, 64.0, 64.0, 65.0, 65.0, 65.0, 66.0, 67.0, 67.0], "value": 65.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 66.0, 66.0, 66.0, 66.0, 66.0, 67.0, 67.0, 67.0], "value": 66.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 89.0, 91.0, 85.0, 85.0, 85.0, 85.0, 84.0, 84.0, 84.0], "value": 86.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 137.0, 139.0, 134.0, 143.0, 147.0, 137.0, 132.0, 186.0, 144.0], "value": 144.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 182.0, 176.0, 176.0, 179.0, 176.0, 177.0, 176.0, 177.0, 176.0], "value": 178.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 70.0, 67.0, 66.0, 68.0, 67.0, 67.0, 67.0, 67.0, 66.0], "value": 67.4, "unit": "score"}], "name": "kraken", "value": 1560.4, "alertThreshold": 2.0}]} 08:12:43 INFO - Return code: 0 08:12:43 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:12:43 INFO - # TBPL SUCCESS # 08:12:43 INFO - Running post-action listener: _resource_record_post_action 08:12:43 INFO - [mozharness: 2016-12-16 16:12:43.963790Z] Finished run-tests step (success) 08:12:43 INFO - Running post-run listener: _resource_record_post_run 08:12:44 INFO - Total resource usage - Wall time: 1202s; CPU: 13.0%; Read bytes: 4096; Write bytes: 382009344; Read time: 12; Write time: 239048 08:12:44 INFO - TinderboxPrint: CPU usage
13.1% 08:12:44 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 08:12:44 INFO - TinderboxPrint: I/O write bytes / time
382,009,344 / 239,048 08:12:44 INFO - TinderboxPrint: CPU idle
8,332.2 (86.8%) 08:12:44 INFO - TinderboxPrint: CPU user
1,216.9 (12.7%) 08:12:44 INFO - TinderboxPrint: Swap in / out
0 / 0 08:12:44 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 98942976; Read time: 0; Write time: 61640 08:12:44 INFO - run-tests - Wall time: 1187s; CPU: 13.0%; Read bytes: 0; Write bytes: 282169344; Read time: 0; Write time: 177388 08:12:44 INFO - Running post-run listener: _upload_blobber_files 08:12:44 INFO - Blob upload gear active. 08:12:44 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:12:44 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 08:12:44 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'] 08:12:44 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 08:12:45 INFO - (blobuploader) - INFO - Open directory for files ... 08:12:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:12:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:12:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:12:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:12:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:12:45 INFO - (blobuploader) - INFO - Done attempting. 08:12:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:12:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:12:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:12:49 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:12:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:12:49 INFO - (blobuploader) - INFO - Done attempting. 08:12:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:12:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:12:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:12:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:12:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:12:49 INFO - (blobuploader) - INFO - Done attempting. 08:12:49 INFO - (blobuploader) - INFO - Iteration through files over. 08:12:49 INFO - Return code: 0 08:12:49 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:12:49 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:12:49 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1d27be7647c11d84077f9a02915750553d1e4a05ad13429b0a09fc2aba437aef1bd4dfde42c53332a2bcdc9292376e08d29bfae5a1b9cdc6530f92a1ca089539", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9f702de0dfcb59c45c22c542a7d859cc1321c5b51cecc9cd3a55f045c1d922bb26c2999e5dcea63ff2a9ae50aa63d86799f6887cfcc486ce6251b91e644cb562", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/53556e98986705e6a2ac8fe51a515615c3e7760296f9b9c350b44e4451f7ed88ea6ceec8ab0b28d2d17a384f900434871012e79145d7f66ad46f0db5219eca2e"} 08:12:49 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:12:49 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:12:49 INFO - Contents: 08:12:49 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1d27be7647c11d84077f9a02915750553d1e4a05ad13429b0a09fc2aba437aef1bd4dfde42c53332a2bcdc9292376e08d29bfae5a1b9cdc6530f92a1ca089539", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9f702de0dfcb59c45c22c542a7d859cc1321c5b51cecc9cd3a55f045c1d922bb26c2999e5dcea63ff2a9ae50aa63d86799f6887cfcc486ce6251b91e644cb562", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/53556e98986705e6a2ac8fe51a515615c3e7760296f9b9c350b44e4451f7ed88ea6ceec8ab0b28d2d17a384f900434871012e79145d7f66ad46f0db5219eca2e"} 08:12:49 INFO - Running post-run listener: copy_logs_to_upload_dir 08:12:49 INFO - Copying logs to upload dir... 08:12:49 INFO - mkdir: /builds/slave/test/build/upload/logs 08:12:49 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1246.415475 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 46 secs) (at 2016-12-16 08:12:49.732634) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-16 08:12:49.735903) ========= 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=a0d2d2de893abaa8702a9954000001a7-1481896745.736146-1060331009 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/1d27be7647c11d84077f9a02915750553d1e4a05ad13429b0a09fc2aba437aef1bd4dfde42c53332a2bcdc9292376e08d29bfae5a1b9cdc6530f92a1ca089539", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9f702de0dfcb59c45c22c542a7d859cc1321c5b51cecc9cd3a55f045c1d922bb26c2999e5dcea63ff2a9ae50aa63d86799f6887cfcc486ce6251b91e644cb562", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/53556e98986705e6a2ac8fe51a515615c3e7760296f9b9c350b44e4451f7ed88ea6ceec8ab0b28d2d17a384f900434871012e79145d7f66ad46f0db5219eca2e"} build_url:https://queue.taskcluster.net/v1/task/eKuB0yAhTdyySFIFm7Uc5g/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011443 build_url: 'https://queue.taskcluster.net/v1/task/eKuB0yAhTdyySFIFm7Uc5g/artifacts/public/build/firefox-53.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/1d27be7647c11d84077f9a02915750553d1e4a05ad13429b0a09fc2aba437aef1bd4dfde42c53332a2bcdc9292376e08d29bfae5a1b9cdc6530f92a1ca089539", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9f702de0dfcb59c45c22c542a7d859cc1321c5b51cecc9cd3a55f045c1d922bb26c2999e5dcea63ff2a9ae50aa63d86799f6887cfcc486ce6251b91e644cb562", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/53556e98986705e6a2ac8fe51a515615c3e7760296f9b9c350b44e4451f7ed88ea6ceec8ab0b28d2d17a384f900434871012e79145d7f66ad46f0db5219eca2e"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-16 08:12:49.760759) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-16 08:12:49.761058) ========= 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=a0d2d2de893abaa8702a9954000001a7-1481896745.736146-1060331009 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004533 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-16 08:12:49.844241) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-16 08:12:49.847576) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-16 08:12:49.847875) ========= ========= Total master_lag: 0.26 =========