builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-052 starttime: 1482265921.8 results: success (0) buildid: 20161220113554 builduid: 17971ee99bdc4bc3872870e1f548c489 revision: 0f3603e365f029bc9ea5f926d32f6f0f8aa4d998 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-20 12:32:01.803567) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-20 12:32:01.804134) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-20 12:32:01.804536) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-20 12:32:01.819525) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-20 12:32:01.819794) ========= 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=826874ccc97bd7bf541b4875000001d5-1482265011.769691-1153358795 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-12-20 12:32:01-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 41.7M=0s 2016-12-20 12:32:02 (41.7 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.121047 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-20 12:32:01.954591) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-20 12:32:01.954903) ========= 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=826874ccc97bd7bf541b4875000001d5-1482265011.769691-1153358795 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018342 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-20 12:32:02.005243) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-20 12:32:02.005605) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 0f3603e365f029bc9ea5f926d32f6f0f8aa4d998 --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 0f3603e365f029bc9ea5f926d32f6f0f8aa4d998 --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=826874ccc97bd7bf541b4875000001d5-1482265011.769691-1153358795 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-12-20 12:32:02,144 truncating revision to first 12 chars 2016-12-20 12:32:02,144 Setting DEBUG logging. 2016-12-20 12:32:02,144 attempt 1/10 2016-12-20 12:32:02,144 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/0f3603e365f0?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-20 12:32:02,536 unpacking tar archive at: mozilla-central-0f3603e365f0/testing/mozharness/ program finished with exit code 0 elapsedTime=0.733173 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-20 12:32:02.753395) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-20 12:32:02.753724) ========= script_repo_revision: 0f3603e365f029bc9ea5f926d32f6f0f8aa4d998 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-20 12:32:02.754133) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-20 12:32:02.754408) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-20 12:32:02.764163) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2016-12-20 12:32:02.764449) ========= /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=826874ccc97bd7bf541b4875000001d5-1482265011.769691-1153358795 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 12:32:02 INFO - MultiFileLogger online at 20161220 12:32:02 in /builds/slave/test 12:32:02 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 12:32:02 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:32:02 INFO - {'append_to_log': False, 12:32:02 INFO - 'base_work_dir': '/builds/slave/test', 12:32:02 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 12:32:02 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:32:02 INFO - 'branch': 'Firefox-Non-PGO', 12:32:02 INFO - 'buildbot_json_path': 'buildprops.json', 12:32:02 INFO - 'config_files': ('talos/linux_config.py',), 12:32:02 INFO - 'default_actions': ('clobber', 12:32:02 INFO - 'read-buildbot-config', 12:32:02 INFO - 'download-and-extract', 12:32:02 INFO - 'populate-webroot', 12:32:02 INFO - 'create-virtualenv', 12:32:02 INFO - 'install', 12:32:02 INFO - 'run-tests'), 12:32:02 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:32:02 INFO - 'download_minidump_stackwalk': True, 12:32:02 INFO - 'download_symbols': 'ondemand', 12:32:02 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:32:02 INFO - 'tooltool.py': '/tools/tooltool.py', 12:32:02 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:32:02 INFO - '/tools/misc-python/virtualenv.py')}, 12:32:02 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:32:02 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:32:02 INFO - 'installer_path': 'installer.exe', 12:32:02 INFO - 'log_level': 'info', 12:32:02 INFO - 'log_name': 'talos', 12:32:02 INFO - 'log_to_console': True, 12:32:02 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 12:32:02 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 12:32:02 INFO - 'opt_config_files': (), 12:32:02 INFO - 'pip_index': False, 12:32:02 INFO - 'sps_profile': False, 12:32:02 INFO - 'sps_profile_interval': 0, 12:32:02 INFO - 'suite': 'dromaeojs', 12:32:02 INFO - 'system_bits': '32', 12:32:02 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:32:02 INFO - 'title': 'talos-linux64-ix-052', 12:32:02 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:32:02 INFO - 'use_talos_json': True, 12:32:02 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:32:02 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:32:02 INFO - 'work_dir': 'build'} 12:32:02 INFO - [mozharness: 2016-12-20 20:32:02.992729Z] Running clobber step. 12:32:02 INFO - Running pre-action listener: _resource_record_pre_action 12:32:02 INFO - Running main action method: clobber 12:32:02 INFO - rmtree: /builds/slave/test/build 12:32:02 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:32:03 INFO - Running post-action listener: _resource_record_post_action 12:32:03 INFO - [mozharness: 2016-12-20 20:32:03.448800Z] Finished clobber step (success) 12:32:03 INFO - [mozharness: 2016-12-20 20:32:03.448901Z] Running read-buildbot-config step. 12:32:03 INFO - Running pre-action listener: _resource_record_pre_action 12:32:03 INFO - Running main action method: read_buildbot_config 12:32:03 INFO - Using buildbot properties: 12:32:03 INFO - { 12:32:03 INFO - "project": "", 12:32:03 INFO - "product": "firefox", 12:32:03 INFO - "script_repo_revision": "production", 12:32:03 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 12:32:03 INFO - "repository": "", 12:32:03 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 12:32:03 INFO - "buildid": "20161220113554", 12:32:03 INFO - "pgo_build": "False", 12:32:03 INFO - "basedir": "/builds/slave/test", 12:32:03 INFO - "buildnumber": 750, 12:32:03 INFO - "slavename": "talos-linux64-ix-052", 12:32:03 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 12:32:03 INFO - "platform": "ubuntu64_hw", 12:32:03 INFO - "scheduler": "tests-mozilla-central-linux64-talos", 12:32:03 INFO - "branch": "mozilla-central", 12:32:03 INFO - "repo_path": "mozilla-central", 12:32:03 INFO - "revision": "0f3603e365f029bc9ea5f926d32f6f0f8aa4d998", 12:32:03 INFO - "stage_platform": "linux64", 12:32:03 INFO - "builduid": "17971ee99bdc4bc3872870e1f548c489", 12:32:03 INFO - "slavebuilddir": "test" 12:32:03 INFO - } 12:32:03 INFO - Found installer url https://queue.taskcluster.net/v1/task/PlZqv_PrSZemwj-kudA5kQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2. 12:32:03 INFO - Running post-action listener: _resource_record_post_action 12:32:03 INFO - [mozharness: 2016-12-20 20:32:03.451355Z] Finished read-buildbot-config step (success) 12:32:03 INFO - [mozharness: 2016-12-20 20:32:03.451446Z] Running download-and-extract step. 12:32:03 INFO - Running pre-action listener: _resource_record_pre_action 12:32:03 INFO - Running main action method: download_and_extract 12:32:03 INFO - mkdir: /builds/slave/test/build/tests 12:32:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:32:03 INFO - trying https://queue.taskcluster.net/v1/task/PlZqv_PrSZemwj-kudA5kQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 12:32:03 INFO - Downloading https://queue.taskcluster.net/v1/task/PlZqv_PrSZemwj-kudA5kQ/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 12:32:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PlZqv_PrSZemwj-kudA5kQ/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 12:32:04 INFO - Downloaded 1361 bytes. 12:32:04 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 12:32:04 INFO - Using the following test package requirements: 12:32:04 INFO - {u'common': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 12:32:04 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 12:32:04 INFO - u'firefox-53.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 12:32:04 INFO - u'gtest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 12:32:04 INFO - u'firefox-53.0a1.en-US.linux-x86_64.gtest.tests.zip'], 12:32:04 INFO - u'jittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 12:32:04 INFO - u'jsshell-linux-x86_64.zip'], 12:32:04 INFO - u'mochitest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 12:32:04 INFO - u'firefox-53.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 12:32:04 INFO - u'mozbase': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 12:32:04 INFO - u'reftest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 12:32:04 INFO - u'firefox-53.0a1.en-US.linux-x86_64.reftest.tests.zip'], 12:32:04 INFO - u'talos': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 12:32:04 INFO - u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'], 12:32:04 INFO - u'web-platform': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 12:32:04 INFO - u'firefox-53.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 12:32:04 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 12:32:04 INFO - u'firefox-53.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 12:32:04 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 12:32:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PlZqv_PrSZemwj-kudA5kQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 12:32:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PlZqv_PrSZemwj-kudA5kQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 12:32:04 INFO - Fetch https://queue.taskcluster.net/v1/task/PlZqv_PrSZemwj-kudA5kQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 12:32:04 INFO - Expected file size: 20259643 12:32:04 INFO - Obtained file size: 20259643 12:32:08 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 12:32:08 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PlZqv_PrSZemwj-kudA5kQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 12:32:08 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PlZqv_PrSZemwj-kudA5kQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 12:32:08 INFO - Fetch https://queue.taskcluster.net/v1/task/PlZqv_PrSZemwj-kudA5kQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 12:32:09 INFO - Expected file size: 20259643 12:32:09 INFO - Obtained file size: 20259643 12:32:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PlZqv_PrSZemwj-kudA5kQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip 12:32:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PlZqv_PrSZemwj-kudA5kQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 12:32:14 INFO - Fetch https://queue.taskcluster.net/v1/task/PlZqv_PrSZemwj-kudA5kQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip into memory 12:32:14 INFO - Expected file size: 14409734 12:32:14 INFO - Obtained file size: 14409734 12:32: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')]} 12:32:15 INFO - trying https://queue.taskcluster.net/v1/task/PlZqv_PrSZemwj-kudA5kQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 12:32:15 INFO - Downloading https://queue.taskcluster.net/v1/task/PlZqv_PrSZemwj-kudA5kQ/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 12:32:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PlZqv_PrSZemwj-kudA5kQ/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 12:32:16 INFO - Downloaded 55646267 bytes. 12:32:16 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/PlZqv_PrSZemwj-kudA5kQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 12:32:16 INFO - mkdir: /builds/slave/test/properties 12:32:16 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:32:16 INFO - Writing to file /builds/slave/test/properties/build_url 12:32:16 INFO - Contents: 12:32:16 INFO - build_url:https://queue.taskcluster.net/v1/task/PlZqv_PrSZemwj-kudA5kQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 12:32:16 INFO - Running post-action listener: _resource_record_post_action 12:32:16 INFO - Running post-action listener: set_extra_try_arguments 12:32:16 INFO - [mozharness: 2016-12-20 20:32:16.908954Z] Finished download-and-extract step (success) 12:32:16 INFO - [mozharness: 2016-12-20 20:32:16.909256Z] Running populate-webroot step. 12:32:16 INFO - Running pre-action listener: _resource_record_pre_action 12:32:16 INFO - Running main action method: populate_webroot 12:32:16 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:32:16 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:32:16 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:32:16 INFO - '--tppagecycles', 12:32:16 INFO - '7'], 12:32:16 INFO - 'tests': ['tsvgm']}}, 12:32:16 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:32:16 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:32:16 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:32:16 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:32:16 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:32:16 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:32:16 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 12:32:16 INFO - 'talos_options': ['--disable-e10s'], 12:32:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:32:16 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 12:32:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:32:16 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 12:32:16 INFO - 'talos_options': ['--disable-e10s'], 12:32:16 INFO - 'tests': ['damp', 'tps']}, 12:32:16 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 12:32:16 INFO - 'tests': ['damp', 'tps']}, 12:32:16 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:32:16 INFO - 'tests': ['dromaeo_dom']}, 12:32:16 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:32:16 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:32:16 INFO - 'tests': ['basic_compositor_video']}, 12:32:16 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 12:32:16 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:32:16 INFO - 'tests': ['a11yr', 12:32:16 INFO - 'ts_paint', 12:32:16 INFO - 'tpaint', 12:32:16 INFO - 'sessionrestore', 12:32:16 INFO - 'sessionrestore_no_auto_restore', 12:32:16 INFO - 'tabpaint']}, 12:32:16 INFO - 'other-e10s': {'tests': ['a11yr', 12:32:16 INFO - 'ts_paint', 12:32:16 INFO - 'tpaint', 12:32:16 INFO - 'sessionrestore', 12:32:16 INFO - 'sessionrestore_no_auto_restore', 12:32:16 INFO - 'tabpaint']}, 12:32:16 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:32:16 INFO - 'ts_paint', 12:32:16 INFO - 'tpaint', 12:32:16 INFO - 'sessionrestore', 12:32:16 INFO - 'sessionrestore_no_auto_restore', 12:32:16 INFO - 'tabpaint']}, 12:32:16 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:32:16 INFO - 'ts_paint', 12:32:16 INFO - 'tpaint', 12:32:16 INFO - 'sessionrestore', 12:32:16 INFO - 'sessionrestore_no_auto_restore', 12:32:16 INFO - 'tabpaint']}, 12:32:16 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:32:16 INFO - 'tests': ['a11yr', 12:32:16 INFO - 'ts_paint', 12:32:16 INFO - 'tpaint', 12:32:16 INFO - 'sessionrestore', 12:32:16 INFO - 'sessionrestore_no_auto_restore', 12:32:16 INFO - 'tabpaint']}, 12:32:16 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:32:16 INFO - 'tests': ['a11yr', 12:32:16 INFO - 'ts_paint', 12:32:16 INFO - 'tpaint', 12:32:16 INFO - 'sessionrestore', 12:32:16 INFO - 'sessionrestore_no_auto_restore', 12:32:16 INFO - 'tabpaint']}, 12:32:16 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:32:16 INFO - 'tests': ['tsvgx', 12:32:16 INFO - 'tsvgr_opacity', 12:32:16 INFO - 'tart', 12:32:16 INFO - 'tscrollx', 12:32:16 INFO - 'cart', 12:32:16 INFO - 'tsvg_static']}, 12:32:16 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:32:16 INFO - 'tsvgr_opacity', 12:32:16 INFO - 'tart', 12:32:16 INFO - 'tscrollx', 12:32:16 INFO - 'cart', 12:32:16 INFO - 'tsvg_static']}, 12:32:16 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 12:32:16 INFO - 'talos_options': ['--disable-e10s'], 12:32:16 INFO - 'tests': ['tp5o']}, 12:32:16 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 12:32:16 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 12:32:16 INFO - 'talos_options': ['--disable-e10s', 12:32:16 INFO - '--xperf_path', 12:32:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:32:16 INFO - 'tests': ['tp5n']}, 12:32:16 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 12:32:16 INFO - 'talos_options': ['--xperf_path', 12:32:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:32:16 INFO - 'tests': ['tp5n']}}, 12:32:16 INFO - 'talos.zip': {'path': '', 12:32:16 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:32:16 INFO - Running post-action listener: _resource_record_post_action 12:32:16 INFO - [mozharness: 2016-12-20 20:32:16.930803Z] Finished populate-webroot step (success) 12:32:16 INFO - [mozharness: 2016-12-20 20:32:16.930896Z] Running create-virtualenv step. 12:32:16 INFO - Running pre-action listener: _resource_record_pre_action 12:32:16 INFO - Running main action method: create_virtualenv 12:32:16 INFO - Creating virtualenv /builds/slave/test/build/venv 12:32:16 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 12:32:16 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:32:17 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:32:17 INFO - Using real prefix '/usr' 12:32:17 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:32:17 INFO - Installing distribute.............................................................................................................................................................................................done. 12:32:19 INFO - Installing pip.................done. 12:32:19 INFO - Return code: 0 12:32:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 12:32:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 12:32:19 INFO - Reading from file tmpfile_stdout 12:32:19 INFO - Output received: 12:32:19 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) 12:32:19 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:32:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:32:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:32:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:32:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:32:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:32:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:32:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b191f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c29a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e8b630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f14850>, '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 0x1e896d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e89b60>, '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': '826874ccc97bd7bf541b4875000001d5-1482265011.769691-1153358795', '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 12:32:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 12:32:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 12:32:19 INFO - Using env: {'DISPLAY': ':0', 12:32:19 INFO - 'HOME': '/home/cltbld', 12:32:19 INFO - 'LANG': 'en_US.UTF-8', 12:32:19 INFO - 'LANGUAGE': 'en_US:en', 12:32:19 INFO - 'LOGNAME': 'cltbld', 12:32:19 INFO - 'MAIL': '/var/mail/cltbld', 12:32:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:32:19 INFO - 'MOZ_NO_REMOTE': '1', 12:32:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:32:19 INFO - 'NO_EM_RESTART': '1', 12:32:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:32:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:32:19 INFO - 'PWD': '/builds/slave/test', 12:32:19 INFO - 'SHELL': '/bin/bash', 12:32:19 INFO - 'SHLVL': '1', 12:32:19 INFO - 'TERM': 'linux', 12:32:19 INFO - 'TMOUT': '86400', 12:32:19 INFO - 'USER': 'cltbld', 12:32:19 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1482265011.769691-1153358795', 12:32:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:32:19 INFO - '_': '/tools/buildbot/bin/python'} 12:32:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:32:19 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 12:32:19 INFO - Cleaning up... 12:32:19 INFO - Return code: 0 12:32:19 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:32:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:32:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:32:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:32:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:32:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:32:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:32:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1b191f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c29a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e8b630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f14850>, '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 0x1e896d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e89b60>, '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': '826874ccc97bd7bf541b4875000001d5-1482265011.769691-1153358795', '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 12:32:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 12:32:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 12:32:19 INFO - Using env: {'DISPLAY': ':0', 12:32:19 INFO - 'HOME': '/home/cltbld', 12:32:19 INFO - 'LANG': 'en_US.UTF-8', 12:32:19 INFO - 'LANGUAGE': 'en_US:en', 12:32:19 INFO - 'LOGNAME': 'cltbld', 12:32:19 INFO - 'MAIL': '/var/mail/cltbld', 12:32:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:32:19 INFO - 'MOZ_NO_REMOTE': '1', 12:32:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:32:19 INFO - 'NO_EM_RESTART': '1', 12:32:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:32:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:32:19 INFO - 'PWD': '/builds/slave/test', 12:32:19 INFO - 'SHELL': '/bin/bash', 12:32:19 INFO - 'SHLVL': '1', 12:32:19 INFO - 'TERM': 'linux', 12:32:19 INFO - 'TMOUT': '86400', 12:32:19 INFO - 'USER': 'cltbld', 12:32:19 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1482265011.769691-1153358795', 12:32:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:32:19 INFO - '_': '/tools/buildbot/bin/python'} 12:32:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:32:20 INFO - Downloading/unpacking psutil>=3.1.1 12:32: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 12:32: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 12:32: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 12:32: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 12:32: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 12:32: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 12:32:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:32:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:32:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:32:22 INFO - Installing collected packages: psutil 12:32:22 INFO - Running setup.py install for psutil 12:32:22 INFO - building 'psutil._psutil_linux' extension 12:32:22 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 12:32:22 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 12:32:22 INFO - building 'psutil._psutil_posix' extension 12:32:22 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 12:32:22 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 12:32:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:32:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:32:22 INFO - Successfully installed psutil 12:32:22 INFO - Cleaning up... 12:32:22 INFO - Return code: 0 12:32:22 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:32:22 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')]} 12:32:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:32:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:32:22 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')]} 12:32:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:32:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:32:22 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 0x1b191f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c29a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e8b630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f14850>, '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 0x1e896d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e89b60>, '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': '826874ccc97bd7bf541b4875000001d5-1482265011.769691-1153358795', '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 12:32:22 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 12:32:22 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 12:32:22 INFO - Using env: {'DISPLAY': ':0', 12:32:22 INFO - 'HOME': '/home/cltbld', 12:32:22 INFO - 'LANG': 'en_US.UTF-8', 12:32:22 INFO - 'LANGUAGE': 'en_US:en', 12:32:22 INFO - 'LOGNAME': 'cltbld', 12:32:22 INFO - 'MAIL': '/var/mail/cltbld', 12:32:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:32:22 INFO - 'MOZ_NO_REMOTE': '1', 12:32:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:32:22 INFO - 'NO_EM_RESTART': '1', 12:32:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:32:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:32:22 INFO - 'PWD': '/builds/slave/test', 12:32:22 INFO - 'SHELL': '/bin/bash', 12:32:22 INFO - 'SHLVL': '1', 12:32:22 INFO - 'TERM': 'linux', 12:32:22 INFO - 'TMOUT': '86400', 12:32:22 INFO - 'USER': 'cltbld', 12:32:22 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1482265011.769691-1153358795', 12:32:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:32:22 INFO - '_': '/tools/buildbot/bin/python'} 12:32:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:32:23 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:32:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:32:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:32:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:32:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:32:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:32:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:32:25 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:32:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:32:25 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:32:25 INFO - Installing collected packages: mozsystemmonitor 12:32:25 INFO - Running setup.py install for mozsystemmonitor 12:32:25 INFO - Successfully installed mozsystemmonitor 12:32:25 INFO - Cleaning up... 12:32:25 INFO - Return code: 0 12:32:25 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:32:25 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')]} 12:32:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:32:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:32:25 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')]} 12:32:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:32:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:32:25 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 0x1b191f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c29a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e8b630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f14850>, '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 0x1e896d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e89b60>, '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': '826874ccc97bd7bf541b4875000001d5-1482265011.769691-1153358795', '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 12:32:25 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 12:32:25 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 12:32:25 INFO - Using env: {'DISPLAY': ':0', 12:32:25 INFO - 'HOME': '/home/cltbld', 12:32:25 INFO - 'LANG': 'en_US.UTF-8', 12:32:25 INFO - 'LANGUAGE': 'en_US:en', 12:32:25 INFO - 'LOGNAME': 'cltbld', 12:32:25 INFO - 'MAIL': '/var/mail/cltbld', 12:32:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:32:25 INFO - 'MOZ_NO_REMOTE': '1', 12:32:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:32:25 INFO - 'NO_EM_RESTART': '1', 12:32:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:32:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:32:25 INFO - 'PWD': '/builds/slave/test', 12:32:25 INFO - 'SHELL': '/bin/bash', 12:32:25 INFO - 'SHLVL': '1', 12:32:25 INFO - 'TERM': 'linux', 12:32:25 INFO - 'TMOUT': '86400', 12:32:25 INFO - 'USER': 'cltbld', 12:32:25 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1482265011.769691-1153358795', 12:32:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:32:25 INFO - '_': '/tools/buildbot/bin/python'} 12:32:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:32:25 INFO - Downloading/unpacking jsonschema==2.5.1 12:32:25 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 12:32:25 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 12:32:25 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 12:32:25 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 12:32:25 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 12:32:25 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 12:32:27 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:32:27 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:32: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 12:32: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 12:32: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 12:32: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 12:32: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 12:32: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 12:32:27 INFO - Downloading functools32-3.2.3-2.tar.gz 12:32:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:32:27 INFO - warning: no files found matching '*.txt' 12:32:27 INFO - no previously-included directories found matching 'build' 12:32:27 INFO - no previously-included directories found matching 'dist' 12:32:27 INFO - no previously-included directories found matching '.git*' 12:32:27 INFO - Installing collected packages: jsonschema, functools32 12:32:27 INFO - Running setup.py install for functools32 12:32:27 INFO - warning: no files found matching '*.txt' 12:32:27 INFO - no previously-included directories found matching 'build' 12:32:27 INFO - no previously-included directories found matching 'dist' 12:32:27 INFO - no previously-included directories found matching '.git*' 12:32:27 INFO - Successfully installed jsonschema functools32 12:32:27 INFO - Cleaning up... 12:32:27 INFO - Return code: 0 12:32:27 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:32:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:32:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:32:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:32:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:32:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:32:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:32:27 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 0x1b191f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c29a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e8b630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f14850>, '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 0x1e896d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e89b60>, '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': '826874ccc97bd7bf541b4875000001d5-1482265011.769691-1153358795', '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 12:32:27 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 12:32:27 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 12:32:27 INFO - Using env: {'DISPLAY': ':0', 12:32:27 INFO - 'HOME': '/home/cltbld', 12:32:27 INFO - 'LANG': 'en_US.UTF-8', 12:32:27 INFO - 'LANGUAGE': 'en_US:en', 12:32:27 INFO - 'LOGNAME': 'cltbld', 12:32:27 INFO - 'MAIL': '/var/mail/cltbld', 12:32:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:32:27 INFO - 'MOZ_NO_REMOTE': '1', 12:32:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:32:27 INFO - 'NO_EM_RESTART': '1', 12:32:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:32:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:32:27 INFO - 'PWD': '/builds/slave/test', 12:32:27 INFO - 'SHELL': '/bin/bash', 12:32:27 INFO - 'SHLVL': '1', 12:32:27 INFO - 'TERM': 'linux', 12:32:27 INFO - 'TMOUT': '86400', 12:32:27 INFO - 'USER': 'cltbld', 12:32:27 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1482265011.769691-1153358795', 12:32:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:32:27 INFO - '_': '/tools/buildbot/bin/python'} 12:32:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:32:28 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:32:28 INFO - Cleaning up... 12:32:28 INFO - Return code: 0 12:32:28 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:32:28 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')]} 12:32:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:32:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:32:28 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')]} 12:32:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:32:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:32:28 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 0x1b191f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c29a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e8b630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f14850>, '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 0x1e896d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e89b60>, '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': '826874ccc97bd7bf541b4875000001d5-1482265011.769691-1153358795', '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 12:32:28 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 12:32:28 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 12:32:28 INFO - Using env: {'DISPLAY': ':0', 12:32:28 INFO - 'HOME': '/home/cltbld', 12:32:28 INFO - 'LANG': 'en_US.UTF-8', 12:32:28 INFO - 'LANGUAGE': 'en_US:en', 12:32:28 INFO - 'LOGNAME': 'cltbld', 12:32:28 INFO - 'MAIL': '/var/mail/cltbld', 12:32:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:32:28 INFO - 'MOZ_NO_REMOTE': '1', 12:32:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:32:28 INFO - 'NO_EM_RESTART': '1', 12:32:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:32:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:32:28 INFO - 'PWD': '/builds/slave/test', 12:32:28 INFO - 'SHELL': '/bin/bash', 12:32:28 INFO - 'SHLVL': '1', 12:32:28 INFO - 'TERM': 'linux', 12:32:28 INFO - 'TMOUT': '86400', 12:32:28 INFO - 'USER': 'cltbld', 12:32:28 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1482265011.769691-1153358795', 12:32:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:32:28 INFO - '_': '/tools/buildbot/bin/python'} 12:32:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:32:28 INFO - Downloading/unpacking blobuploader==1.2.4 12:32:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:32:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:32:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:32:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:32:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:32:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:32:30 INFO - Downloading blobuploader-1.2.4.tar.gz 12:32:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:32:30 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:32:30 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 12:32:30 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 12:32:30 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 12:32:30 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 12:32:30 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 12:32:30 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 12:32:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:32:30 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:32:30 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 12:32:30 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 12:32:30 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 12:32:30 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 12:32:30 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 12:32:30 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 12:32:31 INFO - Downloading docopt-0.6.1.tar.gz 12:32:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:32:31 INFO - Installing collected packages: blobuploader, requests, docopt 12:32:31 INFO - Running setup.py install for blobuploader 12:32:31 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:32:31 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:32:31 INFO - Running setup.py install for requests 12:32:31 INFO - Running setup.py install for docopt 12:32:31 INFO - Successfully installed blobuploader requests docopt 12:32:31 INFO - Cleaning up... 12:32:31 INFO - Return code: 0 12:32:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:32:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:32:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:32:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:32:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:32:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:32:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:32:31 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 0x1b191f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c29a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e8b630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f14850>, '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 0x1e896d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e89b60>, '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': '826874ccc97bd7bf541b4875000001d5-1482265011.769691-1153358795', '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 12:32:31 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 12:32:31 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 12:32:31 INFO - Using env: {'DISPLAY': ':0', 12:32:31 INFO - 'HOME': '/home/cltbld', 12:32:31 INFO - 'LANG': 'en_US.UTF-8', 12:32:31 INFO - 'LANGUAGE': 'en_US:en', 12:32:31 INFO - 'LOGNAME': 'cltbld', 12:32:31 INFO - 'MAIL': '/var/mail/cltbld', 12:32:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:32:31 INFO - 'MOZ_NO_REMOTE': '1', 12:32:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:32:31 INFO - 'NO_EM_RESTART': '1', 12:32:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:32:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:32:31 INFO - 'PWD': '/builds/slave/test', 12:32:31 INFO - 'SHELL': '/bin/bash', 12:32:31 INFO - 'SHLVL': '1', 12:32:31 INFO - 'TERM': 'linux', 12:32:31 INFO - 'TMOUT': '86400', 12:32:31 INFO - 'USER': 'cltbld', 12:32:31 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1482265011.769691-1153358795', 12:32:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:32:31 INFO - '_': '/tools/buildbot/bin/python'} 12:32:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:32:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:32:32 INFO - Running setup.py (path:/tmp/pip-dWxjvv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:32:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:32:32 INFO - Running setup.py (path:/tmp/pip-zr_c6v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:32:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:32:32 INFO - Running setup.py (path:/tmp/pip-VH1MAu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:32:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:32:32 INFO - Running setup.py (path:/tmp/pip-EIYZI6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:32:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:32:32 INFO - Running setup.py (path:/tmp/pip-oIJ5Z4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:32:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:32:32 INFO - Running setup.py (path:/tmp/pip-kRYIIC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:32:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:32:32 INFO - Running setup.py (path:/tmp/pip-CIZE6K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:32:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:32:32 INFO - Running setup.py (path:/tmp/pip-9uXd4U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:32:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:32:32 INFO - Running setup.py (path:/tmp/pip-JEwqB0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:32:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:32:32 INFO - Running setup.py (path:/tmp/pip-lbjNfJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:32:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:32:32 INFO - Running setup.py (path:/tmp/pip-Lv3a1P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:32:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:32:32 INFO - Running setup.py (path:/tmp/pip-ouwL_s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:32:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:32:32 INFO - Running setup.py (path:/tmp/pip-xMAlqr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:32:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:32:32 INFO - Running setup.py (path:/tmp/pip-4MxqvK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:32:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:32:33 INFO - Running setup.py (path:/tmp/pip-yQe8cp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:32:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:32:33 INFO - Running setup.py (path:/tmp/pip-jsvZnk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:32:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:32:33 INFO - Running setup.py (path:/tmp/pip-1vEYWm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:32:33 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:32:33 INFO - Running setup.py install for manifestparser 12:32:33 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:32:33 INFO - Running setup.py install for mozcrash 12:32:33 INFO - Running setup.py install for mozdebug 12:32:33 INFO - Running setup.py install for mozdevice 12:32:33 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:32:33 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:32:33 INFO - Running setup.py install for mozfile 12:32:33 INFO - Running setup.py install for mozhttpd 12:32:33 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:32:33 INFO - Running setup.py install for mozinfo 12:32:33 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:32:33 INFO - Running setup.py install for mozInstall 12:32:34 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:32:34 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:32:34 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:32:34 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:32:34 INFO - Running setup.py install for mozleak 12:32:34 INFO - Running setup.py install for mozlog 12:32:34 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:32:34 INFO - Running setup.py install for moznetwork 12:32:34 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:32:34 INFO - Running setup.py install for mozprocess 12:32:34 INFO - Running setup.py install for mozprofile 12:32:34 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:32:34 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:32:34 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:32:34 INFO - Running setup.py install for mozrunner 12:32:34 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:32:34 INFO - Running setup.py install for mozscreenshot 12:32:34 INFO - Running setup.py install for moztest 12:32:34 INFO - Running setup.py install for mozversion 12:32:34 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:32:34 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:32:34 INFO - Cleaning up... 12:32:35 INFO - Return code: 0 12:32:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:32:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:32:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:32:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:32:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:32:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:32:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:32:35 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 0x1b191f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c29a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e8b630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f14850>, '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 0x1e896d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e89b60>, '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': '826874ccc97bd7bf541b4875000001d5-1482265011.769691-1153358795', '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 12:32:35 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 12:32:35 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 12:32:35 INFO - Using env: {'DISPLAY': ':0', 12:32:35 INFO - 'HOME': '/home/cltbld', 12:32:35 INFO - 'LANG': 'en_US.UTF-8', 12:32:35 INFO - 'LANGUAGE': 'en_US:en', 12:32:35 INFO - 'LOGNAME': 'cltbld', 12:32:35 INFO - 'MAIL': '/var/mail/cltbld', 12:32:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:32:35 INFO - 'MOZ_NO_REMOTE': '1', 12:32:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:32:35 INFO - 'NO_EM_RESTART': '1', 12:32:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:32:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:32:35 INFO - 'PWD': '/builds/slave/test', 12:32:35 INFO - 'SHELL': '/bin/bash', 12:32:35 INFO - 'SHLVL': '1', 12:32:35 INFO - 'TERM': 'linux', 12:32:35 INFO - 'TMOUT': '86400', 12:32:35 INFO - 'USER': 'cltbld', 12:32:35 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1482265011.769691-1153358795', 12:32:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:32:35 INFO - '_': '/tools/buildbot/bin/python'} 12:32:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:32:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:32:35 INFO - Running setup.py (path:/tmp/pip-JQAAEP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:32:35 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)) 12:32:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:32:35 INFO - Running setup.py (path:/tmp/pip-UiH2Ek-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:32:35 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)) 12:32:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:32:35 INFO - Running setup.py (path:/tmp/pip-Wcq0YK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:32:35 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)) 12:32:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:32:35 INFO - Running setup.py (path:/tmp/pip-XGYYk0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:32:35 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)) 12:32:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:32:35 INFO - Running setup.py (path:/tmp/pip-UfWJQQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:32:35 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)) 12:32:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:32:35 INFO - Running setup.py (path:/tmp/pip-CKlm7H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:32:35 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)) 12:32:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:32:35 INFO - Running setup.py (path:/tmp/pip-gMYUHR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:32:35 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)) 12:32:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:32:35 INFO - Running setup.py (path:/tmp/pip-jpQ97d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:32:35 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)) 12:32:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:32:35 INFO - Running setup.py (path:/tmp/pip-AFS4vh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:32:35 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)) 12:32:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:32:35 INFO - Running setup.py (path:/tmp/pip-2p45Nv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:32:35 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)) 12:32:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:32:35 INFO - Running setup.py (path:/tmp/pip-l5PI8N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:32:36 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)) 12:32:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:32:36 INFO - Running setup.py (path:/tmp/pip-6HlUe9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:32:36 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)) 12:32:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:32:36 INFO - Running setup.py (path:/tmp/pip-4slChh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:32:36 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)) 12:32:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:32:36 INFO - Running setup.py (path:/tmp/pip-MZ4EFP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:32:36 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)) 12:32:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:32:36 INFO - Running setup.py (path:/tmp/pip-aUZuhU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:32:36 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)) 12:32:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:32:36 INFO - Running setup.py (path:/tmp/pip-5Sp1N6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:32:36 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)) 12:32:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:32:36 INFO - Running setup.py (path:/tmp/pip-TGk4Kf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:32:36 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)) 12:32:36 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)) 12:32:36 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)) 12:32:36 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)) 12:32:36 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)) 12:32:36 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)) 12:32:36 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:32:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:32:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:32:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:32:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:32:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:32:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:32:38 INFO - Downloading blessings-1.6.tar.gz 12:32:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:32:38 INFO - Installing collected packages: blessings 12:32:38 INFO - Running setup.py install for blessings 12:32:38 INFO - Successfully installed blessings 12:32:38 INFO - Cleaning up... 12:32:38 INFO - Return code: 0 12:32:38 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:32:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:32:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:32:38 INFO - Reading from file tmpfile_stdout 12:32:38 INFO - Current package versions: 12:32:38 INFO - argparse == 1.2.1 12:32:38 INFO - blessings == 1.6 12:32:38 INFO - blobuploader == 1.2.4 12:32:38 INFO - docopt == 0.6.1 12:32:38 INFO - functools32 == 3.2.3-2 12:32:38 INFO - jsonschema == 2.5.1 12:32:38 INFO - manifestparser == 1.1 12:32:38 INFO - mozInstall == 1.12 12:32:38 INFO - mozcrash == 1.0 12:32:38 INFO - mozdebug == 0.1 12:32:38 INFO - mozdevice == 0.49 12:32:38 INFO - mozfile == 1.2 12:32:38 INFO - mozhttpd == 0.7 12:32:38 INFO - mozinfo == 0.9 12:32:38 INFO - mozleak == 0.1 12:32:38 INFO - mozlog == 3.4 12:32:38 INFO - moznetwork == 0.27 12:32:38 INFO - mozprocess == 0.23 12:32:38 INFO - mozprofile == 0.28 12:32:38 INFO - mozrunner == 6.13 12:32:38 INFO - mozscreenshot == 0.1 12:32:38 INFO - mozsystemmonitor == 0.3 12:32:38 INFO - moztest == 0.7 12:32:38 INFO - mozversion == 1.4 12:32:38 INFO - psutil == 3.1.1 12:32:38 INFO - requests == 1.2.3 12:32:38 INFO - wsgiref == 0.1.2 12:32:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:32:38 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')]} 12:32:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:32:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:32:38 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')]} 12:32:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:32:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:32:38 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 0x1b191f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c29a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e8b630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f14850>, '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 0x1e896d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e89b60>, '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': '826874ccc97bd7bf541b4875000001d5-1482265011.769691-1153358795', '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 12:32:38 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 12:32:38 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 12:32:38 INFO - Using env: {'DISPLAY': ':0', 12:32:38 INFO - 'HOME': '/home/cltbld', 12:32:38 INFO - 'LANG': 'en_US.UTF-8', 12:32:38 INFO - 'LANGUAGE': 'en_US:en', 12:32:38 INFO - 'LOGNAME': 'cltbld', 12:32:38 INFO - 'MAIL': '/var/mail/cltbld', 12:32:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:32:38 INFO - 'MOZ_NO_REMOTE': '1', 12:32:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:32:38 INFO - 'NO_EM_RESTART': '1', 12:32:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:32:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:32:38 INFO - 'PWD': '/builds/slave/test', 12:32:38 INFO - 'SHELL': '/bin/bash', 12:32:38 INFO - 'SHLVL': '1', 12:32:38 INFO - 'TERM': 'linux', 12:32:38 INFO - 'TMOUT': '86400', 12:32:38 INFO - 'USER': 'cltbld', 12:32:38 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1482265011.769691-1153358795', 12:32:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:32:38 INFO - '_': '/tools/buildbot/bin/python'} 12:32:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:32:39 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)) 12:32:39 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)) 12:32:39 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)) 12:32:39 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)) 12:32:39 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)) 12:32:39 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)) 12:32:39 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)) 12:32:39 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)) 12:32:39 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)) 12:32:39 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)) 12:32:39 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)) 12:32:39 INFO - Cleaning up... 12:32:39 INFO - Return code: 0 12:32:39 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:32:39 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')]} 12:32:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:32:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:32:39 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')]} 12:32:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:32:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:32:39 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 0x1b191f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c29a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e8b630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f14850>, '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 0x1e896d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e89b60>, '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': '826874ccc97bd7bf541b4875000001d5-1482265011.769691-1153358795', '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 12:32:39 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 12:32:39 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 12:32:39 INFO - Using env: {'DISPLAY': ':0', 12:32:39 INFO - 'HOME': '/home/cltbld', 12:32:39 INFO - 'LANG': 'en_US.UTF-8', 12:32:39 INFO - 'LANGUAGE': 'en_US:en', 12:32:39 INFO - 'LOGNAME': 'cltbld', 12:32:39 INFO - 'MAIL': '/var/mail/cltbld', 12:32:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:32:39 INFO - 'MOZ_NO_REMOTE': '1', 12:32:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:32:39 INFO - 'NO_EM_RESTART': '1', 12:32:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:32:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:32:39 INFO - 'PWD': '/builds/slave/test', 12:32:39 INFO - 'SHELL': '/bin/bash', 12:32:39 INFO - 'SHLVL': '1', 12:32:39 INFO - 'TERM': 'linux', 12:32:39 INFO - 'TMOUT': '86400', 12:32:39 INFO - 'USER': 'cltbld', 12:32:39 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1482265011.769691-1153358795', 12:32:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:32:39 INFO - '_': '/tools/buildbot/bin/python'} 12:32:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:32:39 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:32:39 INFO - Cleaning up... 12:32:39 INFO - Return code: 0 12:32:39 INFO - Running post-action listener: _resource_record_post_action 12:32:39 INFO - Running post-action listener: _start_resource_monitoring 12:32:39 INFO - Starting resource monitoring. 12:32:39 INFO - [mozharness: 2016-12-20 20:32:39.435679Z] Finished create-virtualenv step (success) 12:32:39 INFO - [mozharness: 2016-12-20 20:32:39.436152Z] Running install step. 12:32:39 INFO - Running pre-action listener: _resource_record_pre_action 12:32:39 INFO - Running main action method: install 12:32:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:32:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:32:39 INFO - Reading from file tmpfile_stdout 12:32:39 INFO - Detecting whether we're running mozinstall >=1.0... 12:32:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:32:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:32:39 INFO - Reading from file tmpfile_stdout 12:32:39 INFO - Output received: 12:32:39 INFO - Usage: mozinstall [options] installer 12:32:39 INFO - Options: 12:32:39 INFO - -h, --help show this help message and exit 12:32:39 INFO - -d DEST, --destination=DEST 12:32:39 INFO - Directory to install application into. [default: 12:32:39 INFO - "/builds/slave/test"] 12:32:39 INFO - --app=APP Application being installed. [default: firefox] 12:32:39 INFO - mkdir: /builds/slave/test/build/application 12:32:39 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'] 12:32:39 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 12:32:55 INFO - Reading from file tmpfile_stdout 12:32:55 INFO - Output received: 12:32:55 INFO - /builds/slave/test/build/application/firefox/firefox 12:32:55 INFO - Running post-action listener: _resource_record_post_action 12:32:55 INFO - [mozharness: 2016-12-20 20:32:55.767757Z] Finished install step (success) 12:32:55 INFO - [mozharness: 2016-12-20 20:32:55.768002Z] Running run-tests step. 12:32:55 INFO - Running pre-action listener: _resource_record_pre_action 12:32:55 INFO - Running main action method: run_tests 12:32:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:32:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:32:55 INFO - Python 2.7.3 12:32:55 INFO - Return code: 0 12:32:55 INFO - grabbing minidump binary from tooltool 12:32:55 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')]} 12:32:55 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 0x1f14850>, '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 0x1e896d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e89b60>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:32:55 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 12:32:55 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 12:32:55 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 12:32:55 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:32:55 INFO - Return code: 0 12:32:55 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 12:32:55 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:32:55 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:32:55 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 12:32:55 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:32:55 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/PlZqv_PrSZemwj-kudA5kQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-052', '--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 12:32:55 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/PlZqv_PrSZemwj-kudA5kQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-052 --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 12:32:55 INFO - Using env: {'DISPLAY': ':0', 12:32:55 INFO - 'HOME': '/home/cltbld', 12:32:55 INFO - 'LANG': 'en_US.UTF-8', 12:32:55 INFO - 'LANGUAGE': 'en_US:en', 12:32:55 INFO - 'LOGNAME': 'cltbld', 12:32:55 INFO - 'MAIL': '/var/mail/cltbld', 12:32:55 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:32:55 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:32:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:32:55 INFO - 'MOZ_NO_REMOTE': '1', 12:32:55 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:32:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:32:55 INFO - 'NO_EM_RESTART': '1', 12:32:55 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:32:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:32:55 INFO - 'PWD': '/builds/slave/test', 12:32:55 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:32:55 INFO - 'SHELL': '/bin/bash', 12:32:55 INFO - 'SHLVL': '1', 12:32:55 INFO - 'TERM': 'linux', 12:32:55 INFO - 'TMOUT': '86400', 12:32:55 INFO - 'USER': 'cltbld', 12:32:55 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1482265011.769691-1153358795', 12:32:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:32:55 INFO - '_': '/tools/buildbot/bin/python'} 12:32:55 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/PlZqv_PrSZemwj-kudA5kQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-052', '--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 12:32:56 INFO - mozversion application_buildid: 20161220113554 12:32:56 INFO - mozversion application_changeset: 0f3603e365f029bc9ea5f926d32f6f0f8aa4d998 12:32:56 INFO - mozversion application_display_name: Nightly 12:32:56 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:32:56 INFO - mozversion application_name: Firefox 12:32:56 INFO - mozversion application_remotingname: firefox 12:32:56 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 12:32:56 INFO - mozversion application_vendor: Mozilla 12:32:56 INFO - mozversion application_version: 53.0a1 12:32:56 INFO - mozversion platform_buildid: 20161220113554 12:32:56 INFO - mozversion platform_changeset: 0f3603e365f029bc9ea5f926d32f6f0f8aa4d998 12:32:56 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 12:32:56 INFO - mozversion platform_version: 53.0a1 12:32:56 INFO - using testdate: 1482265976 12:32:56 INFO - actual date: 1482265976 12:32:56 INFO - starting webserver on 'localhost:40948' 12:32:56 INFO - SUITE-START | Running 2 tests 12:32:56 INFO - TEST-START | dromaeo_css 12:32:56 INFO - operating with platform_type : linux_ 12:32:56 INFO - Initialising browser for dromaeo_css test... 12:32:56 INFO - TEST-INFO | started process 15289 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpyRQFpe/profile http://localhost:40948/getInfo.html) 12:32:59 INFO - PROCESS | 15289 | __metrics Screen width/height:1600/1200 12:32:59 INFO - PROCESS | 15289 | colorDepth:24 12:32:59 INFO - PROCESS | 15289 | Browser inner width/height: 1024/697 12:32:59 INFO - PROCESS | 15289 | __metrics 12:33:00 INFO - PROCESS | 15289 | 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" 12:33:00 INFO - PROCESS | 15289 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:33:01 INFO - PROCESS | 15289 | console.error: PushService: 12:33:01 INFO - PROCESS | 15289 | stateChangeProcessEnqueue: Error transitioning state 12:33:01 INFO - PROCESS | 15289 | UnknownError 12:33:01 INFO - TEST-INFO | 15289: exit 0 12:33:01 INFO - Browser initialized. 12:33:01 INFO - Running cycle 1/1 for dromaeo_css test... 12:33:01 INFO - TEST-INFO | started process 15370 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpyRQFpe/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:33:13 INFO - PROCESS | 15370 | RSS: Main: 155430912 12:33:13 INFO - PROCESS | 15370 | 12:34:04 INFO - PROCESS | 15370 | 1482266044867 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] 12:36:19 INFO - PROCESS | 15370 | Cycle 1(1): loaded http://localhost:40948/tests/dromaeo/cssquery-dojo.html (next: http://localhost:40948/tests/dromaeo/cssquery-ext.html) 12:36:20 INFO - PROCESS | 15370 | RSS: Main: 295743488 12:36:20 INFO - PROCESS | 15370 | 12:39:23 INFO - PROCESS | 15370 | Cycle 1(1): loaded http://localhost:40948/tests/dromaeo/cssquery-ext.html (next: http://localhost:40948/tests/dromaeo/cssquery-jquery.html) 12:39:23 INFO - PROCESS | 15370 | RSS: Main: 248614912 12:39:23 INFO - PROCESS | 15370 | 12:42:26 INFO - PROCESS | 15370 | Cycle 1(1): loaded http://localhost:40948/tests/dromaeo/cssquery-jquery.html (next: http://localhost:40948/tests/dromaeo/cssquery-mootools.html) 12:42:26 INFO - PROCESS | 15370 | RSS: Main: 192274432 12:42:26 INFO - PROCESS | 15370 | 12:45:29 INFO - PROCESS | 15370 | Cycle 1(1): loaded http://localhost:40948/tests/dromaeo/cssquery-mootools.html (next: http://localhost:40948/tests/dromaeo/cssquery-prototype.html) 12:45:29 INFO - PROCESS | 15370 | RSS: Main: 189833216 12:45:29 INFO - PROCESS | 15370 | 12:48:33 INFO - PROCESS | 15370 | Cycle 1(1): loaded http://localhost:40948/tests/dromaeo/cssquery-prototype.html (next: http://localhost:40948/tests/dromaeo/cssquery-yui.html) 12:48:33 INFO - PROCESS | 15370 | RSS: Main: 187912192 12:48:33 INFO - PROCESS | 15370 | 12:51:36 INFO - PROCESS | 15370 | Cycle 1(1): loaded http://localhost:40948/tests/dromaeo/cssquery-yui.html (next: http://localhost:40948/tests/dromaeo/cssquery-dojo.html) 12:51:36 INFO - PROCESS | 15370 | RSS: Main: 282300416 12:51:36 INFO - PROCESS | 15370 | 12:51:36 INFO - PROCESS | 15370 | __start_tp_report 12:51:36 INFO - PROCESS | 15370 | _x_x_mozilla_page_load 12:51:36 INFO - PROCESS | 15370 | _x_x_mozilla_page_load_details 12:51:36 INFO - PROCESS | 15370 | |i|pagename|runs| 12:51:36 INFO - PROCESS | 15370 | |0;dojo.html;2589.0549949268516;2619.188051703973;2631.1711810779602;2647.6558047453827;2663.7602615764577;4610.792514336855;4649.767511624422;4758.952410475898;4773.713577185367;4774.665773395864;4725.810967561302;4816.951830481694;4838.056578967094;4848.442429120652;4936.901261974758;4387.078713470175;4437.290033594625;4442.311441726538;4467.084247729208;4490.528494508078;4414.073044660609;4459.843905463309;4477.708948918326;4480.865574032768;4537.4328208973875;4486;4492.258777301741;4497.8425755098415;4521.706089104197;4535.274356103024;4252.893677658052;4294.57054294568;4294.849680261207;4361.476622805284;4427.535108813592;4324.740515569045;4346.782660866944;4365.956340436586;4368.868933931987;4427.29163333865;4736;4737.123632128068;4753.405824271966;4760.357351757538;4802.639802014863;4975.4527002029745;5110.6422550421485;5112.514311140473;5118.309028281172;5170.9741451292875;5045.924311135336;5115;5121.180611102206;5122.820701275437;5126.589872810204;4796.904061918777;4828.275758636232;4831.492693267227;4852.539008794159;4908.239222920453;5301.0723123453245;5305.681659100466;5309.495597918191;5309.787608495694;5360.624756267024;4852.56326930578;5077.365329333833;5080.110980578458;5086.21163719624;5088.313077734459;4913.754312284372;5023.522765337287;5082.669626474268;5130.025295193901;5133.691978481228;5065.290859279704;5111;5111.412187598399;5160.380754309507;5166.095933211748;5097.617678674114;5100.625940892126;5101.69389836611;5102.43873173958;5105.285260063594;4246.023414614656;4261.722988005769;4286.6999310047995;4340.153670034313;4451.755153466525;3729.21686445844;3748.962510374916;3762.097096696773;3792.3742582474133;3830.885073447801;4394.362817391524;4395.362672412482;4415.050764085737;4436.511983681792;4438.755868427203;5056.9494305057215;5058.064258112261;5066.08810414129;5066.771995260222;5104.081265372269;4337.0892112656065;4386.166628340605;4396.450443694538;4403.5376285489565;4429.579189976947;4531.229690952546;4568.428946381702;4576.176288268077;4580.358749775034;4584.724916505021;4723.409573803275;4755.5482229188865;4759.9524004759505;4763.428388593391;4764.737939413366;5112.539871411591;5112.923306150336;5118.539331460186;5123.6157288202785;5126.359205099363;4788.3056956741775;4813.7352445615825;4822.300766388784;4850.95149048505;4867.805287788448;5094.286799848099;5095.592352611708;5101.438841727481;5110.3356563646485;5152.124138896322;5019.196928491424;5110.386753589444;5163.199704045879;5165.896682066263;5166.715830629351;4969.254611808113;4984.102861484823;4987.750612469435;4989.326440930573;4990.4011518618;4891.706497610155;4898.975505122452;4935.210366341368;4976.900461990812;4985.202367621163;4826.58973987215;4836.685615434985;4849.199882019428;4870.172070748053;4893.804247830047;4975.5522003019905;4979.875503112451;4982;4985.501449854985;4991.775370108281;4729.763511824463;4816.940273139903;4905.926411103765;4906.190478571139;4921.188003979303 12:51:36 INFO - PROCESS | 15370 | |1;ext.html;506.1041955738343;522.8457605006525;525.3197109742883;525.9132243179876;547.8685115572264;26865.194044178657;29904.551431728487;29985.25036874079;36220;37898;37953.810230948875;38299.425508617394;38322.80838595803;38346.8082659587;38565;7421.332080112802;7569.659365328546;7583.393328533704;7594.316511513963;7601.543907365576;7394.630268486581;7467.439942004345;7487.812804679872;9617.471039092847;9781.510924453785;9571.18644915183;9616.90383096167;9617.42295462271;9620.086091821266;9643.951780241088;9591.944886062527;9643.566039528238;9659.46872921989;9659.710208693716;9661.323707340489;9611.134997850157;9670.806583868283;9678.467684277362;9679.177269932065;9690.854637180448;36009.81995090008;36472.27055458903;36503.634963650285;36525.26949461023;36583.26833463342;2505.8621775802326;2653.548896687568;2663.427363116939;2690.1794952539467;3052.862621182052;27051.458970820473;27147.728522714915;27348.179554613394;27617.309567260778;27822.3044423889;3142.9371412571622;3284.1953721338186;3344.1138098403944;3373.0892658982184;3385.86456541738;184847.07576462167;237618.81190594108;237824;237901;238046;44437.77781111139;44851;44960.77519612381;44996;45047;47782.283265751044;47943.28085078727;47997;48012.279815802794;48042.27936580954;13568.1859088455;13851.030427869953;13853.515126970507;13925.651858703412;13930;10871.891281087246;10947;10948.835767463494;10952.616658416917;10957.397343146204;13446.798298025538;13825.446982120602;15020.324085416183;15293.694126117416;15428.845711542965;5560.7219639017885;5575.219469274305;5821.796237131764;5897.085951677497;5933.199018132583;749.1908738562448;851.5827244650077;856.1096459681986;880.5421180985851;888.595688961521;25504.872475637872;26995.130562415037;27064;27222.727772722414;27528.58707119395;922.4465320807597;924.4869097650868;928.9767755806024;971.7910649210454;989.0505115089451;14159.50441734555;14427;14475.203863787592;14493;14736.778948315783;329802;333959;334030;334575;335037;40184.39723404151;41202;51186;51833;51960.740196299535;1127.8533790607332;1137.2835113878368;1146.7993101207255;1155.7110722319421;1156.9942150289362;26583.734162658126;27504.0568658098;27757.722422775514;28193.43613127685;28487.43025139527;40151.79924100361;40641.59358406496;40678.79660601678;40713.38929915994;40713.38929916113;239154;240449;240767;240794;241894;24705.135320263704;25070.874645626656;25138.12016579411;25204.369890752878;25218.36954076163;36083.458748118275;36356.36516331494;37443.25113497769;39378.80310598428;40305.596944030185;31001;31206.63107164953;31310;31532.842335788173;32551.51172732458;31318.217044574067;31340.686593134687;31426.52860207053;31654.841725791222;32795.672043279264;30871.919482817993;31373.21566960753;31498;31508.527372089895;31755.20611984719;27764.44471110607;28296.292592685346;28881.42237155203;28894.71105289004;29148.417031659672;22231.221907233168;22554;22871.19950801714;22922.885385573634;23126.190583329502 12:51:36 INFO - PROCESS | 15370 | |2;jquery.html;341.1250143382223;360.8538541890532;361.2954738260392;363.560092288331;384.5327926569218;12268.754624907497;15512.379504819808;15597;16078.115703636295;17066.914665426688;14710.852891471082;15117.470888518905;15197.4680886169;15280;15493.84506154938;3980.9203815923665;4344.847930322439;4470.128324976631;4614.861554153364;4616.215243408628;4570.497245303014;4644.6052085572765;4649.465311489188;4649.488556258809;4651.558101980307;4647.372604698357;4648.767561621906;4649.814007439715;4651.162790697673;4652.5114862939245;4504.729716217021;4639.976800115995;4659.813607455698;4667.346571479996;4671.135839869635;4539.84110556129;4543.182227199136;4548.545145485425;4552.590266875997;4557;12204.633860984184;12354.505819767199;12480.625581232578;12485.50057997679;12503.374831258492;1966.7148263501856;1999.2502811445709;2040.9897950510301;2149.946251343729;2251.3358559225067;8873.778155546099;8999.910000899972;9126.26989840811;9391.248700103975;9477.810443791155;1662.8420300071473;1694.1105919392294;1720.8623310135158;1750.8861923975028;1766.7173252279702;64079.669642946115;65309;65805;66318.3368166317;66430.3356966429;9305.25557955362;9549.331546791796;9605.47169905648;9762.707118786448;9993.700188994342;13189.934050329686;13204.537841175512;13211.207327560378;13218.471261149638;13219.206847589176;6722.428593569602;6999.405050570758;7017.508774385744;7082.92917070833;7094.716211351488;6242.719077641517;6346.333634968354;6411.839704007344;6706.96646516774;6725.529212955046;4194.475690538683;4200.978995105005;4260.126674031816;4287.142571485715;4310.913781724411;1768.5136587438558;1808.2495764257685;1814.3286983816072;1819.5352741043587;1835.109971663742;790.6205021589669;827.611022819274;846.4921047371528;852.5864955496579;914.8627705844177;15476.690466190837;15686.686266274839;16044.278007489691;16098.356065757473;16359.59101022484;770.603139383218;773.091617349612;796.3231253434534;842.1914961636775;845.3448577352601;8650.669492245404;8711.695090671796;8900.243479304332;9033.638654453749;9378.296627752945;331143;337984;338015;338738;340746;67081.32918670848;69865.30134698689;70619;71907;71920;884.5090974509209;910.0399078971749;920.0661328751323;925.717656114878;927.536231884058;14345.928270358581;14348.49780257686;14525.564233073024;14599.85400145985;15029.473968411054;35002;35389.46915796317;35713;35845.82077089598;37034.8148259257;43222;46814;47699.284510731675;47982.76008619935;50228;7568.5837278950185;7647.311741943252;7869.645865935932;7969.1632378601735;8014.078380986261;11184.776304474028;11470.254433461798;12046.518139274682;12202.633920982385;12310.692232694255;9879.555420005972;9968;10019.849702254327;10649.414282214553;10665;9966.451845148586;10140.797184056426;10318.17454603645;10505.264631475724;10548.208884333859;10213.642522511676;10330;10601.098906592717;10625.8937410628;10862.837057443987;7576.924230757621;7613.809654758676;7704.8459030820195;7704.92295077042;7861.685532578633;9215.76960575991;10117.443540605043;10135.087842094246;10258.128059115059;10441 12:51:36 INFO - PROCESS | 15370 | |3;mootools.html;1214.4534959268321;1311.2460335307198;1326.1446367093229;1378.6760111373826;1637.148682685004;6451.129097571827;7153.785386438409;7360.07999000125;7365.3002964718335;7514.135874374435;7360.742374016911;7405.740799072034;7424.109106907165;7427.25727427257;7429.36850367718;1818.6180902010594;1873.110272620501;2101.0650260633984;2125.9255926042592;2135.3166986564306;2088.9450827332175;2139.700441938122;2144.2280778919576;2159.114762947192;2161.2867753641262;2080.781517940617;2106.841237319476;2111.799379058987;2111.873287602741;2127.638301488743;2021.6664250398667;2038;2040.9183632654676;2047.0276618606192;2047.9897600511977;2053.4455696961736;2060.7733149353558;2075.5848830233863;2080.334293026232;2081.8230450411734;7270.854582908312;7307.671154798047;7335.266473352675;7409.110906691178;7412.036435263435;4426.291793313087;4568.497465278817;4577.198990176706;4578.679492435531;4578.862634120982;2877.4820532304175;2962.688917663636;2967.9554806677916;2974.8066375685726;2985.6118704568266;863.7236084452977;920.5213289089636;925.4401087342128;939.6100618243421;1578.1162548972611;9621.855672164924;10049.648262310784;10051.648192313307;10068.244881633907;10070.9496452518;3782.6028267032116;3978.309218582091;4021.718479706451;4039.596040395937;4043.6158564936286;7889.763307100796;7893.17121702213;7898.763037108896;7916.050073991158;7917.841643167105;4472.217361961643;4474.194644963938;4475.48531918827;4478.596926276651;4482.238019536687;3635.236600313911;3756.286305601927;4001.239764444746;4155.480564929384;4159.9376009359885;3171.5876935998635;3180.6501284858646;3213.052149615869;3288.5067239914206;3311.519829624691;1006.0693858181153;1021.2238698589126;1029.804337175949;1038.0916697889347;1049.4490392543823;720.2797202797203;736.0909277042848;756.9318761311508;782.4444644302495;792.5720111140035;4624.676272660881;4767.547083027107;4787.8563643090765;4805.711657300573;4848.151573474698;792.8810678398171;822.7453133970743;830.5307501261768;832.8417600655856;839.0644431458879;3030.787844850883;3064.6935306469177;3100.6589275179713;3104.7360974316985;3223.1136437479413;8842.734717958472;9116.635334586676;9123.954380228057;9132.22376098039;9144.451332920045;9047.185753282236;9130.315226357918;9156.6795162169;9158.90841091594;9159.129882661136;6863.897041544381;7011.509194356346;7027.8594428111155;7031.08595882532;7037.331453511908;4555.4988951216;4624.28323609841;4666.766661666835;4704.270838020112;4790.496997815179;7628.504147230412;7981.72063977758;8253.876191857007;8288.544130072903;8374.706885258987;7921.762347129596;8032.116467188722;8208.876866847122;8211.260986511243;8212.096669366485;5907.734151963258;6653.434458071119;6826.488013398915;6835;6849.24658287578;6057.757689692539;6194.535409844189;6206.503479721701;6208.1308616792785;6488.772892948913;5854.136270061739;5977.5217982560425;6054.212952316347;6082.178905847831;6172.043333283348;5964.850878728066;6008.188894499362;6016.608920420159;6064.848378790565;6114.144019837137;5690.5163061140265;5705.2297939779255;5754.762726013927;5764.538836892954;5768.855778605568;4305.74165550068;4340.348947657877;4434.5122036576595;4440.755758433188;4542.227821270458;4255.446731960798;4261.190373828963;4311.078167518081;4370.978145109254;4469.173202957463 12:51:36 INFO - PROCESS | 15370 | |4;prototype.html;347.75309530233534;350.33436470705664;354.77294531499837;361.9004773687234;364.686369722039;4836.637252206086;4868.56182943535;4869.028043642326;4908.7300198489065;4913.361263035809;4806.759662016902;4816.662833601649;4872.805087796484;4879.731614761186;4885.1695211813985;3651.963480365202;3737.3085979093885;3825.5218097737784;3842.538895332564;3857.42138679199;3887.300285948528;3901.6878649707955;3903.8243279052367;3904.297226499229;3904.7852368119743;3880.670143037831;3883.106885416356;3885.9611403885883;3894.44222311075;3902.073444916313;3656.287024030315;3767.9811600942094;3773.754705944132;3856.939341681032;3895.6883449324127;3482.1642805726697;3490.110021944388;3490.6263740656345;3492.388831954398;3506.8252135534626;4719.976400118012;4720.33915251865;4733.668643194977;4734.4081989751285;4749.762511874393;4268;4396.252637051709;4416.40378548895;4423.292273236299;4425.203463376591;5064.8480545583325;5074.264231686422;5075.365579302587;5077.26379674945;5083.87290317745;3795.772253664789;3800.6959443244473;3802.277567262239;3806.3148633246005;3809.9809500952597;5064.822731204464;5242.423333433319;5311.760970756325;5313.096773548467;5314.362276526803;3895.3962135868987;3935.311320518898;3935.4490371348033;3936.6850651947775;3939.4484772131923;3980.8407663693706;3988.74073185242;3989.5490400803096;3992.5208974923194;3994.7603143811466;4419.712718673276;4424.535423780457;4430.5126436092005;4443.2224360736745;4445;4973.32860063894;5054.014467178865;5086.745662716849;5108.642395032386;5116.309298244763;1342.6039318401095;1348.514534767483;1350.439567579463;1351.5472316773992;1352.4987264136996;911.1935936696071;952.5475399185332;967.1005964453126;968.8498282766183;969.6703120938914;4333.674974376871;4418.182636212311;4424.756638384855;4537.818487260539;4565.721597952579;5244.763985620656;5247.212918062245;5254.264402983586;5256.448072952361;5258.50044245796;4528.954710452919;4534.206513860061;4546.408966834357;4555.362249285103;4557.886052848706;2579.3164811325014;2739.315171207198;2742.8491432971377;2744.7118052604587;2744.8490333031873;4840.572031250789;4842.346283251716;4853.568197381781;4867.758721525988;4875.756212189376;4932.8520144395725;5269.815556455505;5273.815416460406;5277.604179686539;5286.735663216824;4736.313234581035;4747.311639812208;4778.450478194983;4784.808607655655;4795.20879054952;5045.520675535819;5053.938672878737;5094.949050509447;5107.624954396224;5113.883323924277;4846.345743324606;5010.496850944774;5019.222020586815;5026.52248036436;5029.321041659329;4915.058551872062;4927.384076990376;4938.209886418156;4952.678075925053;4954.405471343462;3657.6708096271464;3667.963320366762;3680.153564680085;3684.1342284563643;3691.686206672463;5058.772355244097;5269.8682532935445;5272.261883336259;5277.8416647500635;5285.920711189412;5064.9746751267485;5072.82245121419;5078.289039534394;5086.18621020635;5106.795728170831;5098.470458862401;5105.391801582489;5111.131107711623;5111.207762796773;5128.282040514406;4747.501512341154;4981.925271121008;4982.4768399317545;4990.950090499048;4996.425411077773;4885.877853053702;4942.925856112233;4981.334985782589;5096.23556466533;5106.361704786902;5000.67495612784;5002.599792016557;5015.724135172601;5015.824446144367;5031.899362012666 12:51:36 INFO - PROCESS | 15370 | |5;yui.html;552.9004779139755;568.3946596874326;593.9406059394062;597.1371368372703;612.6997771092166;10922.508487118079;11712.648620541377;11792.17454778167;13630.522931697393;13764.58706238812;12412.565560205394;12959.157654752435;13247.470101195964;13270.071095023352;13272.137311074774;750.9324160825524;767.6583920155533;767.8118860879092;768.3046842607449;768.3776141325516;609.6311731402504;628.2931701835436;754.9396048316149;775.5889378629336;780.910195327536;813.800618848383;814.2142832166958;815.4536460571431;816.5713000674634;817.1705718695529;809.522381794744;810.0198759500981;810.1695761844157;810.2707563193144;810.4529442626203;468.7328222912941;480.6635355251338;580.8925348810484;591.5415552946458;596.257659214281;12900.290484023375;12939.805902911465;12942.288174150419;12948;12956.157849739737;11572.247803892804;11717.296962182296;12327;12447.813282800766;12481.687957801038;10732.892671073267;10817.350958942488;10958.890411095947;11104.44477776108;11379.089672826154;3827.617238276178;3873.5351757788962;3888.0862997195636;3927.6072392760493;3932.7247092703524;61533.384666153215;62535.37464625341;62588;65643.34356656468;65659.67170164167;9121.224695900923;9289.442633442015;9292.39599426035;9440.763980900532;10111.949440252898;19284.22863085461;19499;20559.38321850347;20578.691319630216;20583.8970805145;6758.628275444799;6762.763303284361;6768.526203165731;6793.694283757242;7082.29177082298;3558.5195998540385;3571.607123216444;3572.071261471984;3632.2735452909524;3669.5779985301497;2769.0862015534826;2863.8568071596756;2897.884084636633;2916.7374936255837;2947.292649764041;484.5203248783679;490.2768416585508;490.68105731274244;490.7595278313672;492.5370152057055;488.577380565808;490.3845673679581;495.57133079885637;502.1764306537282;502.791341593242;7526.924730752732;7816.84366312682;7869;8065.732624060268;8148;456.82183948260206;508.15646027594016;532.4967905329504;552.8721408327049;565.2369301443101;3840.462335273064;3871.825767840454;3981.8407263709128;3992.276845388397;4037.4953130858644;66254.66872665702;67694.3230567688;67990.66004669946;71802.64098679474;71968.64015679993;58569;60221.698891505264;60491.69754151201;60750;61974.38025619686;50923;52573.211401829;53202.2019669713;53462.73268633632;54156;7530.736424225126;7730.961345193238;7772.533647981139;7878;8046.316063134699;30762.69237307688;34336.141596460286;34450.82774586111;35920.46119308265;37298.98071914551;44218.3367249498;44607.776961114985;45855.54144458513;46123.538764613266;48052.27921581109;10087.59283747096;10419.166466682798;10766.838497422701;10782.838257426301;11317.434128293717;11630.767384652429;12266.141370104007;12606.054545909277;12965.805512917503;13160.01299902492;10895.564177432814;11133.55465781392;11506.597269095542;11526.366049867338;11641.126915481203;10742.73143171427;11144.832827507757;11366.829497557708;11507.482163302504;11872.643820685393;10234.385936843813;10495.632652857113;10611.787764244826;10759.784804304027;10869.836952445878;9504.02311735562;9557.13732614562;9570.856437153308;9830.606775729177;10083.747906302402;8987;9959.900400995897;10227.84658230142;10241.692749217535;10254.794904102026 12:51:36 INFO - PROCESS | 15370 | __end_tp_report 12:51:36 INFO - PROCESS | 15370 | __start_cc_report 12:51:36 INFO - PROCESS | 15370 | _x_x_mozilla_cycle_collect,1519 12:51:36 INFO - PROCESS | 15370 | __end_cc_report 12:51:36 INFO - PROCESS | 15370 | __startTimestamp1482267096916__endTimestamp 12:51:36 INFO - PROCESS | 15370 | 12:51:36 INFO - PROCESS | 15370 | ------- Summary: start ------- 12:51:36 INFO - PROCESS | 15370 | Number of tests: 6 12:51:36 INFO - PROCESS | 15370 | 12:51:36 INFO - PROCESS | 15370 | [#0] dojo.html Cycles:165 Average:4721.93 Median:4838.06 stddev:500.31 (10.3%) stddev-sans-first:473.04 12:51:36 INFO - PROCESS | 15370 | Values: 2589.1 2619.2 2631.2 2647.7 2663.8 4610.8 4649.8 4759.0 4773.7 4774.7 4725.8 4817.0 4838.1 4848.4 4936.9 4387.1 4437.3 4442.3 4467.1 4490.5 4414.1 4459.8 4477.7 4480.9 4537.4 4486.0 4492.3 4497.8 4521.7 4535.3 4252.9 4294.6 4294.8 4361.5 4427.5 4324.7 4346.8 4366.0 4368.9 4427.3 4736.0 4737.1 4753.4 4760.4 4802.6 4975.5 5110.6 5112.5 5118.3 5171.0 5045.9 5115.0 5121.2 5122.8 5126.6 4796.9 4828.3 4831.5 4852.5 4908.2 5301.1 5305.7 5309.5 5309.8 5360.6 4852.6 5077.4 5080.1 5086.2 5088.3 4913.8 5023.5 5082.7 5130.0 5133.7 5065.3 5111.0 5111.4 5160.4 5166.1 5097.6 5100.6 5101.7 5102.4 5105.3 4246.0 4261.7 4286.7 4340.2 4451.8 3729.2 3749.0 3762.1 3792.4 3830.9 4394.4 4395.4 4415.1 4436.5 4438.8 5056.9 5058.1 5066.1 5066.8 5104.1 4337.1 4386.2 4396.5 4403.5 4429.6 4531.2 4568.4 4576.2 4580.4 4584.7 4723.4 4755.5 4760.0 4763.4 4764.7 5112.5 5112.9 5118.5 5123.6 5126.4 4788.3 4813.7 4822.3 4851.0 4867.8 5094.3 5095.6 5101.4 5110.3 5152.1 5019.2 5110.4 5163.2 5165.9 5166.7 4969.3 4984.1 4987.8 4989.3 4990.4 4891.7 4899.0 4935.2 4976.9 4985.2 4826.6 4836.7 4849.2 4870.2 4893.8 4975.6 4979.9 4982.0 4985.5 4991.8 4729.8 4816.9 4905.9 4906.2 4921.2 12:51:36 INFO - PROCESS | 15370 | 12:51:36 INFO - PROCESS | 15370 | [#1] ext.html Cycles:180 Average:41491.69 Median:331880.50 stddev:71127.45 (21.4%) stddev-sans-first:71260.41 12:51:36 INFO - PROCESS | 15370 | Values: 506.1 522.8 525.3 525.9 547.9 26865.2 29904.6 29985.3 36220.0 37898.0 37953.8 38299.4 38322.8 38346.8 38565.0 7421.3 7569.7 7583.4 7594.3 7601.5 7394.6 7467.4 7487.8 9617.5 9781.5 9571.2 9616.9 9617.4 9620.1 9644.0 9591.9 9643.6 9659.5 9659.7 9661.3 9611.1 9670.8 9678.5 9679.2 9690.9 36009.8 36472.3 36503.6 36525.3 36583.3 2505.9 2653.5 2663.4 2690.2 3052.9 27051.5 27147.7 27348.2 27617.3 27822.3 3142.9 3284.2 3344.1 3373.1 3385.9 184847.1 237618.8 237824.0 237901.0 238046.0 44437.8 44851.0 44960.8 44996.0 45047.0 47782.3 47943.3 47997.0 48012.3 48042.3 13568.2 13851.0 13853.5 13925.7 13930.0 10871.9 10947.0 10948.8 10952.6 10957.4 13446.8 13825.4 15020.3 15293.7 15428.8 5560.7 5575.2 5821.8 5897.1 5933.2 749.2 851.6 856.1 880.5 888.6 25504.9 26995.1 27064.0 27222.7 27528.6 922.4 924.5 929.0 971.8 989.1 14159.5 14427.0 14475.2 14493.0 14736.8 329802.0 333959.0 334030.0 334575.0 335037.0 40184.4 41202.0 51186.0 51833.0 51960.7 1127.9 1137.3 1146.8 1155.7 1157.0 26583.7 27504.1 27757.7 28193.4 28487.4 40151.8 40641.6 40678.8 40713.4 40713.4 239154.0 240449.0 240767.0 240794.0 241894.0 24705.1 25070.9 25138.1 25204.4 25218.4 36083.5 36356.4 37443.3 39378.8 40305.6 31001.0 31206.6 31310.0 31532.8 32551.5 31318.2 31340.7 31426.5 31654.8 32795.7 30871.9 31373.2 31498.0 31508.5 31755.2 27764.4 28296.3 28881.4 28894.7 29148.4 22231.2 22554.0 22871.2 22922.9 23126.2 12:51:36 INFO - PROCESS | 15370 | 12:51:36 INFO - PROCESS | 15370 | [#2] jquery.html Cycles:180 Average:21891.04 Median:4522.29 stddev:55866.53 (1235.4%) stddev-sans-first:55999.81 12:51:36 INFO - PROCESS | 15370 | Values: 341.1 360.9 361.3 363.6 384.5 12268.8 15512.4 15597.0 16078.1 17066.9 14710.9 15117.5 15197.5 15280.0 15493.8 3980.9 4344.8 4470.1 4614.9 4616.2 4570.5 4644.6 4649.5 4649.5 4651.6 4647.4 4648.8 4649.8 4651.2 4652.5 4504.7 4640.0 4659.8 4667.3 4671.1 4539.8 4543.2 4548.5 4552.6 4557.0 12204.6 12354.5 12480.6 12485.5 12503.4 1966.7 1999.3 2041.0 2149.9 2251.3 8873.8 8999.9 9126.3 9391.2 9477.8 1662.8 1694.1 1720.9 1750.9 1766.7 64079.7 65309.0 65805.0 66318.3 66430.3 9305.3 9549.3 9605.5 9762.7 9993.7 13189.9 13204.5 13211.2 13218.5 13219.2 6722.4 6999.4 7017.5 7082.9 7094.7 6242.7 6346.3 6411.8 6707.0 6725.5 4194.5 4201.0 4260.1 4287.1 4310.9 1768.5 1808.2 1814.3 1819.5 1835.1 790.6 827.6 846.5 852.6 914.9 15476.7 15686.7 16044.3 16098.4 16359.6 770.6 773.1 796.3 842.2 845.3 8650.7 8711.7 8900.2 9033.6 9378.3 331143.0 337984.0 338015.0 338738.0 340746.0 67081.3 69865.3 70619.0 71907.0 71920.0 884.5 910.0 920.1 925.7 927.5 14345.9 14348.5 14525.6 14599.9 15029.5 35002.0 35389.5 35713.0 35845.8 37034.8 43222.0 46814.0 47699.3 47982.8 50228.0 7568.6 7647.3 7869.6 7969.2 8014.1 11184.8 11470.3 12046.5 12202.6 12310.7 9879.6 9968.0 10019.8 10649.4 10665.0 9966.5 10140.8 10318.2 10505.3 10548.2 10213.6 10330.0 10601.1 10625.9 10862.8 7576.9 7613.8 7704.8 7704.9 7861.7 9215.8 10117.4 10135.1 10258.1 10441.0 12:51:36 INFO - PROCESS | 15370 | 12:51:36 INFO - PROCESS | 15370 | [#3] mootools.html Cycles:180 Average:4768.63 Median:4777.70 stddev:2637.36 (55.2%) stddev-sans-first:2631.24 12:51:36 INFO - PROCESS | 15370 | Values: 1214.5 1311.2 1326.1 1378.7 1637.1 6451.1 7153.8 7360.1 7365.3 7514.1 7360.7 7405.7 7424.1 7427.3 7429.4 1818.6 1873.1 2101.1 2125.9 2135.3 2088.9 2139.7 2144.2 2159.1 2161.3 2080.8 2106.8 2111.8 2111.9 2127.6 2021.7 2038.0 2040.9 2047.0 2048.0 2053.4 2060.8 2075.6 2080.3 2081.8 7270.9 7307.7 7335.3 7409.1 7412.0 4426.3 4568.5 4577.2 4578.7 4578.9 2877.5 2962.7 2968.0 2974.8 2985.6 863.7 920.5 925.4 939.6 1578.1 9621.9 10049.6 10051.6 10068.2 10070.9 3782.6 3978.3 4021.7 4039.6 4043.6 7889.8 7893.2 7898.8 7916.1 7917.8 4472.2 4474.2 4475.5 4478.6 4482.2 3635.2 3756.3 4001.2 4155.5 4159.9 3171.6 3180.7 3213.1 3288.5 3311.5 1006.1 1021.2 1029.8 1038.1 1049.4 720.3 736.1 756.9 782.4 792.6 4624.7 4767.5 4787.9 4805.7 4848.2 792.9 822.7 830.5 832.8 839.1 3030.8 3064.7 3100.7 3104.7 3223.1 8842.7 9116.6 9124.0 9132.2 9144.5 9047.2 9130.3 9156.7 9158.9 9159.1 6863.9 7011.5 7027.9 7031.1 7037.3 4555.5 4624.3 4666.8 4704.3 4790.5 7628.5 7981.7 8253.9 8288.5 8374.7 7921.8 8032.1 8208.9 8211.3 8212.1 5907.7 6653.4 6826.5 6835.0 6849.2 6057.8 6194.5 6206.5 6208.1 6488.8 5854.1 5977.5 6054.2 6082.2 6172.0 5964.9 6008.2 6016.6 6064.8 6114.1 5690.5 5705.2 5754.8 5764.5 5768.9 4305.7 4340.3 4434.5 4440.8 4542.2 4255.4 4261.2 4311.1 4371.0 4469.2 12:51:36 INFO - PROCESS | 15370 | 12:51:36 INFO - PROCESS | 15370 | [#4] prototype.html Cycles:180 Average:4243.58 Median:4800.98 stddev:1189.75 (24.8%) stddev-sans-first:1156.59 12:51:36 INFO - PROCESS | 15370 | Values: 347.8 350.3 354.8 361.9 364.7 4836.6 4868.6 4869.0 4908.7 4913.4 4806.8 4816.7 4872.8 4879.7 4885.2 3652.0 3737.3 3825.5 3842.5 3857.4 3887.3 3901.7 3903.8 3904.3 3904.8 3880.7 3883.1 3886.0 3894.4 3902.1 3656.3 3768.0 3773.8 3856.9 3895.7 3482.2 3490.1 3490.6 3492.4 3506.8 4720.0 4720.3 4733.7 4734.4 4749.8 4268.0 4396.3 4416.4 4423.3 4425.2 5064.8 5074.3 5075.4 5077.3 5083.9 3795.8 3800.7 3802.3 3806.3 3810.0 5064.8 5242.4 5311.8 5313.1 5314.4 3895.4 3935.3 3935.4 3936.7 3939.4 3980.8 3988.7 3989.5 3992.5 3994.8 4419.7 4424.5 4430.5 4443.2 4445.0 4973.3 5054.0 5086.7 5108.6 5116.3 1342.6 1348.5 1350.4 1351.5 1352.5 911.2 952.5 967.1 968.8 969.7 4333.7 4418.2 4424.8 4537.8 4565.7 5244.8 5247.2 5254.3 5256.4 5258.5 4529.0 4534.2 4546.4 4555.4 4557.9 2579.3 2739.3 2742.8 2744.7 2744.8 4840.6 4842.3 4853.6 4867.8 4875.8 4932.9 5269.8 5273.8 5277.6 5286.7 4736.3 4747.3 4778.5 4784.8 4795.2 5045.5 5053.9 5094.9 5107.6 5113.9 4846.3 5010.5 5019.2 5026.5 5029.3 4915.1 4927.4 4938.2 4952.7 4954.4 3657.7 3668.0 3680.2 3684.1 3691.7 5058.8 5269.9 5272.3 5277.8 5285.9 5065.0 5072.8 5078.3 5086.2 5106.8 5098.5 5105.4 5111.1 5111.2 5128.3 4747.5 4981.9 4982.5 4991.0 4996.4 4885.9 4942.9 4981.3 5096.2 5106.4 5000.7 5002.6 5015.7 5015.8 5031.9 12:51:36 INFO - PROCESS | 15370 | 12:51:36 INFO - PROCESS | 15370 | [#5] yui.html Cycles:180 Average:14888.51 Median:474.70 stddev:18987.11 (3999.8%) stddev-sans-first:19009.85 12:51:36 INFO - PROCESS | 15370 | Values: 552.9 568.4 593.9 597.1 612.7 10922.5 11712.6 11792.2 13630.5 13764.6 12412.6 12959.2 13247.5 13270.1 13272.1 750.9 767.7 767.8 768.3 768.4 609.6 628.3 754.9 775.6 780.9 813.8 814.2 815.5 816.6 817.2 809.5 810.0 810.2 810.3 810.5 468.7 480.7 580.9 591.5 596.3 12900.3 12939.8 12942.3 12948.0 12956.2 11572.2 11717.3 12327.0 12447.8 12481.7 10732.9 10817.4 10958.9 11104.4 11379.1 3827.6 3873.5 3888.1 3927.6 3932.7 61533.4 62535.4 62588.0 65643.3 65659.7 9121.2 9289.4 9292.4 9440.8 10111.9 19284.2 19499.0 20559.4 20578.7 20583.9 6758.6 6762.8 6768.5 6793.7 7082.3 3558.5 3571.6 3572.1 3632.3 3669.6 2769.1 2863.9 2897.9 2916.7 2947.3 484.5 490.3 490.7 490.8 492.5 488.6 490.4 495.6 502.2 502.8 7526.9 7816.8 7869.0 8065.7 8148.0 456.8 508.2 532.5 552.9 565.2 3840.5 3871.8 3981.8 3992.3 4037.5 66254.7 67694.3 67990.7 71802.6 71968.6 58569.0 60221.7 60491.7 60750.0 61974.4 50923.0 52573.2 53202.2 53462.7 54156.0 7530.7 7731.0 7772.5 7878.0 8046.3 30762.7 34336.1 34450.8 35920.5 37299.0 44218.3 44607.8 45855.5 46123.5 48052.3 10087.6 10419.2 10766.8 10782.8 11317.4 11630.8 12266.1 12606.1 12965.8 13160.0 10895.6 11133.6 11506.6 11526.4 11641.1 10742.7 11144.8 11366.8 11507.5 11872.6 10234.4 10495.6 10611.8 10759.8 10869.8 9504.0 9557.1 9570.9 9830.6 10083.7 8987.0 9959.9 10227.8 10241.7 10254.8 12:51:36 INFO - PROCESS | 15370 | -------- Summary: end -------- 12:51:36 INFO - PROCESS | 15370 | 12:51:37 INFO - TEST-INFO | 15370: exit 0 12:51:37 INFO - TEST-OK | dromaeo_css | took 1121681ms 12:51:37 INFO - TEST-START | kraken 12:51:37 INFO - operating with platform_type : linux_ 12:51:37 INFO - Initialising browser for kraken test... 12:51:37 INFO - TEST-INFO | started process 15672 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpwXjYKp/profile http://localhost:40948/getInfo.html) 12:51:41 INFO - PROCESS | 15672 | __metrics Screen width/height:1600/1200 12:51:41 INFO - PROCESS | 15672 | colorDepth:24 12:51:41 INFO - PROCESS | 15672 | Browser inner width/height: 1024/697 12:51:41 INFO - PROCESS | 15672 | __metrics 12:51:42 INFO - PROCESS | 15672 | 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" 12:51:42 INFO - PROCESS | 15672 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:51:42 INFO - PROCESS | 15672 | console.error: PushService: 12:51:42 INFO - PROCESS | 15672 | stateChangeProcessEnqueue: Error transitioning state 12:51:42 INFO - PROCESS | 15672 | UnknownError 12:51:43 INFO - TEST-INFO | 15672: exit 0 12:51:43 INFO - Browser initialized. 12:51:43 INFO - Running cycle 1/1 for kraken test... 12:51:43 INFO - TEST-INFO | started process 15754 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpwXjYKp/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:51:55 INFO - PROCESS | 15754 | RSS: Main: 155623424 12:51:55 INFO - PROCESS | 15754 | 12:51:58 INFO - PROCESS | 15754 | Cycle 1(1): loaded http://localhost:40948/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:40948/tests/kraken/driver.html?testName=audio-beat-detection) 12:51:58 INFO - PROCESS | 15754 | RSS: Main: 208826368 12:51:58 INFO - PROCESS | 15754 | 12:52:01 INFO - PROCESS | 15754 | Cycle 1(1): loaded http://localhost:40948/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:40948/tests/kraken/driver.html?testName=audio-dft) 12:52:02 INFO - PROCESS | 15754 | RSS: Main: 187977728 12:52:02 INFO - PROCESS | 15754 | 12:52:05 INFO - PROCESS | 15754 | Cycle 1(1): loaded http://localhost:40948/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:40948/tests/kraken/driver.html?testName=audio-fft) 12:52:05 INFO - PROCESS | 15754 | RSS: Main: 269926400 12:52:05 INFO - PROCESS | 15754 | 12:52:08 INFO - PROCESS | 15754 | Cycle 1(1): loaded http://localhost:40948/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:40948/tests/kraken/driver.html?testName=audio-oscillator) 12:52:08 INFO - PROCESS | 15754 | RSS: Main: 181260288 12:52:08 INFO - PROCESS | 15754 | 12:52:10 INFO - PROCESS | 15754 | Cycle 1(1): loaded http://localhost:40948/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:40948/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:52:11 INFO - PROCESS | 15754 | RSS: Main: 184496128 12:52:11 INFO - PROCESS | 15754 | 12:52:14 INFO - PROCESS | 15754 | Cycle 1(1): loaded http://localhost:40948/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:40948/tests/kraken/driver.html?testName=imaging-darkroom) 12:52:15 INFO - PROCESS | 15754 | RSS: Main: 223944704 12:52:15 INFO - PROCESS | 15754 | 12:52:18 INFO - PROCESS | 15754 | Cycle 1(1): loaded http://localhost:40948/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:40948/tests/kraken/driver.html?testName=imaging-desaturate) 12:52:19 INFO - PROCESS | 15754 | RSS: Main: 217870336 12:52:19 INFO - PROCESS | 15754 | 12:52:22 INFO - PROCESS | 15754 | Cycle 1(1): loaded http://localhost:40948/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:40948/tests/kraken/driver.html?testName=json-parse-financial) 12:52:22 INFO - PROCESS | 15754 | RSS: Main: 219947008 12:52:22 INFO - PROCESS | 15754 | 12:52:24 INFO - PROCESS | 15754 | Cycle 1(1): loaded http://localhost:40948/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:40948/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:52:25 INFO - PROCESS | 15754 | RSS: Main: 183783424 12:52:25 INFO - PROCESS | 15754 | 12:52:27 INFO - PROCESS | 15754 | Cycle 1(1): loaded http://localhost:40948/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:40948/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:52:27 INFO - PROCESS | 15754 | RSS: Main: 190476288 12:52:27 INFO - PROCESS | 15754 | 12:52:29 INFO - PROCESS | 15754 | Cycle 1(1): loaded http://localhost:40948/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:40948/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:52:30 INFO - PROCESS | 15754 | RSS: Main: 199262208 12:52:30 INFO - PROCESS | 15754 | 12:52:33 INFO - PROCESS | 15754 | Cycle 1(1): loaded http://localhost:40948/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:40948/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:52:34 INFO - PROCESS | 15754 | RSS: Main: 209231872 12:52:34 INFO - PROCESS | 15754 | 12:52:37 INFO - PROCESS | 15754 | Cycle 1(1): loaded http://localhost:40948/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:40948/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:52:37 INFO - PROCESS | 15754 | RSS: Main: 204808192 12:52:37 INFO - PROCESS | 15754 | 12:52:39 INFO - PROCESS | 15754 | Cycle 1(1): loaded http://localhost:40948/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:40948/tests/kraken/driver.html?testName=ai-astar) 12:52:39 INFO - PROCESS | 15754 | RSS: Main: 255737856 12:52:39 INFO - PROCESS | 15754 | 12:52:39 INFO - PROCESS | 15754 | __start_tp_report 12:52:39 INFO - PROCESS | 15754 | _x_x_mozilla_page_load 12:52:39 INFO - PROCESS | 15754 | _x_x_mozilla_page_load_details 12:52:39 INFO - PROCESS | 15754 | |i|pagename|runs| 12:52:39 INFO - PROCESS | 15754 | |0;ai-astar;106;117;115;117;116;116;115;116;115;115 12:52:39 INFO - PROCESS | 15754 | |1;audio-beat-detection;153;128;126;173;127;124;128;127;142;126 12:52:39 INFO - PROCESS | 15754 | |2;audio-dft;160;161;161;161;161;162;160;162;161;161 12:52:39 INFO - PROCESS | 15754 | |3;audio-fft;75;75;75;111;77;76;75;76;97;76 12:52:39 INFO - PROCESS | 15754 | |4;audio-oscillator;85;84;115;84;85;85;99;85;84;112 12:52:39 INFO - PROCESS | 15754 | |5;imaging-gaussian-blur;126;127;127;127;127;126;128;126;126;127 12:52:39 INFO - PROCESS | 15754 | |6;imaging-darkroom;147;148;147;147;147;147;147;146;147;148 12:52:39 INFO - PROCESS | 15754 | |7;imaging-desaturate;96;95;95;95;95;96;96;95;96;95 12:52:39 INFO - PROCESS | 15754 | |8;json-parse-financial;65;65;63;64;64;64;65;64;65;64 12:52:39 INFO - PROCESS | 15754 | |9;json-stringify-tinderbox;66;66;66;66;66;67;66;66;66;67 12:52:39 INFO - PROCESS | 15754 | |10;stanford-crypto-aes;85;84;85;93;88;90;84;95;85;84 12:52:39 INFO - PROCESS | 15754 | |11;stanford-crypto-ccm;136;153;144;137;134;135;140;155;187;132 12:52:39 INFO - PROCESS | 15754 | |12;stanford-crypto-pbkdf2;180;185;176;177;174;175;179;177;178;175 12:52:39 INFO - PROCESS | 15754 | |13;stanford-crypto-sha256-iterative;68;68;67;67;67;67;66;72;71;67 12:52:39 INFO - PROCESS | 15754 | __end_tp_report 12:52:39 INFO - PROCESS | 15754 | __start_cc_report 12:52:39 INFO - PROCESS | 15754 | _x_x_mozilla_cycle_collect,1967 12:52:39 INFO - PROCESS | 15754 | __end_cc_report 12:52:39 INFO - PROCESS | 15754 | __startTimestamp1482267159852__endTimestamp 12:52:39 INFO - PROCESS | 15754 | 12:52:39 INFO - PROCESS | 15754 | ------- Summary: start ------- 12:52:39 INFO - PROCESS | 15754 | Number of tests: 14 12:52:39 INFO - PROCESS | 15754 | 12:52:39 INFO - PROCESS | 15754 | [#0] ai-astar Cycles:10 Average:114.80 Median:116.00 stddev:3.19 (2.8%) stddev-sans-first:0.83 12:52:39 INFO - PROCESS | 15754 | Values: 106.0 117.0 115.0 117.0 116.0 116.0 115.0 116.0 115.0 115.0 12:52:39 INFO - PROCESS | 15754 | 12:52:39 INFO - PROCESS | 15754 | [#1] audio-beat-detection Cycles:10 Average:135.40 Median:128.00 stddev:16.07 (12.6%) stddev-sans-first:15.73 12:52:39 INFO - PROCESS | 15754 | Values: 153.0 128.0 126.0 173.0 127.0 124.0 128.0 127.0 142.0 126.0 12:52:39 INFO - PROCESS | 15754 | 12:52:39 INFO - PROCESS | 15754 | [#2] audio-dft Cycles:10 Average:161.00 Median:161.00 stddev:0.67 (0.4%) stddev-sans-first:0.60 12:52:39 INFO - PROCESS | 15754 | Values: 160.0 161.0 161.0 161.0 161.0 162.0 160.0 162.0 161.0 161.0 12:52:39 INFO - PROCESS | 15754 | 12:52:39 INFO - PROCESS | 15754 | [#3] audio-fft Cycles:10 Average:81.30 Median:76.00 stddev:12.43 (16.4%) stddev-sans-first:12.97 12:52:39 INFO - PROCESS | 15754 | Values: 75.0 75.0 75.0 111.0 77.0 76.0 75.0 76.0 97.0 76.0 12:52:39 INFO - PROCESS | 15754 | 12:52:39 INFO - PROCESS | 15754 | [#4] audio-oscillator Cycles:10 Average:91.80 Median:85.00 stddev:12.32 (14.5%) stddev-sans-first:12.82 12:52:39 INFO - PROCESS | 15754 | Values: 85.0 84.0 115.0 84.0 85.0 85.0 99.0 85.0 84.0 112.0 12:52:39 INFO - PROCESS | 15754 | 12:52:39 INFO - PROCESS | 15754 | [#5] imaging-gaussian-blur Cycles:10 Average:126.70 Median:127.00 stddev:0.67 (0.5%) stddev-sans-first:0.67 12:52:39 INFO - PROCESS | 15754 | Values: 126.0 127.0 127.0 127.0 127.0 126.0 128.0 126.0 126.0 127.0 12:52:39 INFO - PROCESS | 15754 | 12:52:39 INFO - PROCESS | 15754 | [#6] imaging-darkroom Cycles:10 Average:147.10 Median:147.00 stddev:0.57 (0.4%) stddev-sans-first:0.60 12:52:39 INFO - PROCESS | 15754 | Values: 147.0 148.0 147.0 147.0 147.0 147.0 147.0 146.0 147.0 148.0 12:52:39 INFO - PROCESS | 15754 | 12:52:39 INFO - PROCESS | 15754 | [#7] imaging-desaturate Cycles:10 Average:95.40 Median:95.50 stddev:0.52 (0.5%) stddev-sans-first:0.50 12:52:39 INFO - PROCESS | 15754 | Values: 96.0 95.0 95.0 95.0 95.0 96.0 96.0 95.0 96.0 95.0 12:52:39 INFO - PROCESS | 15754 | 12:52:39 INFO - PROCESS | 15754 | [#8] json-parse-financial Cycles:10 Average:64.30 Median:64.50 stddev:0.67 (1.0%) stddev-sans-first:0.67 12:52:39 INFO - PROCESS | 15754 | Values: 65.0 65.0 63.0 64.0 64.0 64.0 65.0 64.0 65.0 64.0 12:52:39 INFO - PROCESS | 15754 | 12:52:39 INFO - PROCESS | 15754 | [#9] json-stringify-tinderbox Cycles:10 Average:66.20 Median:66.00 stddev:0.42 (0.6%) stddev-sans-first:0.44 12:52:39 INFO - PROCESS | 15754 | Values: 66.0 66.0 66.0 66.0 66.0 67.0 66.0 66.0 66.0 67.0 12:52:39 INFO - PROCESS | 15754 | 12:52:39 INFO - PROCESS | 15754 | [#10] stanford-crypto-aes Cycles:10 Average:87.30 Median:86.50 stddev:4.06 (4.7%) stddev-sans-first:4.22 12:52:39 INFO - PROCESS | 15754 | Values: 85.0 84.0 85.0 93.0 88.0 90.0 84.0 95.0 85.0 84.0 12:52:39 INFO - PROCESS | 15754 | 12:52:39 INFO - PROCESS | 15754 | [#11] stanford-crypto-ccm Cycles:10 Average:145.30 Median:142.00 stddev:16.63 (11.7%) stddev-sans-first:17.29 12:52:39 INFO - PROCESS | 15754 | Values: 136.0 153.0 144.0 137.0 134.0 135.0 140.0 155.0 187.0 132.0 12:52:39 INFO - PROCESS | 15754 | 12:52:39 INFO - PROCESS | 15754 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:177.60 Median:177.50 stddev:3.20 (1.8%) stddev-sans-first:3.28 12:52:39 INFO - PROCESS | 15754 | Values: 180.0 185.0 176.0 177.0 174.0 175.0 179.0 177.0 178.0 175.0 12:52:39 INFO - PROCESS | 15754 | 12:52:39 INFO - PROCESS | 15754 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.00 Median:67.50 stddev:1.94 (2.9%) stddev-sans-first:2.06 12:52:39 INFO - PROCESS | 15754 | Values: 68.0 68.0 67.0 67.0 67.0 67.0 66.0 72.0 71.0 67.0 12:52:39 INFO - PROCESS | 15754 | -------- Summary: end -------- 12:52:39 INFO - PROCESS | 15754 | 12:52:40 INFO - TEST-INFO | 15754: exit 0 12:52:41 INFO - TEST-OK | kraken | took 63286ms 12:52:41 INFO - SUITE-END | took 1184s 12:52:41 INFO - Completed test suite (00:19:45) 12:52:41 INFO - javascript benchmark 12:52:41 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2589.0549949268516, 2619.188051703973, 2631.1711810779602, 2647.6558047453827, 2663.7602615764577, 4610.792514336855, 4649.767511624422, 4758.952410475898, 4773.713577185367, 4774.665773395864, 4725.810967561302, 4816.951830481694, 4838.056578967094, 4848.442429120652, 4936.901261974758, 4387.078713470175, 4437.290033594625, 4442.311441726538, 4467.084247729208, 4490.528494508078, 4414.073044660609, 4459.843905463309, 4477.708948918326, 4480.865574032768, 4537.4328208973875, 4486.0, 4492.258777301741, 4497.8425755098415, 4521.706089104197, 4535.274356103024, 4252.893677658052, 4294.57054294568, 4294.849680261207, 4361.476622805284, 4427.535108813592, 4324.740515569045, 4346.782660866944, 4365.956340436586, 4368.868933931987, 4427.29163333865, 4736.0, 4737.123632128068, 4753.405824271966, 4760.357351757538, 4802.639802014863, 4975.4527002029745, 5110.6422550421485, 5112.514311140473, 5118.309028281172, 5170.9741451292875, 5045.924311135336, 5115.0, 5121.180611102206, 5122.820701275437, 5126.589872810204, 4796.904061918777, 4828.275758636232, 4831.492693267227, 4852.539008794159, 4908.239222920453, 5301.0723123453245, 5305.681659100466, 5309.495597918191, 5309.787608495694, 5360.624756267024, 4852.56326930578, 5077.365329333833, 5080.110980578458, 5086.21163719624, 5088.313077734459, 4913.754312284372, 5023.522765337287, 5082.669626474268, 5130.025295193901, 5133.691978481228, 5065.290859279704, 5111.0, 5111.412187598399, 5160.380754309507, 5166.095933211748, 5097.617678674114, 5100.625940892126, 5101.69389836611, 5102.43873173958, 5105.285260063594, 4246.023414614656, 4261.722988005769, 4286.6999310047995, 4340.153670034313, 4451.755153466525, 3729.21686445844, 3748.962510374916, 3762.097096696773, 3792.3742582474133, 3830.885073447801, 4394.362817391524, 4395.362672412482, 4415.050764085737, 4436.511983681792, 4438.755868427203, 5056.9494305057215, 5058.064258112261, 5066.08810414129, 5066.771995260222, 5104.081265372269, 4337.0892112656065, 4386.166628340605, 4396.450443694538, 4403.5376285489565, 4429.579189976947, 4531.229690952546, 4568.428946381702, 4576.176288268077, 4580.358749775034, 4584.724916505021, 4723.409573803275, 4755.5482229188865, 4759.9524004759505, 4763.428388593391, 4764.737939413366, 5112.539871411591, 5112.923306150336, 5118.539331460186, 5123.6157288202785, 5126.359205099363, 4788.3056956741775, 4813.7352445615825, 4822.300766388784, 4850.95149048505, 4867.805287788448, 5094.286799848099, 5095.592352611708, 5101.438841727481, 5110.3356563646485, 5152.124138896322, 5019.196928491424, 5110.386753589444, 5163.199704045879, 5165.896682066263, 5166.715830629351, 4969.254611808113, 4984.102861484823, 4987.750612469435, 4989.326440930573, 4990.4011518618, 4891.706497610155, 4898.975505122452, 4935.210366341368, 4976.900461990812, 4985.202367621163, 4826.58973987215, 4836.685615434985, 4849.199882019428, 4870.172070748053, 4893.804247830047, 4975.5522003019905, 4979.875503112451, 4982.0, 4985.501449854985, 4991.775370108281, 4729.763511824463, 4816.940273139903, 4905.926411103765, 4906.190478571139, 4921.188003979303], "value": 4689.35201707045, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [506.1041955738343, 522.8457605006525, 525.3197109742883, 525.9132243179876, 547.8685115572264, 26865.194044178657, 29904.551431728487, 29985.25036874079, 36220.0, 37898.0, 37953.810230948875, 38299.425508617394, 38322.80838595803, 38346.8082659587, 38565.0, 7421.332080112802, 7569.659365328546, 7583.393328533704, 7594.316511513963, 7601.543907365576, 7394.630268486581, 7467.439942004345, 7487.812804679872, 9617.471039092847, 9781.510924453785, 9571.18644915183, 9616.90383096167, 9617.42295462271, 9620.086091821266, 9643.951780241088, 9591.944886062527, 9643.566039528238, 9659.46872921989, 9659.710208693716, 9661.323707340489, 9611.134997850157, 9670.806583868283, 9678.467684277362, 9679.177269932065, 9690.854637180448, 36009.81995090008, 36472.27055458903, 36503.634963650285, 36525.26949461023, 36583.26833463342, 2505.8621775802326, 2653.548896687568, 2663.427363116939, 2690.1794952539467, 3052.862621182052, 27051.458970820473, 27147.728522714915, 27348.179554613394, 27617.309567260778, 27822.3044423889, 3142.9371412571622, 3284.1953721338186, 3344.1138098403944, 3373.0892658982184, 3385.86456541738, 184847.07576462167, 237618.81190594108, 237824.0, 237901.0, 238046.0, 44437.77781111139, 44851.0, 44960.77519612381, 44996.0, 45047.0, 47782.283265751044, 47943.28085078727, 47997.0, 48012.279815802794, 48042.27936580954, 13568.1859088455, 13851.030427869953, 13853.515126970507, 13925.651858703412, 13930.0, 10871.891281087246, 10947.0, 10948.835767463494, 10952.616658416917, 10957.397343146204, 13446.798298025538, 13825.446982120602, 15020.324085416183, 15293.694126117416, 15428.845711542965, 5560.7219639017885, 5575.219469274305, 5821.796237131764, 5897.085951677497, 5933.199018132583, 749.1908738562448, 851.5827244650077, 856.1096459681986, 880.5421180985851, 888.595688961521, 25504.872475637872, 26995.130562415037, 27064.0, 27222.727772722414, 27528.58707119395, 922.4465320807597, 924.4869097650868, 928.9767755806024, 971.7910649210454, 989.0505115089451, 14159.50441734555, 14427.0, 14475.203863787592, 14493.0, 14736.778948315783, 329802.0, 333959.0, 334030.0, 334575.0, 335037.0, 40184.39723404151, 41202.0, 51186.0, 51833.0, 51960.740196299535, 1127.8533790607332, 1137.2835113878368, 1146.7993101207255, 1155.7110722319421, 1156.9942150289362, 26583.734162658126, 27504.0568658098, 27757.722422775514, 28193.43613127685, 28487.43025139527, 40151.79924100361, 40641.59358406496, 40678.79660601678, 40713.38929915994, 40713.38929916113, 239154.0, 240449.0, 240767.0, 240794.0, 241894.0, 24705.135320263704, 25070.874645626656, 25138.12016579411, 25204.369890752878, 25218.36954076163, 36083.458748118275, 36356.36516331494, 37443.25113497769, 39378.80310598428, 40305.596944030185, 31001.0, 31206.63107164953, 31310.0, 31532.842335788173, 32551.51172732458, 31318.217044574067, 31340.686593134687, 31426.52860207053, 31654.841725791222, 32795.672043279264, 30871.919482817993, 31373.21566960753, 31498.0, 31508.527372089895, 31755.20611984719, 27764.44471110607, 28296.292592685346, 28881.42237155203, 28894.71105289004, 29148.417031659672, 22231.221907233168, 22554.0, 22871.19950801714, 22922.885385573634, 23126.190583329502], "value": 16448.05268630433, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [341.1250143382223, 360.8538541890532, 361.2954738260392, 363.560092288331, 384.5327926569218, 12268.754624907497, 15512.379504819808, 15597.0, 16078.115703636295, 17066.914665426688, 14710.852891471082, 15117.470888518905, 15197.4680886169, 15280.0, 15493.84506154938, 3980.9203815923665, 4344.847930322439, 4470.128324976631, 4614.861554153364, 4616.215243408628, 4570.497245303014, 4644.6052085572765, 4649.465311489188, 4649.488556258809, 4651.558101980307, 4647.372604698357, 4648.767561621906, 4649.814007439715, 4651.162790697673, 4652.5114862939245, 4504.729716217021, 4639.976800115995, 4659.813607455698, 4667.346571479996, 4671.135839869635, 4539.84110556129, 4543.182227199136, 4548.545145485425, 4552.590266875997, 4557.0, 12204.633860984184, 12354.505819767199, 12480.625581232578, 12485.50057997679, 12503.374831258492, 1966.7148263501856, 1999.2502811445709, 2040.9897950510301, 2149.946251343729, 2251.3358559225067, 8873.778155546099, 8999.910000899972, 9126.26989840811, 9391.248700103975, 9477.810443791155, 1662.8420300071473, 1694.1105919392294, 1720.8623310135158, 1750.8861923975028, 1766.7173252279702, 64079.669642946115, 65309.0, 65805.0, 66318.3368166317, 66430.3356966429, 9305.25557955362, 9549.331546791796, 9605.47169905648, 9762.707118786448, 9993.700188994342, 13189.934050329686, 13204.537841175512, 13211.207327560378, 13218.471261149638, 13219.206847589176, 6722.428593569602, 6999.405050570758, 7017.508774385744, 7082.92917070833, 7094.716211351488, 6242.719077641517, 6346.333634968354, 6411.839704007344, 6706.96646516774, 6725.529212955046, 4194.475690538683, 4200.978995105005, 4260.126674031816, 4287.142571485715, 4310.913781724411, 1768.5136587438558, 1808.2495764257685, 1814.3286983816072, 1819.5352741043587, 1835.109971663742, 790.6205021589669, 827.611022819274, 846.4921047371528, 852.5864955496579, 914.8627705844177, 15476.690466190837, 15686.686266274839, 16044.278007489691, 16098.356065757473, 16359.59101022484, 770.603139383218, 773.091617349612, 796.3231253434534, 842.1914961636775, 845.3448577352601, 8650.669492245404, 8711.695090671796, 8900.243479304332, 9033.638654453749, 9378.296627752945, 331143.0, 337984.0, 338015.0, 338738.0, 340746.0, 67081.32918670848, 69865.30134698689, 70619.0, 71907.0, 71920.0, 884.5090974509209, 910.0399078971749, 920.0661328751323, 925.717656114878, 927.536231884058, 14345.928270358581, 14348.49780257686, 14525.564233073024, 14599.85400145985, 15029.473968411054, 35002.0, 35389.46915796317, 35713.0, 35845.82077089598, 37034.8148259257, 43222.0, 46814.0, 47699.284510731675, 47982.76008619935, 50228.0, 7568.5837278950185, 7647.311741943252, 7869.645865935932, 7969.1632378601735, 8014.078380986261, 11184.776304474028, 11470.254433461798, 12046.518139274682, 12202.633920982385, 12310.692232694255, 9879.555420005972, 9968.0, 10019.849702254327, 10649.414282214553, 10665.0, 9966.451845148586, 10140.797184056426, 10318.17454603645, 10505.264631475724, 10548.208884333859, 10213.642522511676, 10330.0, 10601.098906592717, 10625.8937410628, 10862.837057443987, 7576.924230757621, 7613.809654758676, 7704.8459030820195, 7704.92295077042, 7861.685532578633, 9215.76960575991, 10117.443540605043, 10135.087842094246, 10258.128059115059, 10441.0], "value": 7700.40788841861, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1214.4534959268321, 1311.2460335307198, 1326.1446367093229, 1378.6760111373826, 1637.148682685004, 6451.129097571827, 7153.785386438409, 7360.07999000125, 7365.3002964718335, 7514.135874374435, 7360.742374016911, 7405.740799072034, 7424.109106907165, 7427.25727427257, 7429.36850367718, 1818.6180902010594, 1873.110272620501, 2101.0650260633984, 2125.9255926042592, 2135.3166986564306, 2088.9450827332175, 2139.700441938122, 2144.2280778919576, 2159.114762947192, 2161.2867753641262, 2080.781517940617, 2106.841237319476, 2111.799379058987, 2111.873287602741, 2127.638301488743, 2021.6664250398667, 2038.0, 2040.9183632654676, 2047.0276618606192, 2047.9897600511977, 2053.4455696961736, 2060.7733149353558, 2075.5848830233863, 2080.334293026232, 2081.8230450411734, 7270.854582908312, 7307.671154798047, 7335.266473352675, 7409.110906691178, 7412.036435263435, 4426.291793313087, 4568.497465278817, 4577.198990176706, 4578.679492435531, 4578.862634120982, 2877.4820532304175, 2962.688917663636, 2967.9554806677916, 2974.8066375685726, 2985.6118704568266, 863.7236084452977, 920.5213289089636, 925.4401087342128, 939.6100618243421, 1578.1162548972611, 9621.855672164924, 10049.648262310784, 10051.648192313307, 10068.244881633907, 10070.9496452518, 3782.6028267032116, 3978.309218582091, 4021.718479706451, 4039.596040395937, 4043.6158564936286, 7889.763307100796, 7893.17121702213, 7898.763037108896, 7916.050073991158, 7917.841643167105, 4472.217361961643, 4474.194644963938, 4475.48531918827, 4478.596926276651, 4482.238019536687, 3635.236600313911, 3756.286305601927, 4001.239764444746, 4155.480564929384, 4159.9376009359885, 3171.5876935998635, 3180.6501284858646, 3213.052149615869, 3288.5067239914206, 3311.519829624691, 1006.0693858181153, 1021.2238698589126, 1029.804337175949, 1038.0916697889347, 1049.4490392543823, 720.2797202797203, 736.0909277042848, 756.9318761311508, 782.4444644302495, 792.5720111140035, 4624.676272660881, 4767.547083027107, 4787.8563643090765, 4805.711657300573, 4848.151573474698, 792.8810678398171, 822.7453133970743, 830.5307501261768, 832.8417600655856, 839.0644431458879, 3030.787844850883, 3064.6935306469177, 3100.6589275179713, 3104.7360974316985, 3223.1136437479413, 8842.734717958472, 9116.635334586676, 9123.954380228057, 9132.22376098039, 9144.451332920045, 9047.185753282236, 9130.315226357918, 9156.6795162169, 9158.90841091594, 9159.129882661136, 6863.897041544381, 7011.509194356346, 7027.8594428111155, 7031.08595882532, 7037.331453511908, 4555.4988951216, 4624.28323609841, 4666.766661666835, 4704.270838020112, 4790.496997815179, 7628.504147230412, 7981.72063977758, 8253.876191857007, 8288.544130072903, 8374.706885258987, 7921.762347129596, 8032.116467188722, 8208.876866847122, 8211.260986511243, 8212.096669366485, 5907.734151963258, 6653.434458071119, 6826.488013398915, 6835.0, 6849.24658287578, 6057.757689692539, 6194.535409844189, 6206.503479721701, 6208.1308616792785, 6488.772892948913, 5854.136270061739, 5977.5217982560425, 6054.212952316347, 6082.178905847831, 6172.043333283348, 5964.850878728066, 6008.188894499362, 6016.608920420159, 6064.848378790565, 6114.144019837137, 5690.5163061140265, 5705.2297939779255, 5754.762726013927, 5764.538836892954, 5768.855778605568, 4305.74165550068, 4340.348947657877, 4434.5122036576595, 4440.755758433188, 4542.227821270458, 4255.446731960798, 4261.190373828963, 4311.078167518081, 4370.978145109254, 4469.173202957463], "value": 3869.8114557154363, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [347.75309530233534, 350.33436470705664, 354.77294531499837, 361.9004773687234, 364.686369722039, 4836.637252206086, 4868.56182943535, 4869.028043642326, 4908.7300198489065, 4913.361263035809, 4806.759662016902, 4816.662833601649, 4872.805087796484, 4879.731614761186, 4885.1695211813985, 3651.963480365202, 3737.3085979093885, 3825.5218097737784, 3842.538895332564, 3857.42138679199, 3887.300285948528, 3901.6878649707955, 3903.8243279052367, 3904.297226499229, 3904.7852368119743, 3880.670143037831, 3883.106885416356, 3885.9611403885883, 3894.44222311075, 3902.073444916313, 3656.287024030315, 3767.9811600942094, 3773.754705944132, 3856.939341681032, 3895.6883449324127, 3482.1642805726697, 3490.110021944388, 3490.6263740656345, 3492.388831954398, 3506.8252135534626, 4719.976400118012, 4720.33915251865, 4733.668643194977, 4734.4081989751285, 4749.762511874393, 4268.0, 4396.252637051709, 4416.40378548895, 4423.292273236299, 4425.203463376591, 5064.8480545583325, 5074.264231686422, 5075.365579302587, 5077.26379674945, 5083.87290317745, 3795.772253664789, 3800.6959443244473, 3802.277567262239, 3806.3148633246005, 3809.9809500952597, 5064.822731204464, 5242.423333433319, 5311.760970756325, 5313.096773548467, 5314.362276526803, 3895.3962135868987, 3935.311320518898, 3935.4490371348033, 3936.6850651947775, 3939.4484772131923, 3980.8407663693706, 3988.74073185242, 3989.5490400803096, 3992.5208974923194, 3994.7603143811466, 4419.712718673276, 4424.535423780457, 4430.5126436092005, 4443.2224360736745, 4445.0, 4973.32860063894, 5054.014467178865, 5086.745662716849, 5108.642395032386, 5116.309298244763, 1342.6039318401095, 1348.514534767483, 1350.439567579463, 1351.5472316773992, 1352.4987264136996, 911.1935936696071, 952.5475399185332, 967.1005964453126, 968.8498282766183, 969.6703120938914, 4333.674974376871, 4418.182636212311, 4424.756638384855, 4537.818487260539, 4565.721597952579, 5244.763985620656, 5247.212918062245, 5254.264402983586, 5256.448072952361, 5258.50044245796, 4528.954710452919, 4534.206513860061, 4546.408966834357, 4555.362249285103, 4557.886052848706, 2579.3164811325014, 2739.315171207198, 2742.8491432971377, 2744.7118052604587, 2744.8490333031873, 4840.572031250789, 4842.346283251716, 4853.568197381781, 4867.758721525988, 4875.756212189376, 4932.8520144395725, 5269.815556455505, 5273.815416460406, 5277.604179686539, 5286.735663216824, 4736.313234581035, 4747.311639812208, 4778.450478194983, 4784.808607655655, 4795.20879054952, 5045.520675535819, 5053.938672878737, 5094.949050509447, 5107.624954396224, 5113.883323924277, 4846.345743324606, 5010.496850944774, 5019.222020586815, 5026.52248036436, 5029.321041659329, 4915.058551872062, 4927.384076990376, 4938.209886418156, 4952.678075925053, 4954.405471343462, 3657.6708096271464, 3667.963320366762, 3680.153564680085, 3684.1342284563643, 3691.686206672463, 5058.772355244097, 5269.8682532935445, 5272.261883336259, 5277.8416647500635, 5285.920711189412, 5064.9746751267485, 5072.82245121419, 5078.289039534394, 5086.18621020635, 5106.795728170831, 5098.470458862401, 5105.391801582489, 5111.131107711623, 5111.207762796773, 5128.282040514406, 4747.501512341154, 4981.925271121008, 4982.4768399317545, 4990.950090499048, 4996.425411077773, 4885.877853053702, 4942.925856112233, 4981.334985782589, 5096.23556466533, 5106.361704786902, 5000.67495612784, 5002.599792016557, 5015.724135172601, 5015.824446144367, 5031.899362012666], "value": 3884.6155580091618, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [552.9004779139755, 568.3946596874326, 593.9406059394062, 597.1371368372703, 612.6997771092166, 10922.508487118079, 11712.648620541377, 11792.17454778167, 13630.522931697393, 13764.58706238812, 12412.565560205394, 12959.157654752435, 13247.470101195964, 13270.071095023352, 13272.137311074774, 750.9324160825524, 767.6583920155533, 767.8118860879092, 768.3046842607449, 768.3776141325516, 609.6311731402504, 628.2931701835436, 754.9396048316149, 775.5889378629336, 780.910195327536, 813.800618848383, 814.2142832166958, 815.4536460571431, 816.5713000674634, 817.1705718695529, 809.522381794744, 810.0198759500981, 810.1695761844157, 810.2707563193144, 810.4529442626203, 468.7328222912941, 480.6635355251338, 580.8925348810484, 591.5415552946458, 596.257659214281, 12900.290484023375, 12939.805902911465, 12942.288174150419, 12948.0, 12956.157849739737, 11572.247803892804, 11717.296962182296, 12327.0, 12447.813282800766, 12481.687957801038, 10732.892671073267, 10817.350958942488, 10958.890411095947, 11104.44477776108, 11379.089672826154, 3827.617238276178, 3873.5351757788962, 3888.0862997195636, 3927.6072392760493, 3932.7247092703524, 61533.384666153215, 62535.37464625341, 62588.0, 65643.34356656468, 65659.67170164167, 9121.224695900923, 9289.442633442015, 9292.39599426035, 9440.763980900532, 10111.949440252898, 19284.22863085461, 19499.0, 20559.38321850347, 20578.691319630216, 20583.8970805145, 6758.628275444799, 6762.763303284361, 6768.526203165731, 6793.694283757242, 7082.29177082298, 3558.5195998540385, 3571.607123216444, 3572.071261471984, 3632.2735452909524, 3669.5779985301497, 2769.0862015534826, 2863.8568071596756, 2897.884084636633, 2916.7374936255837, 2947.292649764041, 484.5203248783679, 490.2768416585508, 490.68105731274244, 490.7595278313672, 492.5370152057055, 488.577380565808, 490.3845673679581, 495.57133079885637, 502.1764306537282, 502.791341593242, 7526.924730752732, 7816.84366312682, 7869.0, 8065.732624060268, 8148.0, 456.82183948260206, 508.15646027594016, 532.4967905329504, 552.8721408327049, 565.2369301443101, 3840.462335273064, 3871.825767840454, 3981.8407263709128, 3992.276845388397, 4037.4953130858644, 66254.66872665702, 67694.3230567688, 67990.66004669946, 71802.64098679474, 71968.64015679993, 58569.0, 60221.698891505264, 60491.69754151201, 60750.0, 61974.38025619686, 50923.0, 52573.211401829, 53202.2019669713, 53462.73268633632, 54156.0, 7530.736424225126, 7730.961345193238, 7772.533647981139, 7878.0, 8046.316063134699, 30762.69237307688, 34336.141596460286, 34450.82774586111, 35920.46119308265, 37298.98071914551, 44218.3367249498, 44607.776961114985, 45855.54144458513, 46123.538764613266, 48052.27921581109, 10087.59283747096, 10419.166466682798, 10766.838497422701, 10782.838257426301, 11317.434128293717, 11630.767384652429, 12266.141370104007, 12606.054545909277, 12965.805512917503, 13160.01299902492, 10895.564177432814, 11133.55465781392, 11506.597269095542, 11526.366049867338, 11641.126915481203, 10742.73143171427, 11144.832827507757, 11366.829497557708, 11507.482163302504, 11872.643820685393, 10234.385936843813, 10495.632652857113, 10611.787764244826, 10759.784804304027, 10869.836952445878, 9504.02311735562, 9557.13732614562, 9570.856437153308, 9830.606775729177, 10083.747906302402, 8987.0, 9959.900400995897, 10227.84658230142, 10241.692749217535, 10254.794904102026], "value": 6112.413127688369, "unit": "score"}], "name": "dromaeo_css", "value": 6158.947459934018, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 117.0, 115.0, 117.0, 116.0, 116.0, 115.0, 116.0, 115.0, 115.0], "value": 114.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 128.0, 126.0, 173.0, 127.0, 124.0, 128.0, 127.0, 142.0, 126.0], "value": 135.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 161.0, 161.0, 161.0, 161.0, 162.0, 160.0, 162.0, 161.0, 161.0], "value": 161.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 75.0, 111.0, 77.0, 76.0, 75.0, 76.0, 97.0, 76.0], "value": 81.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 115.0, 84.0, 85.0, 85.0, 99.0, 85.0, 84.0, 112.0], "value": 91.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [126.0, 127.0, 127.0, 127.0, 127.0, 126.0, 128.0, 126.0, 126.0, 127.0], "value": 126.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 148.0, 147.0, 147.0, 147.0, 147.0, 147.0, 146.0, 147.0, 148.0], "value": 147.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 95.0, 95.0, 95.0, 96.0, 96.0, 95.0, 96.0, 95.0], "value": 95.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 63.0, 64.0, 64.0, 64.0, 65.0, 64.0, 65.0, 64.0], "value": 64.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 66.0, 66.0, 66.0, 67.0, 66.0, 66.0, 66.0, 67.0], "value": 66.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 85.0, 93.0, 88.0, 90.0, 84.0, 95.0, 85.0, 84.0], "value": 87.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 153.0, 144.0, 137.0, 134.0, 135.0, 140.0, 155.0, 187.0, 132.0], "value": 145.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [180.0, 185.0, 176.0, 177.0, 174.0, 175.0, 179.0, 177.0, 178.0, 175.0], "value": 177.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 67.0, 67.0, 67.0, 67.0, 66.0, 72.0, 71.0, 67.0], "value": 68.0, "unit": "score"}], "name": "kraken", "value": 1562.1999999999998, "alertThreshold": 2.0}]} 12:52:41 INFO - Return code: 0 12:52:41 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:52:41 INFO - # TBPL SUCCESS # 12:52:41 INFO - Running post-action listener: _resource_record_post_action 12:52:41 INFO - [mozharness: 2016-12-20 20:52:41.654071Z] Finished run-tests step (success) 12:52:41 INFO - Running post-run listener: _resource_record_post_run 12:52:41 INFO - Total resource usage - Wall time: 1202s; CPU: 13.0%; Read bytes: 4096; Write bytes: 393699328; Read time: 12; Write time: 274164 12:52:41 INFO - TinderboxPrint: CPU usage
13.1% 12:52:41 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 12:52:41 INFO - TinderboxPrint: I/O write bytes / time
393,699,328 / 274,164 12:52:41 INFO - TinderboxPrint: CPU idle
8,336.1 (86.9%) 12:52:41 INFO - TinderboxPrint: CPU user
1,213.4 (12.6%) 12:52:41 INFO - TinderboxPrint: Swap in / out
0 / 0 12:52:41 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 110276608; Read time: 0; Write time: 61968 12:52:41 INFO - run-tests - Wall time: 1186s; CPU: 13.0%; Read bytes: 0; Write bytes: 283422720; Read time: 0; Write time: 212196 12:52:42 INFO - Running post-run listener: _upload_blobber_files 12:52:42 INFO - Blob upload gear active. 12:52:42 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:52:42 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 12:52:42 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'] 12:52:42 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 12:52:42 INFO - (blobuploader) - INFO - Open directory for files ... 12:52:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 12:52:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:52:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:52:43 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 12:52:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:52:43 INFO - (blobuploader) - INFO - Done attempting. 12:52:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 12:52:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:52:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:52:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 12:52:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:52:44 INFO - (blobuploader) - INFO - Done attempting. 12:52:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:52:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:52:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:52:48 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:52:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:52:48 INFO - (blobuploader) - INFO - Done attempting. 12:52:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 12:52:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:52:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:52:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 12:52:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:52:49 INFO - (blobuploader) - INFO - Done attempting. 12:52:49 INFO - (blobuploader) - INFO - Iteration through files over. 12:52:49 INFO - Return code: 0 12:52:49 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:52:49 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:52:49 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/2cd10e0e57f39115f576dc47e091d3354702a783ebc9cb154f2b1995f2f93acf31843b3eefeca3e1ca80f75747175b9dcdee56bf7e91e62ddfe2305ae19e5409", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9d892910b6b818d7d6ae403c60709453f5641603ad641cb0f5aa54a3b6d4fdf76afef1d3c2c9d17a43786ad99be1b474c85f0f25200cfe0f3e83e24a179a7459", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6576bb03b308735e0c7457526acc5708481b2ab3d97b25d0546332bf6f4553ca7816a474ffe1fdfe9d78f0ff67c3aee1989502f7875b5410df1fcd38727eb250", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6faab935fdee196e067ffe1b8fbbd3b00e23314aa0148f44e17c6f73d436b0ced97868fe251430d53c3ee41de0549f7e2c3fb3fddf989ee3a2735d68028bca8b"} 12:52:49 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:52:49 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:52:49 INFO - Contents: 12:52:49 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/2cd10e0e57f39115f576dc47e091d3354702a783ebc9cb154f2b1995f2f93acf31843b3eefeca3e1ca80f75747175b9dcdee56bf7e91e62ddfe2305ae19e5409", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9d892910b6b818d7d6ae403c60709453f5641603ad641cb0f5aa54a3b6d4fdf76afef1d3c2c9d17a43786ad99be1b474c85f0f25200cfe0f3e83e24a179a7459", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6576bb03b308735e0c7457526acc5708481b2ab3d97b25d0546332bf6f4553ca7816a474ffe1fdfe9d78f0ff67c3aee1989502f7875b5410df1fcd38727eb250", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6faab935fdee196e067ffe1b8fbbd3b00e23314aa0148f44e17c6f73d436b0ced97868fe251430d53c3ee41de0549f7e2c3fb3fddf989ee3a2735d68028bca8b"} 12:52:49 INFO - Running post-run listener: copy_logs_to_upload_dir 12:52:49 INFO - Copying logs to upload dir... 12:52:49 INFO - mkdir: /builds/slave/test/build/upload/logs 12:52:49 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1247.100727 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2016-12-20 12:52:49.884521) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-20 12:52:49.888912) ========= 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=826874ccc97bd7bf541b4875000001d5-1482265011.769691-1153358795 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/2cd10e0e57f39115f576dc47e091d3354702a783ebc9cb154f2b1995f2f93acf31843b3eefeca3e1ca80f75747175b9dcdee56bf7e91e62ddfe2305ae19e5409", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9d892910b6b818d7d6ae403c60709453f5641603ad641cb0f5aa54a3b6d4fdf76afef1d3c2c9d17a43786ad99be1b474c85f0f25200cfe0f3e83e24a179a7459", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6576bb03b308735e0c7457526acc5708481b2ab3d97b25d0546332bf6f4553ca7816a474ffe1fdfe9d78f0ff67c3aee1989502f7875b5410df1fcd38727eb250", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6faab935fdee196e067ffe1b8fbbd3b00e23314aa0148f44e17c6f73d436b0ced97868fe251430d53c3ee41de0549f7e2c3fb3fddf989ee3a2735d68028bca8b"} build_url:https://queue.taskcluster.net/v1/task/PlZqv_PrSZemwj-kudA5kQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011652 build_url: 'https://queue.taskcluster.net/v1/task/PlZqv_PrSZemwj-kudA5kQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/2cd10e0e57f39115f576dc47e091d3354702a783ebc9cb154f2b1995f2f93acf31843b3eefeca3e1ca80f75747175b9dcdee56bf7e91e62ddfe2305ae19e5409", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9d892910b6b818d7d6ae403c60709453f5641603ad641cb0f5aa54a3b6d4fdf76afef1d3c2c9d17a43786ad99be1b474c85f0f25200cfe0f3e83e24a179a7459", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6576bb03b308735e0c7457526acc5708481b2ab3d97b25d0546332bf6f4553ca7816a474ffe1fdfe9d78f0ff67c3aee1989502f7875b5410df1fcd38727eb250", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6faab935fdee196e067ffe1b8fbbd3b00e23314aa0148f44e17c6f73d436b0ced97868fe251430d53c3ee41de0549f7e2c3fb3fddf989ee3a2735d68028bca8b"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-20 12:52:49.919858) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-20 12:52:49.920451) ========= 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=826874ccc97bd7bf541b4875000001d5-1482265011.769691-1153358795 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004580 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-20 12:52:49.971402) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-20 12:52:49.971925) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-20 12:52:49.972303) ========= ========= Total master_lag: 0.15 =========