builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-034 starttime: 1482583797.55 results: success (0) buildid: 20161224030205 builduid: 1fc6e3ef70f94037a4315ec4b10310cf revision: da22155a2dc30dafc93d70f38f6bb8a49248c80f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-24 04:49:57.554134) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-24 04:49:57.554568) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-24 04:49:57.554847) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-24 04:49:57.569171) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-24 04:49:57.569430) ========= 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=d6bbd613908ccac0c6c9cb47000001f7-1482576190.421265-884628426 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-12-24 04:49:58-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 88.3M=0s 2016-12-24 04:49:58 (88.3 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.108086 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-24 04:49:57.690986) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-24 04:49:57.691314) ========= 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=d6bbd613908ccac0c6c9cb47000001f7-1482576190.421265-884628426 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017264 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-24 04:49:57.741234) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-24 04:49:57.741689) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev da22155a2dc30dafc93d70f38f6bb8a49248c80f --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 da22155a2dc30dafc93d70f38f6bb8a49248c80f --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=d6bbd613908ccac0c6c9cb47000001f7-1482576190.421265-884628426 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-12-24 04:49:58,460 truncating revision to first 12 chars 2016-12-24 04:49:58,460 Setting DEBUG logging. 2016-12-24 04:49:58,461 attempt 1/10 2016-12-24 04:49:58,461 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/da22155a2dc3?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-24 04:49:58,769 unpacking tar archive at: mozilla-central-da22155a2dc3/testing/mozharness/ program finished with exit code 0 elapsedTime=0.587451 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-24 04:49:58.343189) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-24 04:49:58.343482) ========= script_repo_revision: da22155a2dc30dafc93d70f38f6bb8a49248c80f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-24 04:49:58.343887) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-24 04:49:58.344160) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-24 04:49:58.360351) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2016-12-24 04:49:58.360659) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=d6bbd613908ccac0c6c9cb47000001f7-1482576190.421265-884628426 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 04:49:59 INFO - MultiFileLogger online at 20161224 04:49:59 in /builds/slave/test 04:49:59 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 04:49:59 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:49:59 INFO - {'append_to_log': False, 04:49:59 INFO - 'base_work_dir': '/builds/slave/test', 04:49:59 INFO - 'blob_upload_branch': 'Firefox', 04:49:59 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:49:59 INFO - 'branch': 'Firefox', 04:49:59 INFO - 'buildbot_json_path': 'buildprops.json', 04:49:59 INFO - 'config_files': ('talos/linux_config.py',), 04:49:59 INFO - 'default_actions': ('clobber', 04:49:59 INFO - 'read-buildbot-config', 04:49:59 INFO - 'download-and-extract', 04:49:59 INFO - 'populate-webroot', 04:49:59 INFO - 'create-virtualenv', 04:49:59 INFO - 'install', 04:49:59 INFO - 'run-tests'), 04:49:59 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:49:59 INFO - 'download_minidump_stackwalk': True, 04:49:59 INFO - 'download_symbols': 'ondemand', 04:49:59 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:49:59 INFO - 'tooltool.py': '/tools/tooltool.py', 04:49:59 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:49:59 INFO - '/tools/misc-python/virtualenv.py')}, 04:49:59 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:49:59 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:49:59 INFO - 'installer_path': 'installer.exe', 04:49:59 INFO - 'log_level': 'info', 04:49:59 INFO - 'log_name': 'talos', 04:49:59 INFO - 'log_to_console': True, 04:49:59 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 04:49:59 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 04:49:59 INFO - 'opt_config_files': (), 04:49:59 INFO - 'pip_index': False, 04:49:59 INFO - 'sps_profile': False, 04:49:59 INFO - 'sps_profile_interval': 0, 04:49:59 INFO - 'suite': 'dromaeojs', 04:49:59 INFO - 'system_bits': '32', 04:49:59 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:49:59 INFO - 'title': 'talos-linux64-ix-034', 04:49:59 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:49:59 INFO - 'use_talos_json': True, 04:49:59 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:49:59 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:49:59 INFO - 'work_dir': 'build'} 04:49:59 INFO - [mozharness: 2016-12-24 12:49:59.178620Z] Running clobber step. 04:49:59 INFO - Running pre-action listener: _resource_record_pre_action 04:49:59 INFO - Running main action method: clobber 04:49:59 INFO - rmtree: /builds/slave/test/build 04:49:59 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:49:59 INFO - Running post-action listener: _resource_record_post_action 04:49:59 INFO - [mozharness: 2016-12-24 12:49:59.642263Z] Finished clobber step (success) 04:49:59 INFO - [mozharness: 2016-12-24 12:49:59.642364Z] Running read-buildbot-config step. 04:49:59 INFO - Running pre-action listener: _resource_record_pre_action 04:49:59 INFO - Running main action method: read_buildbot_config 04:49:59 INFO - Using buildbot properties: 04:49:59 INFO - { 04:49:59 INFO - "project": "", 04:49:59 INFO - "product": "firefox", 04:49:59 INFO - "script_repo_revision": "production", 04:49:59 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 04:49:59 INFO - "repository": "", 04:49:59 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 04:49:59 INFO - "buildid": "20161224030205", 04:49:59 INFO - "stage_platform": "linux64-pgo", 04:49:59 INFO - "basedir": "/builds/slave/test", 04:49:59 INFO - "nightly_build": "True", 04:49:59 INFO - "buildnumber": 129, 04:49:59 INFO - "slavename": "talos-linux64-ix-034", 04:49:59 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 04:49:59 INFO - "platform": "ubuntu64_hw", 04:49:59 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 04:49:59 INFO - "branch": "mozilla-central", 04:49:59 INFO - "repo_path": "mozilla-central", 04:49:59 INFO - "revision": "da22155a2dc30dafc93d70f38f6bb8a49248c80f", 04:49:59 INFO - "pgo_build": "True", 04:49:59 INFO - "builduid": "1fc6e3ef70f94037a4315ec4b10310cf", 04:49:59 INFO - "slavebuilddir": "test" 04:49:59 INFO - } 04:49:59 INFO - Found installer url https://queue.taskcluster.net/v1/task/dx8uU0KPQWW9lHCdmv6MUg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2. 04:49:59 INFO - Running post-action listener: _resource_record_post_action 04:49:59 INFO - [mozharness: 2016-12-24 12:49:59.644927Z] Finished read-buildbot-config step (success) 04:49:59 INFO - [mozharness: 2016-12-24 12:49:59.645018Z] Running download-and-extract step. 04:49:59 INFO - Running pre-action listener: _resource_record_pre_action 04:49:59 INFO - Running main action method: download_and_extract 04:49:59 INFO - mkdir: /builds/slave/test/build/tests 04:49:59 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')]} 04:49:59 INFO - trying https://queue.taskcluster.net/v1/task/dx8uU0KPQWW9lHCdmv6MUg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 04:49:59 INFO - Downloading https://queue.taskcluster.net/v1/task/dx8uU0KPQWW9lHCdmv6MUg/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 04:49:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dx8uU0KPQWW9lHCdmv6MUg/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 04:50:00 INFO - Downloaded 1361 bytes. 04:50:00 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 04:50:00 INFO - Using the following test package requirements: 04:50:00 INFO - {u'common': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 04:50:00 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 04:50:00 INFO - u'firefox-53.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 04:50:00 INFO - u'gtest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 04:50:00 INFO - u'firefox-53.0a1.en-US.linux-x86_64.gtest.tests.zip'], 04:50:00 INFO - u'jittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 04:50:00 INFO - u'jsshell-linux-x86_64.zip'], 04:50:00 INFO - u'mochitest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 04:50:00 INFO - u'firefox-53.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 04:50:00 INFO - u'mozbase': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 04:50:00 INFO - u'reftest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 04:50:00 INFO - u'firefox-53.0a1.en-US.linux-x86_64.reftest.tests.zip'], 04:50:00 INFO - u'talos': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 04:50:00 INFO - u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'], 04:50:00 INFO - u'web-platform': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 04:50:00 INFO - u'firefox-53.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 04:50:00 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 04:50:00 INFO - u'firefox-53.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 04:50:00 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 04:50:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dx8uU0KPQWW9lHCdmv6MUg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 04:50:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dx8uU0KPQWW9lHCdmv6MUg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 04:50:00 INFO - Fetch https://queue.taskcluster.net/v1/task/dx8uU0KPQWW9lHCdmv6MUg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 04:50:01 INFO - Expected file size: 21825836 04:50:01 INFO - Obtained file size: 21825836 04:50:05 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 04:50:05 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dx8uU0KPQWW9lHCdmv6MUg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 04:50:05 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dx8uU0KPQWW9lHCdmv6MUg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 04:50:05 INFO - Fetch https://queue.taskcluster.net/v1/task/dx8uU0KPQWW9lHCdmv6MUg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 04:50:05 INFO - Expected file size: 21825836 04:50:05 INFO - Obtained file size: 21825836 04:50:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dx8uU0KPQWW9lHCdmv6MUg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip 04:50:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dx8uU0KPQWW9lHCdmv6MUg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 04:50:10 INFO - Fetch https://queue.taskcluster.net/v1/task/dx8uU0KPQWW9lHCdmv6MUg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip into memory 04:50:11 INFO - Expected file size: 14409776 04:50:11 INFO - Obtained file size: 14409776 04:50:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:50:11 INFO - trying https://queue.taskcluster.net/v1/task/dx8uU0KPQWW9lHCdmv6MUg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 04:50:11 INFO - Downloading https://queue.taskcluster.net/v1/task/dx8uU0KPQWW9lHCdmv6MUg/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 04:50:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dx8uU0KPQWW9lHCdmv6MUg/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 04:50:12 INFO - Downloaded 61390127 bytes. 04:50:12 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/dx8uU0KPQWW9lHCdmv6MUg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 04:50:12 INFO - mkdir: /builds/slave/test/properties 04:50:12 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:50:12 INFO - Writing to file /builds/slave/test/properties/build_url 04:50:12 INFO - Contents: 04:50:12 INFO - build_url:https://queue.taskcluster.net/v1/task/dx8uU0KPQWW9lHCdmv6MUg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 04:50:13 INFO - Running post-action listener: _resource_record_post_action 04:50:13 INFO - Running post-action listener: set_extra_try_arguments 04:50:13 INFO - [mozharness: 2016-12-24 12:50:13.239664Z] Finished download-and-extract step (success) 04:50:13 INFO - [mozharness: 2016-12-24 12:50:13.239963Z] Running populate-webroot step. 04:50:13 INFO - Running pre-action listener: _resource_record_pre_action 04:50:13 INFO - Running main action method: populate_webroot 04:50:13 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:50:13 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:50:13 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:50:13 INFO - '--tppagecycles', 04:50:13 INFO - '7'], 04:50:13 INFO - 'tests': ['tsvgm']}}, 04:50:13 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:50:13 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:50:13 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:50:13 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:50:13 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:50:13 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:50:13 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 04:50:13 INFO - 'talos_options': ['--disable-e10s'], 04:50:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:50:13 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 04:50:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:50:13 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 04:50:13 INFO - 'talos_options': ['--disable-e10s'], 04:50:13 INFO - 'tests': ['damp', 'tps']}, 04:50:13 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 04:50:13 INFO - 'tests': ['damp', 'tps']}, 04:50:13 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:50:13 INFO - 'tests': ['dromaeo_dom']}, 04:50:13 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:50:13 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:50:13 INFO - 'tests': ['basic_compositor_video']}, 04:50:13 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:50:13 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:50:13 INFO - 'tests': ['a11yr', 04:50:13 INFO - 'ts_paint', 04:50:13 INFO - 'tpaint', 04:50:13 INFO - 'sessionrestore', 04:50:13 INFO - 'sessionrestore_no_auto_restore', 04:50:13 INFO - 'tabpaint']}, 04:50:13 INFO - 'other-e10s': {'tests': ['a11yr', 04:50:13 INFO - 'ts_paint', 04:50:13 INFO - 'tpaint', 04:50:13 INFO - 'sessionrestore', 04:50:13 INFO - 'sessionrestore_no_auto_restore', 04:50:13 INFO - 'tabpaint']}, 04:50:13 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:50:13 INFO - 'ts_paint', 04:50:13 INFO - 'tpaint', 04:50:13 INFO - 'sessionrestore', 04:50:13 INFO - 'sessionrestore_no_auto_restore', 04:50:13 INFO - 'tabpaint']}, 04:50:13 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:50:13 INFO - 'ts_paint', 04:50:13 INFO - 'tpaint', 04:50:13 INFO - 'sessionrestore', 04:50:13 INFO - 'sessionrestore_no_auto_restore', 04:50:13 INFO - 'tabpaint']}, 04:50:13 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:50:13 INFO - 'tests': ['a11yr', 04:50:13 INFO - 'ts_paint', 04:50:13 INFO - 'tpaint', 04:50:13 INFO - 'sessionrestore', 04:50:13 INFO - 'sessionrestore_no_auto_restore', 04:50:13 INFO - 'tabpaint']}, 04:50:13 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:50:13 INFO - 'tests': ['a11yr', 04:50:13 INFO - 'ts_paint', 04:50:13 INFO - 'tpaint', 04:50:13 INFO - 'sessionrestore', 04:50:13 INFO - 'sessionrestore_no_auto_restore', 04:50:13 INFO - 'tabpaint']}, 04:50:13 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:50:13 INFO - 'tests': ['tsvgx', 04:50:13 INFO - 'tsvgr_opacity', 04:50:13 INFO - 'tart', 04:50:13 INFO - 'tscrollx', 04:50:13 INFO - 'cart', 04:50:13 INFO - 'tsvg_static']}, 04:50:13 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:50:13 INFO - 'tsvgr_opacity', 04:50:13 INFO - 'tart', 04:50:13 INFO - 'tscrollx', 04:50:13 INFO - 'cart', 04:50:13 INFO - 'tsvg_static']}, 04:50:13 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 04:50:13 INFO - 'talos_options': ['--disable-e10s'], 04:50:13 INFO - 'tests': ['tp5o']}, 04:50:13 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 04:50:13 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 04:50:13 INFO - 'talos_options': ['--disable-e10s', 04:50:13 INFO - '--xperf_path', 04:50:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:50:13 INFO - 'tests': ['tp5n']}, 04:50:13 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 04:50:13 INFO - 'talos_options': ['--xperf_path', 04:50:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:50:13 INFO - 'tests': ['tp5n']}}, 04:50:13 INFO - 'talos.zip': {'path': '', 04:50:13 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:50:13 INFO - Running post-action listener: _resource_record_post_action 04:50:13 INFO - [mozharness: 2016-12-24 12:50:13.261588Z] Finished populate-webroot step (success) 04:50:13 INFO - [mozharness: 2016-12-24 12:50:13.261682Z] Running create-virtualenv step. 04:50:13 INFO - Running pre-action listener: _resource_record_pre_action 04:50:13 INFO - Running main action method: create_virtualenv 04:50:13 INFO - Creating virtualenv /builds/slave/test/build/venv 04:50:13 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 04:50:13 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:50:13 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:50:13 INFO - Using real prefix '/usr' 04:50:13 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:50:13 INFO - Installing distribute.............................................................................................................................................................................................done. 04:50:15 INFO - Installing pip.................done. 04:50:15 INFO - Return code: 0 04:50:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 04:50:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 04:50:16 INFO - Reading from file tmpfile_stdout 04:50:16 INFO - Output received: 04:50:16 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) 04:50:16 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:50:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:50:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:50:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:50:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:50:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:50:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:50:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x253b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2707a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28acc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28aa3f0>, '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 0x28aacf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28ab1a0>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1482576190.421265-884628426', '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 04:50:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 04:50:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 04:50:16 INFO - Using env: {'DISPLAY': ':0', 04:50:16 INFO - 'HOME': '/home/cltbld', 04:50:16 INFO - 'LANG': 'en_US.UTF-8', 04:50:16 INFO - 'LANGUAGE': 'en_US:en', 04:50:16 INFO - 'LOGNAME': 'cltbld', 04:50:16 INFO - 'MAIL': '/var/mail/cltbld', 04:50:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:50:16 INFO - 'MOZ_NO_REMOTE': '1', 04:50:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:50:16 INFO - 'NO_EM_RESTART': '1', 04:50:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:50:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:50:16 INFO - 'PWD': '/builds/slave/test', 04:50:16 INFO - 'SHELL': '/bin/bash', 04:50:16 INFO - 'SHLVL': '1', 04:50:16 INFO - 'TERM': 'linux', 04:50:16 INFO - 'TMOUT': '86400', 04:50:16 INFO - 'USER': 'cltbld', 04:50:16 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1482576190.421265-884628426', 04:50:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:50:16 INFO - '_': '/tools/buildbot/bin/python'} 04:50:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:50:16 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 04:50:16 INFO - Cleaning up... 04:50:16 INFO - Return code: 0 04:50:16 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:50:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:50:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:50:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:50:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:50:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:50:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:50:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x253b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2707a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28acc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28aa3f0>, '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 0x28aacf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28ab1a0>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1482576190.421265-884628426', '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 04:50:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 04:50:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 04:50:16 INFO - Using env: {'DISPLAY': ':0', 04:50:16 INFO - 'HOME': '/home/cltbld', 04:50:16 INFO - 'LANG': 'en_US.UTF-8', 04:50:16 INFO - 'LANGUAGE': 'en_US:en', 04:50:16 INFO - 'LOGNAME': 'cltbld', 04:50:16 INFO - 'MAIL': '/var/mail/cltbld', 04:50:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:50:16 INFO - 'MOZ_NO_REMOTE': '1', 04:50:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:50:16 INFO - 'NO_EM_RESTART': '1', 04:50:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:50:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:50:16 INFO - 'PWD': '/builds/slave/test', 04:50:16 INFO - 'SHELL': '/bin/bash', 04:50:16 INFO - 'SHLVL': '1', 04:50:16 INFO - 'TERM': 'linux', 04:50:16 INFO - 'TMOUT': '86400', 04:50:16 INFO - 'USER': 'cltbld', 04:50:16 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1482576190.421265-884628426', 04:50:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:50:16 INFO - '_': '/tools/buildbot/bin/python'} 04:50:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:50:16 INFO - Downloading/unpacking psutil>=3.1.1 04:50:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:50:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:50:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:50:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:50:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:50:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:50:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:50:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:50:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:50:18 INFO - Installing collected packages: psutil 04:50:18 INFO - Running setup.py install for psutil 04:50:18 INFO - building 'psutil._psutil_linux' extension 04:50:18 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 04:50:18 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 04:50:18 INFO - building 'psutil._psutil_posix' extension 04:50:18 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 04:50:19 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 04:50:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:50:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:50:19 INFO - Successfully installed psutil 04:50:19 INFO - Cleaning up... 04:50:19 INFO - Return code: 0 04:50:19 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:50: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')]} 04:50:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:50:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:50: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')]} 04:50:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:50:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:50: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x253b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2707a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28acc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28aa3f0>, '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 0x28aacf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28ab1a0>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1482576190.421265-884628426', '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 04:50: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 04:50: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 mozsystemmonitor==0.3 04:50:19 INFO - Using env: {'DISPLAY': ':0', 04:50:19 INFO - 'HOME': '/home/cltbld', 04:50:19 INFO - 'LANG': 'en_US.UTF-8', 04:50:19 INFO - 'LANGUAGE': 'en_US:en', 04:50:19 INFO - 'LOGNAME': 'cltbld', 04:50:19 INFO - 'MAIL': '/var/mail/cltbld', 04:50:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:50:19 INFO - 'MOZ_NO_REMOTE': '1', 04:50:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:50:19 INFO - 'NO_EM_RESTART': '1', 04:50:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:50:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:50:19 INFO - 'PWD': '/builds/slave/test', 04:50:19 INFO - 'SHELL': '/bin/bash', 04:50:19 INFO - 'SHLVL': '1', 04:50:19 INFO - 'TERM': 'linux', 04:50:19 INFO - 'TMOUT': '86400', 04:50:19 INFO - 'USER': 'cltbld', 04:50:19 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1482576190.421265-884628426', 04:50:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:50:19 INFO - '_': '/tools/buildbot/bin/python'} 04:50:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:50:19 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:50:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:50:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:50:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:50:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:50:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:50:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:50:21 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:50:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:50:21 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:50:21 INFO - Installing collected packages: mozsystemmonitor 04:50:21 INFO - Running setup.py install for mozsystemmonitor 04:50:21 INFO - Successfully installed mozsystemmonitor 04:50:21 INFO - Cleaning up... 04:50:21 INFO - Return code: 0 04:50:21 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:50:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:50:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:50:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:50:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:50:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:50:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:50:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x253b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2707a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28acc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28aa3f0>, '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 0x28aacf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28ab1a0>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1482576190.421265-884628426', '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 04:50:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 04:50:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 04:50:21 INFO - Using env: {'DISPLAY': ':0', 04:50:21 INFO - 'HOME': '/home/cltbld', 04:50:21 INFO - 'LANG': 'en_US.UTF-8', 04:50:21 INFO - 'LANGUAGE': 'en_US:en', 04:50:21 INFO - 'LOGNAME': 'cltbld', 04:50:21 INFO - 'MAIL': '/var/mail/cltbld', 04:50:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:50:21 INFO - 'MOZ_NO_REMOTE': '1', 04:50:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:50:21 INFO - 'NO_EM_RESTART': '1', 04:50:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:50:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:50:21 INFO - 'PWD': '/builds/slave/test', 04:50:21 INFO - 'SHELL': '/bin/bash', 04:50:21 INFO - 'SHLVL': '1', 04:50:21 INFO - 'TERM': 'linux', 04:50:21 INFO - 'TMOUT': '86400', 04:50:21 INFO - 'USER': 'cltbld', 04:50:21 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1482576190.421265-884628426', 04:50:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:50:21 INFO - '_': '/tools/buildbot/bin/python'} 04:50:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:50:21 INFO - Downloading/unpacking jsonschema==2.5.1 04:50:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:50:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:50:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:50:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:50:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:50:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:50:23 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:50:23 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:50: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 04:50: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 04:50: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 04:50: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 04:50: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 04:50: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 04:50:24 INFO - Downloading functools32-3.2.3-2.tar.gz 04:50:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:50:24 INFO - warning: no files found matching '*.txt' 04:50:24 INFO - no previously-included directories found matching 'build' 04:50:24 INFO - no previously-included directories found matching 'dist' 04:50:24 INFO - no previously-included directories found matching '.git*' 04:50:24 INFO - Installing collected packages: jsonschema, functools32 04:50:24 INFO - Running setup.py install for functools32 04:50:24 INFO - warning: no files found matching '*.txt' 04:50:24 INFO - no previously-included directories found matching 'build' 04:50:24 INFO - no previously-included directories found matching 'dist' 04:50:24 INFO - no previously-included directories found matching '.git*' 04:50:24 INFO - Successfully installed jsonschema functools32 04:50:24 INFO - Cleaning up... 04:50:24 INFO - Return code: 0 04:50:24 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:50:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:50:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:50:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:50:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:50:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:50:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:50:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x253b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2707a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28acc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28aa3f0>, '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 0x28aacf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28ab1a0>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1482576190.421265-884628426', '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 04:50:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 04:50:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 04:50:24 INFO - Using env: {'DISPLAY': ':0', 04:50:24 INFO - 'HOME': '/home/cltbld', 04:50:24 INFO - 'LANG': 'en_US.UTF-8', 04:50:24 INFO - 'LANGUAGE': 'en_US:en', 04:50:24 INFO - 'LOGNAME': 'cltbld', 04:50:24 INFO - 'MAIL': '/var/mail/cltbld', 04:50:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:50:24 INFO - 'MOZ_NO_REMOTE': '1', 04:50:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:50:24 INFO - 'NO_EM_RESTART': '1', 04:50:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:50:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:50:24 INFO - 'PWD': '/builds/slave/test', 04:50:24 INFO - 'SHELL': '/bin/bash', 04:50:24 INFO - 'SHLVL': '1', 04:50:24 INFO - 'TERM': 'linux', 04:50:24 INFO - 'TMOUT': '86400', 04:50:24 INFO - 'USER': 'cltbld', 04:50:24 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1482576190.421265-884628426', 04:50:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:50:24 INFO - '_': '/tools/buildbot/bin/python'} 04:50:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:50:24 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:50:24 INFO - Cleaning up... 04:50:24 INFO - Return code: 0 04:50:24 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:50:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:50:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:50:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:50:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:50:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:50:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:50:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x253b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2707a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28acc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28aa3f0>, '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 0x28aacf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28ab1a0>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1482576190.421265-884628426', '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 04:50:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 04:50:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 04:50:24 INFO - Using env: {'DISPLAY': ':0', 04:50:24 INFO - 'HOME': '/home/cltbld', 04:50:24 INFO - 'LANG': 'en_US.UTF-8', 04:50:24 INFO - 'LANGUAGE': 'en_US:en', 04:50:24 INFO - 'LOGNAME': 'cltbld', 04:50:24 INFO - 'MAIL': '/var/mail/cltbld', 04:50:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:50:24 INFO - 'MOZ_NO_REMOTE': '1', 04:50:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:50:24 INFO - 'NO_EM_RESTART': '1', 04:50:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:50:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:50:24 INFO - 'PWD': '/builds/slave/test', 04:50:24 INFO - 'SHELL': '/bin/bash', 04:50:24 INFO - 'SHLVL': '1', 04:50:24 INFO - 'TERM': 'linux', 04:50:24 INFO - 'TMOUT': '86400', 04:50:24 INFO - 'USER': 'cltbld', 04:50:24 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1482576190.421265-884628426', 04:50:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:50:24 INFO - '_': '/tools/buildbot/bin/python'} 04:50:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:50:24 INFO - Downloading/unpacking blobuploader==1.2.4 04:50:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:50:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:50:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:50:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:50:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:50:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:50:26 INFO - Downloading blobuploader-1.2.4.tar.gz 04:50:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:50:26 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:50:26 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 04:50:26 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 04:50:26 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 04:50:26 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 04:50:26 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 04:50:26 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 04:50:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:50:27 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:50: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 04:50: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 04:50: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 04:50: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 04:50: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 04:50: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 04:50:27 INFO - Downloading docopt-0.6.1.tar.gz 04:50:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:50:27 INFO - Installing collected packages: blobuploader, requests, docopt 04:50:27 INFO - Running setup.py install for blobuploader 04:50:27 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:50:27 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:50:27 INFO - Running setup.py install for requests 04:50:28 INFO - Running setup.py install for docopt 04:50:28 INFO - Successfully installed blobuploader requests docopt 04:50:28 INFO - Cleaning up... 04:50:28 INFO - Return code: 0 04:50:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:50: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')]} 04:50:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:50:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:50: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')]} 04:50:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:50:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:50:28 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 0x253b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2707a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28acc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28aa3f0>, '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 0x28aacf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28ab1a0>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1482576190.421265-884628426', '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 04:50:28 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 04:50:28 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 04:50:28 INFO - Using env: {'DISPLAY': ':0', 04:50:28 INFO - 'HOME': '/home/cltbld', 04:50:28 INFO - 'LANG': 'en_US.UTF-8', 04:50:28 INFO - 'LANGUAGE': 'en_US:en', 04:50:28 INFO - 'LOGNAME': 'cltbld', 04:50:28 INFO - 'MAIL': '/var/mail/cltbld', 04:50:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:50:28 INFO - 'MOZ_NO_REMOTE': '1', 04:50:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:50:28 INFO - 'NO_EM_RESTART': '1', 04:50:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:50:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:50:28 INFO - 'PWD': '/builds/slave/test', 04:50:28 INFO - 'SHELL': '/bin/bash', 04:50:28 INFO - 'SHLVL': '1', 04:50:28 INFO - 'TERM': 'linux', 04:50:28 INFO - 'TMOUT': '86400', 04:50:28 INFO - 'USER': 'cltbld', 04:50:28 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1482576190.421265-884628426', 04:50:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:50:28 INFO - '_': '/tools/buildbot/bin/python'} 04:50:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:50:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:50:28 INFO - Running setup.py (path:/tmp/pip-N6Pwbz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:50:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:50:28 INFO - Running setup.py (path:/tmp/pip-wWNqy5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:50:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:50:28 INFO - Running setup.py (path:/tmp/pip-GI510M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:50:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:50:28 INFO - Running setup.py (path:/tmp/pip-qQXAAk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:50:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:50:28 INFO - Running setup.py (path:/tmp/pip-8AaZrk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:50:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:50:28 INFO - Running setup.py (path:/tmp/pip-kWeSDR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:50:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:50:28 INFO - Running setup.py (path:/tmp/pip-o0wXGO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:50:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:50:28 INFO - Running setup.py (path:/tmp/pip-a_Rd3I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:50:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:50:29 INFO - Running setup.py (path:/tmp/pip-shEYT3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:50:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:50:29 INFO - Running setup.py (path:/tmp/pip-PVU24I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:50:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:50:29 INFO - Running setup.py (path:/tmp/pip-WE5_k9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:50:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:50:29 INFO - Running setup.py (path:/tmp/pip-Y8cVf4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:50:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:50:29 INFO - Running setup.py (path:/tmp/pip-b7nLbe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:50:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:50:29 INFO - Running setup.py (path:/tmp/pip-uBFDlE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:50:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:50:29 INFO - Running setup.py (path:/tmp/pip-o2IMC_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:50:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:50:29 INFO - Running setup.py (path:/tmp/pip-7jIX_d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:50:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:50:29 INFO - Running setup.py (path:/tmp/pip-OLPFYX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:50:29 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:50:29 INFO - Running setup.py install for manifestparser 04:50:29 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:50:29 INFO - Running setup.py install for mozcrash 04:50:29 INFO - Running setup.py install for mozdebug 04:50:29 INFO - Running setup.py install for mozdevice 04:50:30 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:50:30 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:50:30 INFO - Running setup.py install for mozfile 04:50:30 INFO - Running setup.py install for mozhttpd 04:50:30 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:50:30 INFO - Running setup.py install for mozinfo 04:50:30 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:50:30 INFO - Running setup.py install for mozInstall 04:50:30 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:50:30 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:50:30 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:50:30 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:50:30 INFO - Running setup.py install for mozleak 04:50:30 INFO - Running setup.py install for mozlog 04:50:30 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:50:30 INFO - Running setup.py install for moznetwork 04:50:30 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:50:30 INFO - Running setup.py install for mozprocess 04:50:30 INFO - Running setup.py install for mozprofile 04:50:31 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:50:31 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:50:31 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:50:31 INFO - Running setup.py install for mozrunner 04:50:31 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:50:31 INFO - Running setup.py install for mozscreenshot 04:50:31 INFO - Running setup.py install for moztest 04:50:31 INFO - Running setup.py install for mozversion 04:50:31 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:50:31 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:50:31 INFO - Cleaning up... 04:50:31 INFO - Return code: 0 04:50:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:50: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')]} 04:50:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:50:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:50: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')]} 04:50:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:50:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:50:31 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 0x253b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2707a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28acc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28aa3f0>, '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 0x28aacf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28ab1a0>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1482576190.421265-884628426', '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 04:50:31 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 04:50:31 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 04:50:31 INFO - Using env: {'DISPLAY': ':0', 04:50:31 INFO - 'HOME': '/home/cltbld', 04:50:31 INFO - 'LANG': 'en_US.UTF-8', 04:50:31 INFO - 'LANGUAGE': 'en_US:en', 04:50:31 INFO - 'LOGNAME': 'cltbld', 04:50:31 INFO - 'MAIL': '/var/mail/cltbld', 04:50:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:50:31 INFO - 'MOZ_NO_REMOTE': '1', 04:50:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:50:31 INFO - 'NO_EM_RESTART': '1', 04:50:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:50:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:50:31 INFO - 'PWD': '/builds/slave/test', 04:50:31 INFO - 'SHELL': '/bin/bash', 04:50:31 INFO - 'SHLVL': '1', 04:50:31 INFO - 'TERM': 'linux', 04:50:31 INFO - 'TMOUT': '86400', 04:50:31 INFO - 'USER': 'cltbld', 04:50:31 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1482576190.421265-884628426', 04:50:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:50:31 INFO - '_': '/tools/buildbot/bin/python'} 04:50:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:50:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:50:31 INFO - Running setup.py (path:/tmp/pip-dM7RJN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:50:31 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)) 04:50:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:50:31 INFO - Running setup.py (path:/tmp/pip-EPFPwn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:50:31 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)) 04:50:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:50:31 INFO - Running setup.py (path:/tmp/pip-TApOsJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:50:31 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)) 04:50:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:50:31 INFO - Running setup.py (path:/tmp/pip-GTp3N8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:50:32 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)) 04:50:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:50:32 INFO - Running setup.py (path:/tmp/pip-qSfalK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:50:32 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)) 04:50:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:50:32 INFO - Running setup.py (path:/tmp/pip-Yww5NY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:50:32 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)) 04:50:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:50:32 INFO - Running setup.py (path:/tmp/pip-E3S7NN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:50:32 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)) 04:50:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:50:32 INFO - Running setup.py (path:/tmp/pip-mialSD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:50:32 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)) 04:50:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:50:32 INFO - Running setup.py (path:/tmp/pip-4YooMC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:50:32 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)) 04:50:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:50:32 INFO - Running setup.py (path:/tmp/pip-JgF3CS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:50:32 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)) 04:50:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:50:32 INFO - Running setup.py (path:/tmp/pip-p3lcMG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:50:32 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)) 04:50:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:50:32 INFO - Running setup.py (path:/tmp/pip-xdqtxS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:50:32 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)) 04:50:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:50:32 INFO - Running setup.py (path:/tmp/pip-LBcdjR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:50:32 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)) 04:50:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:50:32 INFO - Running setup.py (path:/tmp/pip-1rP6cO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:50:32 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)) 04:50:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:50:32 INFO - Running setup.py (path:/tmp/pip-EM1Bvn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:50:32 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)) 04:50:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:50:32 INFO - Running setup.py (path:/tmp/pip-0nKOG9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:50:32 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)) 04:50:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:50:32 INFO - Running setup.py (path:/tmp/pip-TpeNUg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:50:32 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)) 04:50:32 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)) 04:50:32 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)) 04:50:32 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)) 04:50:32 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)) 04:50:32 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)) 04:50:32 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:50:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:50:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:50:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:50:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:50:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:50:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:50:34 INFO - Downloading blessings-1.6.tar.gz 04:50:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:50:35 INFO - Installing collected packages: blessings 04:50:35 INFO - Running setup.py install for blessings 04:50:35 INFO - Successfully installed blessings 04:50:35 INFO - Cleaning up... 04:50:35 INFO - Return code: 0 04:50:35 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:50:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:50:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:50:35 INFO - Reading from file tmpfile_stdout 04:50:35 INFO - Current package versions: 04:50:35 INFO - argparse == 1.2.1 04:50:35 INFO - blessings == 1.6 04:50:35 INFO - blobuploader == 1.2.4 04:50:35 INFO - docopt == 0.6.1 04:50:35 INFO - functools32 == 3.2.3-2 04:50:35 INFO - jsonschema == 2.5.1 04:50:35 INFO - manifestparser == 1.1 04:50:35 INFO - mozInstall == 1.12 04:50:35 INFO - mozcrash == 1.0 04:50:35 INFO - mozdebug == 0.1 04:50:35 INFO - mozdevice == 0.49 04:50:35 INFO - mozfile == 1.2 04:50:35 INFO - mozhttpd == 0.7 04:50:35 INFO - mozinfo == 0.9 04:50:35 INFO - mozleak == 0.1 04:50:35 INFO - mozlog == 3.4 04:50:35 INFO - moznetwork == 0.27 04:50:35 INFO - mozprocess == 0.23 04:50:35 INFO - mozprofile == 0.28 04:50:35 INFO - mozrunner == 6.13 04:50:35 INFO - mozscreenshot == 0.1 04:50:35 INFO - mozsystemmonitor == 0.3 04:50:35 INFO - moztest == 0.7 04:50:35 INFO - mozversion == 1.4 04:50:35 INFO - psutil == 3.1.1 04:50:35 INFO - requests == 1.2.3 04:50:35 INFO - wsgiref == 0.1.2 04:50:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:50: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')]} 04:50:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:50:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:50: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')]} 04:50:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:50:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:50:35 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 0x253b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2707a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28acc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28aa3f0>, '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 0x28aacf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28ab1a0>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1482576190.421265-884628426', '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 04:50:35 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 04:50:35 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 04:50:35 INFO - Using env: {'DISPLAY': ':0', 04:50:35 INFO - 'HOME': '/home/cltbld', 04:50:35 INFO - 'LANG': 'en_US.UTF-8', 04:50:35 INFO - 'LANGUAGE': 'en_US:en', 04:50:35 INFO - 'LOGNAME': 'cltbld', 04:50:35 INFO - 'MAIL': '/var/mail/cltbld', 04:50:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:50:35 INFO - 'MOZ_NO_REMOTE': '1', 04:50:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:50:35 INFO - 'NO_EM_RESTART': '1', 04:50:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:50:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:50:35 INFO - 'PWD': '/builds/slave/test', 04:50:35 INFO - 'SHELL': '/bin/bash', 04:50:35 INFO - 'SHLVL': '1', 04:50:35 INFO - 'TERM': 'linux', 04:50:35 INFO - 'TMOUT': '86400', 04:50:35 INFO - 'USER': 'cltbld', 04:50:35 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1482576190.421265-884628426', 04:50:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:50:35 INFO - '_': '/tools/buildbot/bin/python'} 04:50:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:50:35 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)) 04:50:35 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)) 04:50:35 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)) 04:50:35 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)) 04:50:35 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)) 04:50:35 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)) 04:50:35 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)) 04:50:35 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)) 04:50:35 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)) 04:50:35 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)) 04:50:35 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)) 04:50:35 INFO - Cleaning up... 04:50:35 INFO - Return code: 0 04:50:35 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:50: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')]} 04:50:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:50:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:50: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')]} 04:50:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:50:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:50:35 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 0x253b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2707a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28acc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28aa3f0>, '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 0x28aacf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28ab1a0>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1482576190.421265-884628426', '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 04:50:35 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 04:50:35 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 04:50:35 INFO - Using env: {'DISPLAY': ':0', 04:50:35 INFO - 'HOME': '/home/cltbld', 04:50:35 INFO - 'LANG': 'en_US.UTF-8', 04:50:35 INFO - 'LANGUAGE': 'en_US:en', 04:50:35 INFO - 'LOGNAME': 'cltbld', 04:50:35 INFO - 'MAIL': '/var/mail/cltbld', 04:50:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:50:35 INFO - 'MOZ_NO_REMOTE': '1', 04:50:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:50:35 INFO - 'NO_EM_RESTART': '1', 04:50:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:50:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:50:35 INFO - 'PWD': '/builds/slave/test', 04:50:35 INFO - 'SHELL': '/bin/bash', 04:50:35 INFO - 'SHLVL': '1', 04:50:35 INFO - 'TERM': 'linux', 04:50:35 INFO - 'TMOUT': '86400', 04:50:35 INFO - 'USER': 'cltbld', 04:50:35 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1482576190.421265-884628426', 04:50:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:50:35 INFO - '_': '/tools/buildbot/bin/python'} 04:50:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:50:35 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:50:35 INFO - Cleaning up... 04:50:35 INFO - Return code: 0 04:50:35 INFO - Running post-action listener: _resource_record_post_action 04:50:35 INFO - Running post-action listener: _start_resource_monitoring 04:50:35 INFO - Starting resource monitoring. 04:50:35 INFO - [mozharness: 2016-12-24 12:50:35.946723Z] Finished create-virtualenv step (success) 04:50:35 INFO - [mozharness: 2016-12-24 12:50:35.947319Z] Running install step. 04:50:35 INFO - Running pre-action listener: _resource_record_pre_action 04:50:35 INFO - Running main action method: install 04:50:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:50:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:50:36 INFO - Reading from file tmpfile_stdout 04:50:36 INFO - Detecting whether we're running mozinstall >=1.0... 04:50:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:50:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:50:36 INFO - Reading from file tmpfile_stdout 04:50:36 INFO - Output received: 04:50:36 INFO - Usage: mozinstall [options] installer 04:50:36 INFO - Options: 04:50:36 INFO - -h, --help show this help message and exit 04:50:36 INFO - -d DEST, --destination=DEST 04:50:36 INFO - Directory to install application into. [default: 04:50:36 INFO - "/builds/slave/test"] 04:50:36 INFO - --app=APP Application being installed. [default: firefox] 04:50:36 INFO - mkdir: /builds/slave/test/build/application 04:50:36 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'] 04:50:36 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 04:50:53 INFO - Reading from file tmpfile_stdout 04:50:53 INFO - Output received: 04:50:53 INFO - /builds/slave/test/build/application/firefox/firefox 04:50:53 INFO - Running post-action listener: _resource_record_post_action 04:50:53 INFO - [mozharness: 2016-12-24 12:50:53.588290Z] Finished install step (success) 04:50:53 INFO - [mozharness: 2016-12-24 12:50:53.588538Z] Running run-tests step. 04:50:53 INFO - Running pre-action listener: _resource_record_pre_action 04:50:53 INFO - Running main action method: run_tests 04:50:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:50:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:50:53 INFO - Python 2.7.3 04:50:53 INFO - Return code: 0 04:50:53 INFO - grabbing minidump binary from tooltool 04:50:53 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')]} 04:50:53 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 0x28aa3f0>, '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 0x28aacf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28ab1a0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:50:53 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 04:50:53 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 04:50:53 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 04:50:53 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:50:53 INFO - Return code: 0 04:50:53 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 04:50:53 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:50:53 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:50:53 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 04:50:53 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:50:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/dx8uU0KPQWW9lHCdmv6MUg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-034', '--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 04:50:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/dx8uU0KPQWW9lHCdmv6MUg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-034 --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 04:50:53 INFO - Using env: {'DISPLAY': ':0', 04:50:53 INFO - 'HOME': '/home/cltbld', 04:50:53 INFO - 'LANG': 'en_US.UTF-8', 04:50:53 INFO - 'LANGUAGE': 'en_US:en', 04:50:53 INFO - 'LOGNAME': 'cltbld', 04:50:53 INFO - 'MAIL': '/var/mail/cltbld', 04:50:53 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:50:53 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:50:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:50:53 INFO - 'MOZ_NO_REMOTE': '1', 04:50:53 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:50:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:50:53 INFO - 'NO_EM_RESTART': '1', 04:50:53 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:50:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:50:53 INFO - 'PWD': '/builds/slave/test', 04:50:53 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:50:53 INFO - 'SHELL': '/bin/bash', 04:50:53 INFO - 'SHLVL': '1', 04:50:53 INFO - 'TERM': 'linux', 04:50:53 INFO - 'TMOUT': '86400', 04:50:53 INFO - 'USER': 'cltbld', 04:50:53 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1482576190.421265-884628426', 04:50:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:50:53 INFO - '_': '/tools/buildbot/bin/python'} 04:50:53 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/dx8uU0KPQWW9lHCdmv6MUg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-034', '--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 04:50:53 INFO - mozversion application_buildid: 20161224030205 04:50:53 INFO - mozversion application_changeset: da22155a2dc30dafc93d70f38f6bb8a49248c80f 04:50:53 INFO - mozversion application_display_name: Nightly 04:50:53 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:50:53 INFO - mozversion application_name: Firefox 04:50:53 INFO - mozversion application_remotingname: firefox 04:50:53 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 04:50:53 INFO - mozversion application_vendor: Mozilla 04:50:53 INFO - mozversion application_version: 53.0a1 04:50:53 INFO - mozversion platform_buildid: 20161224030205 04:50:53 INFO - mozversion platform_changeset: da22155a2dc30dafc93d70f38f6bb8a49248c80f 04:50:53 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 04:50:53 INFO - mozversion platform_version: 53.0a1 04:50:53 INFO - using testdate: 1482583853 04:50:53 INFO - actual date: 1482583853 04:50:53 INFO - starting webserver on 'localhost:42457' 04:50:53 INFO - SUITE-START | Running 2 tests 04:50:53 INFO - TEST-START | dromaeo_css 04:50:53 INFO - operating with platform_type : linux_ 04:50:53 INFO - Initialising browser for dromaeo_css test... 04:50:53 INFO - TEST-INFO | started process 23640 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpC2sCSt/profile http://localhost:42457/getInfo.html) 04:50:54 INFO - PROCESS | 23640 | 1482583854362 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4979 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3825 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2829 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 04:50:56 INFO - PROCESS | 23640 | JavaScript error: chrome://global/content/browser-content.js, line 1772: ReferenceError: event is not defined 04:50:56 INFO - PROCESS | 23640 | JavaScript error: chrome://global/content/browser-content.js, line 1772: ReferenceError: event is not defined 04:50:57 INFO - PROCESS | 23640 | JavaScript error: chrome://global/content/browser-content.js, line 1772: ReferenceError: event is not defined 04:50:57 INFO - PROCESS | 23640 | __metrics Screen width/height:1600/1200 04:50:57 INFO - PROCESS | 23640 | colorDepth:24 04:50:57 INFO - PROCESS | 23640 | Browser inner width/height: 1024/697 04:50:57 INFO - PROCESS | 23640 | __metrics 04:50:58 INFO - PROCESS | 23640 | 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" 04:50:58 INFO - PROCESS | 23640 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:50:59 INFO - PROCESS | 23640 | console.error: PushService: 04:50:59 INFO - PROCESS | 23640 | stateChangeProcessEnqueue: Error transitioning state 04:50:59 INFO - PROCESS | 23640 | UnknownError 04:50:59 INFO - TEST-INFO | 23640: exit 0 04:50:59 INFO - Browser initialized. 04:50:59 INFO - Running cycle 1/1 for dromaeo_css test... 04:50:59 INFO - TEST-INFO | started process 23723 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpC2sCSt/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:51:11 INFO - PROCESS | 23723 | RSS: Main: 147521536 04:51:11 INFO - PROCESS | 23723 | 04:52:02 INFO - PROCESS | 23723 | 1482583922848 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] 04:54:12 INFO - PROCESS | 23723 | Cycle 1(1): loaded http://localhost:42457/tests/dromaeo/cssquery-dojo.html (next: http://localhost:42457/tests/dromaeo/cssquery-ext.html) 04:54:14 INFO - PROCESS | 23723 | RSS: Main: 349315072 04:54:14 INFO - PROCESS | 23723 | 04:56:00 INFO - PROCESS | 23723 | ************************* 04:56:00 INFO - PROCESS | 23723 | A coding exception was thrown and uncaught in a Task. 04:56:00 INFO - PROCESS | 23723 | 04:56:00 INFO - PROCESS | 23723 | Full message: ReferenceError: fetch is not defined 04:56:00 INFO - PROCESS | 23723 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 04:56:00 INFO - PROCESS | 23723 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 04:56:00 INFO - PROCESS | 23723 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 04:56:00 INFO - PROCESS | 23723 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 04:56:00 INFO - PROCESS | 23723 | TaskImpl@resource://gre/modules/Task.jsm:277:3 04:56:00 INFO - PROCESS | 23723 | asyncFunction@resource://gre/modules/Task.jsm:252:14 04:56:00 INFO - PROCESS | 23723 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 04:56:00 INFO - PROCESS | 23723 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 04:56:00 INFO - PROCESS | 23723 | TaskImpl@resource://gre/modules/Task.jsm:277:3 04:56:00 INFO - PROCESS | 23723 | asyncFunction@resource://gre/modules/Task.jsm:252:14 04:56:00 INFO - PROCESS | 23723 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 04:56:00 INFO - PROCESS | 23723 | 04:56:00 INFO - PROCESS | 23723 | ************************* 04:57:17 INFO - PROCESS | 23723 | Cycle 1(1): loaded http://localhost:42457/tests/dromaeo/cssquery-ext.html (next: http://localhost:42457/tests/dromaeo/cssquery-jquery.html) 04:57:17 INFO - PROCESS | 23723 | RSS: Main: 244101120 04:57:17 INFO - PROCESS | 23723 | 05:00:19 INFO - PROCESS | 23723 | Cycle 1(1): loaded http://localhost:42457/tests/dromaeo/cssquery-jquery.html (next: http://localhost:42457/tests/dromaeo/cssquery-mootools.html) 05:00:20 INFO - PROCESS | 23723 | RSS: Main: 189890560 05:00:20 INFO - PROCESS | 23723 | 05:03:22 INFO - PROCESS | 23723 | Cycle 1(1): loaded http://localhost:42457/tests/dromaeo/cssquery-mootools.html (next: http://localhost:42457/tests/dromaeo/cssquery-prototype.html) 05:03:23 INFO - PROCESS | 23723 | RSS: Main: 230920192 05:03:23 INFO - PROCESS | 23723 | 05:06:25 INFO - PROCESS | 23723 | Cycle 1(1): loaded http://localhost:42457/tests/dromaeo/cssquery-prototype.html (next: http://localhost:42457/tests/dromaeo/cssquery-yui.html) 05:06:26 INFO - PROCESS | 23723 | RSS: Main: 189038592 05:06:26 INFO - PROCESS | 23723 | 05:09:29 INFO - PROCESS | 23723 | Cycle 1(1): loaded http://localhost:42457/tests/dromaeo/cssquery-yui.html (next: http://localhost:42457/tests/dromaeo/cssquery-dojo.html) 05:09:29 INFO - PROCESS | 23723 | RSS: Main: 282980352 05:09:29 INFO - PROCESS | 23723 | 05:09:29 INFO - PROCESS | 23723 | __start_tp_report 05:09:29 INFO - PROCESS | 23723 | _x_x_mozilla_page_load 05:09:29 INFO - PROCESS | 23723 | _x_x_mozilla_page_load_details 05:09:29 INFO - PROCESS | 23723 | |i|pagename|runs| 05:09:29 INFO - PROCESS | 23723 | |0;dojo.html;3178.682131786821;3233.644299127097;3243.691849274319;3273.7217336526396;3281.3765384576927;5260.684358938466;5313.73431328434;5375.89248215036;5437.809676661318;5458.727063646822;5335.653182543132;5349.973250133754;5361;5361.490658387456;5362.490563396471;4807.158747219231;4846.8545943621575;4883.829065982692;4889.095517329288;4950.084234416627;4922.21244600864;4984.37695288089;4986.052649996507;4987.276844857493;4992.550670439659;4838.903221935577;4946.777395017215;5019.322391477159;5021.5229553192385;5034.84895453137;4723.102610504027;4754.144254034272;4972.105021096201;5027.321311622939;5028.472010438888;4769.403824521934;4786.282057691375;4793;4798.736069516175;4805.879853003668;5220.947790522122;5263.02634012705;5263.157894736823;5274.55166310864;5279.049771041211;5594.1608758686525;5645.407232240596;5749.453801888814;5749.8275051748515;5807.970960145172;5743.368229494752;5785.8553536161935;5792.304923409218;5796.594238403313;5797.304323481167;5248.448912864171;5372.677639341613;5388.46115388458;5392.379876314196;5395.244665746799;5839.737211825478;5884.264466941632;5957.404259574095;5960.135780311831;6025.216721826135;5655;5667.914981275284;5716.171155182476;5724.0841465366175;5753.8561535961935;5815.447532484407;5827.533797296291;5827.65034097947;5828.067509198508;5833.970830145822;5488;5525.171224316385;5766.3368712598585;5787.913181302284;5799.478046975708;5533.778648854081;5572.804951826748;5584.91622625661;5707.543396528268;5845.620034697731;4319.481662200493;4319.4816622005565;4341.848035318749;4360.738355698605;4368.8689339319235;3861.613838616172;3873.8063096845044;3881.941770873439;3912.139329347539;3914.197589494147;5275;5374.462553744673;5436.728163591804;5471.863203419866;5545.889082218414;5749.511291540182;5754.309482862083;5766.452187042224;5780.335261444903;5793.536517078624;5474.671519708831;5481.040817856859;5486.972565137149;5487.972560137254;5489.1217405214975;4683.578477937002;4725.362076119749;4808.519148085164;4812.807487700453;4856.757162141878;6083.178770865877;6125.449118208436;6142.069075791497;6145.108959200801;6153;5842.824715258549;5846.883062338815;5861.794837180678;5869.266341707286;5911.467967882912;5819.476247137778;5937.5249980000635;6131.877362452815;6135.049067394561;6137.447629713347;5628.85927851807;5646.35066967304;5653.180288858175;5701.743421545957;5759.884802303846;5774.2782152230975;5780.248567686174;5784.595078344475;5787.623804452697;5804.506616937439;5412.972935135299;5654.7455364509515;5656.23640808502;5658.207850900795;5661.2923384576925;5437.755301011385;5451.236826844166;5509.531689806411;5510.173473978935;5515.034868898007;5483.095289277225;5483.287172667528;5491.670499770026;5495.340559132929;5503.477169668875;5501.0648189807025;5508.586855985737;5558.44415558441;5566.248556444828;5591.356993945586;5451.672899626035;5508.9724551376985;5511.751971161227;5551.750171242223;5571.052921003358 05:09:29 INFO - PROCESS | 23723 | |1;ext.html;551.5229326632463;586.6978506069372;590.0676930449888;594.771013159933;603.5654328883203;32877.50683739743;33705.83147084264;40355;41860.790696046475;42412.36381454273;45727.314090288586;46113.769431152796;46146.53853461464;46186.53813461864;46201.768991155;8888.511131887759;9075.818483630323;9080.182783549479;9083.818323633523;9084.227840633554;8642.092580279075;8804.64781408743;8933.731988040368;10463.372197668165;10719.249652524326;10897.400642964634;10967.945160274227;10973.451327433637;10982.06652434544;10982.560697572088;10849;10904.890951090547;10919.563217471292;10920.344779313187;10925.617603383844;10577.206709496742;10620.40693895914;10629;10636.361818290927;10638.255322127454;41937.79031104856;42199.36700949488;42327.36508952368;42353.788231058956;42398;3354.480055591387;3356.832158392095;3423.4351332030187;3440.569928758905;3483.303339332144;31750.523742143887;31807.363852723047;32330.83834580812;32404.18989525257;33014.83492582522;4276.764777937212;4352.325389564622;4355.3031514957775;4565.61192298655;4593.655475839292;342598;343059;343119;344609.2769536161;345718;53139.734301328244;53617.731911340976;53798.462015379344;53821.23251372445;53882.461175388526;63352.68323658352;63354;63355;63521.36478635247;63571.68214158899;16489.422870199487;16912.83087169137;16981;17005.574860628334;17022.148892555324;11003.944980275048;12768.170068945674;12910.419031143621;12914.67713307175;12932.482700692055;15950;17549.859259148114;17577.472675819747;17991.280348786164;18541.35105271336;5868.3251426086545;6082.908756368658;6115.1744514490865;6132.78535251264;6159.81520554384;868.4869702055202;985.4973963278636;1052.6894566103017;1069.7432616172064;1084.2572837606265;33108.66891331104;34356.48465273023;34687.479687804705;36778.8161059193;37082.25835483275;1024.1601886453036;1028.2339656955467;1034.3623759413615;1065.227709910309;1071.4535586850607;15860.365585376456;17139.22873470697;17308.221130049176;17311.220995055253;17338.479845604652;528419;531445;532090;534202;539593;40177.1964560713;40513.392299115534;40555.79722101371;40592.39111413331;40622.796886015974;1398.7552178368744;1403.6911879386519;1419.8012278281049;1432.7564314066842;1433.6487560547523;29842.850785745934;31081.37837243288;31280.68719312778;32202;32471.512927306583;53229.73385133049;54095.45904540904;54157.45842541524;54160;54228.72885635705;495638;496893;499926;501070;501739;35786.463203051455;43323.35014974715;43620.56379436165;44170.11659766851;44234.33648495211;47412.288815668486;48720.756396217796;48816;49096.2635560474;49203.753981229864;35525;39809.60190398175;40322.19355612813;41851.1629767409;42098.895055247725;34205.828970854986;37573.62426375701;37775.24449510939;37793.433098502996;37920.241595168496;36172.81913590415;36575.63424365722;37596.436053459765;37850.43224351582;39716.00709982274;32056;33778.662213378535;34333.48499772555;34627.826860865534;34820;26316;26383.972956850575;26538.469230614894;26643.866780665972;26782.19653410401 05:09:29 INFO - PROCESS | 23723 | |2;jquery.html;383.34065407499094;394.45171212015293;396.76590811421255;399.1697269679067;421.4647397804786;13460.52888148915;15415.383384664614;18291.63416731666;18569.25722971083;18934.390576800954;18384.874571322875;18885.90557047213;18904.527386815338;18930;19158.13788379523;5511.145772405283;5521.944780552193;5709.200711900337;5735.713214339287;5736.082226843706;5586.581006424514;5591.133374326986;5600.831975040755;5607.467290607386;5611.663300201981;5454.754536045868;5581.469760372758;5586.41342659021;5588.161775733653;5589.189567512708;5569.777208911639;5594.9720251398885;5605.1872478490795;5635.211070450161;5635.718214089279;4855.830045948375;5025.6733312334945;5036.169032109698;5050.823221187277;5050.873728156826;18644.720329195072;18784.530386740305;18803.905980470146;20954.16183352664;22019.449513762123;2222.388843068153;2332.498512819735;2422.479166979108;2503.111395454606;2906.8401237931907;13589.252591107484;14266.3580138894;14424.855751442456;14547;14554.781678274834;1899.2972600138037;1915.798841121676;1935.4290484307023;1953.1406181280333;1961.8332441742468;66991.66504167496;76818;78347.21652783456;78839;80633;10812.891871081345;10898.400587967579;10925.453727313761;10982.780344393068;11082.501287442085;14872.851271487363;14911.403543858125;15027.474038408604;15062.774058389134;15063.54809355721;7371.64590111498;7695.884561731578;7800.9609951951015;7948.920510794934;8022.679092836222;7345.853082938312;7354.816129596803;7404.148522919933;7553.509021913668;7554.660040298199;4683.531646835289;4696.671233013656;4730.432348118248;4738.763061846963;4819.759012049453;1899.1928430417017;1937.5156210947264;1944.8638595298194;1982.9816506901484;1984.563396052866;885.703289398046;918.912703293186;940.9952950235205;967.1923943507251;1045.6967479431032;16963.74554381685;18351.265949362143;18639.534011649546;18778.795531182583;18944.621107577772;932.6036434515304;956.2063487305659;976.2921881635899;984;1019.4240254256322;9828.459434731016;10583.523741431654;10598.31110977784;10667.626633067805;10692.465376731132;401892;407140;409447;414185;415345;73728.26271737214;86341.56829215813;89444.5527772357;90538;91168.54415727878;977.750673578233;1017.4658304390255;1029.4955471818755;1041.1358572384902;1042.2652030318607;16284.755728664317;16399.180040997664;16878.74681879748;17059.829401705825;17071.658566828843;40760.79619601883;41090.79454602827;41158.794206028775;41202.79398603108;42633.78683106565;56248.437515625956;58043.70978145082;58148.70925645345;58960.70519647374;59707.104393435;8371.874421883555;8493.87259191125;8540.530270835165;8696.130386961253;8775.91224087751;10645.840312395476;12484.375781211085;12754.234745915275;12981.480740770263;13077.149985250928;10875.836862446911;10936.56253749841;10968.506417211085;11572.537098515964;11626.651200463999;10829.512671929624;10917.781644367227;11095.611653592085;11349;11819.763604728028;11269.323840569592;11377.658670239905;11445.25605835618;11650.669866026998;11667.824982625438;8256.504609723435;8416.537090460084;8427.115152908857;8472.576371181292;8620.267277281502;10542.841857371992;10982.341059536453;11169.497372618414;11298.322100673986;11588.884111159117 05:09:29 INFO - PROCESS | 23723 | |3;mootools.html;1442.2860683961442;1470.455931305417;1619.9352025918963;1782.884112532686;1783.4827899909028;8090.635921383538;8573.57132143392;8591.785205369868;8628.654853805856;8636.222739953402;7589.582572958485;7826;8176.38677099217;8375.455595386296;8478.19457151571;2305.1470955746354;2333.53329334133;2336.217367181996;2350.5181437805295;2354.9175778847743;2327.4530485336018;2347.94130146746;2387.379281386843;2387.5941090014653;2388.414838364603;2291.278247352087;2296.7932886040253;2318.246569864792;2318.431984163882;2321.0483701682315;2233;2247.325802259324;2251.9887400562975;2256.9210077647203;2260.9660855087186;2295.3228797504776;2318.652202169671;2320.245920075982;2322.3381336319235;2328.441174118216;8399.11809260031;8589.184027517376;8592.140785921358;8595.226429621302;8607.82784344316;5140.768665410065;5290.391604965402;5291.656042357235;5292.629515933886;5295.973520132375;3148.9608429218533;3260.690234427749;3409.07954852191;3411.7441191910457;3416.7266618670446;1043.5929987304958;1057.6245432871297;1072.3405105859918;1086.0876863434673;1097.9451027448677;11321.660350189508;11398.772024559545;11489.597864074718;11515.481803318788;11532.596359127474;4481;4520.954790452053;4556.954430455719;4690.788914498856;4702.271147972001;9529.285303602119;9533.761655958519;9557.569909353957;9560.856587151198;9568.712938611852;5223.869403264949;5230.869228269324;5236.450172731885;5237.3453318335205;5238.214267859851;4262.531121576624;4358.5205627380965;4514.864554063383;4691.507391723888;4838.588719958843;3855.768653880776;3924.117073658404;3958.8416463341146;3988.2422339755353;4000.1599664070873;1315.9934200328937;1321.0818481155504;1321.378951892609;1324.0466863858007;1331.6138319887125;795.1452188896959;908.2506931781306;912.7763697894059;922.2431268433645;942.9198518126036;5516.531094856902;5616.9157462638095;5631.2397826293745;5665.291838520185;5744.942550574524;890.643742503004;910.2672348759312;915.9542022898962;938.671464987249;946.5787724462685;3806.200697853482;3833.3291673956946;3882.902927426837;3916.8629097981434;3929.135590170158;10148;10169.745756356151;10269.794604108025;10280.588776448858;10628.946855265829;10086.092251697235;10168.237382196365;10184.796304073878;10192.286539942133;10194.388336699822;7488.5506869588;7709.074911010603;7754.728584499629;7822;7836.92163078362;5757.107648314518;5823;5864.794732184353;5920.940790592039;6007.90988135187;9246;9272.211861991806;9292.303077269096;9360.11078947499;9404.811903761749;9379.953100234456;9509;9529.714108576754;9602.08780165883;9626.278029147701;6547.836304092436;6819.318068193141;7304.926950730424;7365.116186057708;7524.134724506752;7932.246436588515;7997.320227780704;8005.919940800517;8009.719659811879;8020.919790802017;7223.747168849065;7271.272872712686;7408.407327413686;7530.246975302426;7531.623418828927;7047.894281585678;7181.174164971095;7202.459815513753;7205.387542058774;7443.218462061405;6459.61242325462;6587.4071333580205;6588.04473351371;6588.176477940257;6588.472922166311;4782.880427989189;4815.855524334275;4884.65807393479;4972.8756781080765;5015.774290156879;4958.107540642719;4970.5029497051455;5153.252778347193;5173.793048278027;5189.169732842727 05:09:29 INFO - PROCESS | 23723 | |4;prototype.html;377.8899655832499;389.3595036165508;394.3335762561272;394.9940750888739;401.90756126091014;5213.687178769276;5219.321488206538;5221.269022336871;5244.292020577221;5250.921236181455;4910.7299098549565;4990.625703072266;5048.671836330636;5097.541221290083;5198.818041368553;4121.609466509417;4141.503019637633;4141.6065473779945;4154.940490175001;4167.770772607505;4117;4134.441850350209;4151.73013754105;4161.521425036114;4164.354525048622;3887.280853042182;3933.331333673268;4024.1146947671614;4050.7974601269966;4100.323446631317;4070.7354021988476;4074.470318858559;4082;4094.28350038745;4106.32245679464;3674.0527520414225;3688.8340024698955;3695.020819482839;3695.981520092409;3700.111973126457;4808.403462130445;5016.247562865563;5106.64673861427;5108;5110.207917772751;4715.41057367829;4720.33915251865;4728.314394412825;4734.668573199878;4749.833755818529;5365.382980957201;5437.782488700447;5458.536024437927;5472.017750326042;5473.221203109006;3974.602539746031;4071.145059537501;4079.8980025499304;4086.162336720965;4123.422720819088;5564.304461942257;5597.7201139943245;5664.20701101846;5664.631798933056;5673.23411339468;4183.769892655933;4194.517630472474;4206.810693518799;4206.831726730958;4209.915801683949;3899.5710471848074;4261.893452663646;4272.102858399709;4293.248681480728;4298.742075475481;4625.653076019312;4629.4676112247525;4651.186042442559;4664.906701866012;4670.1126785910565;5468.972655136698;5474.835754927359;5478.945210547843;5508.366537848118;5525.557955363642;1327.8576997372963;1375.4498200719793;1379.3103448275863;1387.257817067864;1391.193107997359;954.9474778887089;955.571420726019;956.9090936361034;959.1271942532262;961.8890181839986;5076;5150.536451719363;5153.381594208644;5184.422748303734;5196.168613021899;5418.566514678816;5451.427600102012;5461.453854614586;5466.508014278734;5482.451754824486;5052.317937078521;5144;5174.922376164285;5200.115980283361;5225.529702326809;2861.513542697705;2865.5271880139544;2872.3967966727223;2874.375977572665;2903.288694269875;5472.972635136878;5667.489925906688;5684.8010319638615;5698.7747634258;5726.284214473191;5514.92458970505;5536.667799932017;5541.889162216814;5544.1960915667005;5547.281831902339;5634.703698336437;5671.234383358276;5678;5708.686022268732;5731.312242530923;5309.150535914235;5448.7548060336585;5473.917891231555;5634;5637.633553819152;5549.972250138723;5556.860843526986;5567.24842146305;5597.5726189821335;5598.888022239451;5733.741981610921;5818.563607729353;5839.445252700986;5859.179714840011;5916.674582897982;4051.2505186540584;4054.39184122384;4073.287174744467;4074.022234663719;4075.2460794753065;5362.597805164551;5486.61593688438;5539.14143307788;5607.523360514402;5617.6910269933915;5510.9448905510435;5529.115341545334;5530.1197592818635;5540.445955404427;5546.500814926676;5422.755975981169;5449.564034877121;5479.205515200353;5527.944720552743;5537.950958145512;5236.476352364733;5412.78348866041;5455.290812194389;5461.590380721383;5485.6960076768655;5439.972800135974;5494.972525137348;5525.4474552546035;5561.63849349791;5616.326040875121;5149.768260428365;5365.946340536545;5403.108487099585;5441.8981180726605;5478.233047373451 05:09:29 INFO - PROCESS | 23723 | |5;yui.html;769.6690423118059;778.1790211327047;791.2522666080553;825.1253671108625;881.1937077574019;14651.12093274403;15931.920340398296;15945.920270398661;15951.760723589126;16074.517764467055;14841.925790371033;15004;15012.549623511284;15021.32404041818;15285.388584456636;671.03036112817;720.240146645289;730.2551397574471;787.94484386093;798.9081255655601;814.3851392198882;820.995895020524;821.0147822612859;821.4701517521195;823.7858156879224;843.9915600843974;862.6247582301719;871.6861929705301;871.7079778274285;872.402404353017;688.0333131949619;741.0329519976428;789.0491957191571;854.6880388658158;858.1804376820122;630.6090224061065;630.8517498387876;632.3897438971414;633.6704913445029;634.6255709131589;14591.489297874523;15002.099874007594;15003.774943375858;15034.774478382833;15045.473408430762;12499.62578958397;13099.148555343967;13142;13185.60443186706;13239.404226809816;12160.391980401033;12211.145219834616;12285.754284914252;12439.502419903192;12561.748765024648;3747.6065013173497;3787.261484010619;3806.638369354907;3824.827882745283;3909.2767837949787;80826.19173808246;81566;82066.58966705189;83245;83712.58143709303;11402.543898243977;11417.60038398652;11461.770764584662;11498.827517587242;11539.19225654213;21481.46296342604;21640.675389869164;21796.89101554482;21884.12463501442;22105.889470552545;7838.843223135619;8029.518228906284;8030.116687164407;8077.959610202029;8438.493690378475;4380.364847097217;4450.109978004412;4557.384753058294;4597.632189424838;4644.930326045112;3529.6470352964498;3602.8290805488323;3620.637936206411;3633.6184700606054;3633.927321453556;651.5471747135695;653.4282502810041;654.8755736410067;671.9529632925648;681.9693113809891;697.7592730507967;705.8059033765755;711.5872793779596;715.9463040271896;738.8596166728305;8615.003298087095;9230.907690923139;9232.492212928353;9245.491497967541;9502.762430939281;550.5513006899364;558.6148732450233;639.7153266796231;665.7270519087248;700.2122612061431;4105.281575724236;4311.288637374798;4328.567143285646;4452.4211852458975;4504.324351347324;81625;82419;82615;84735.15264847272;87512;68946.65526672335;73410.63294683493;73459.26540734524;75215;77612.2238777605;53001.2049819253;63534;63795.68102159553;64517;65197.674011629635;9199.310051746279;9408.64783804049;9777.21782257432;9784.706458806248;9825.4104753715;39794;40023.1995360097;40437.1912561753;41750.37374439446;41832.16335673209;55868;57892.421075788705;58397.70801145967;58927.11609325778;59336.70331648487;11697.824532632187;11735.767744387058;12163.57427490069;12331.136820422484;12733.236005839679;13501.257430841006;13673.521426750414;13702.794458083335;13795.448182072605;14099.083559568595;12938;13168.868311316764;13181.736365272449;13231.536896208587;13858.445662173803;12368.505259789508;12811.871881281068;12853.807192892302;13347.532836350681;13361.799573006607;12878.93560532229;12964.28696421668;13030.226438097383;13040.869591304345;13122.14706044104;10042.447665378475;10292.279540432397;10367.792644147226;10660.093892019266;10843.891561084289;10708.250422470664;10868.891311086787;10947.671569852917;11030.50362733663;11059.778804424028 05:09:29 INFO - PROCESS | 23723 | __end_tp_report 05:09:29 INFO - PROCESS | 23723 | __start_cc_report 05:09:29 INFO - PROCESS | 23723 | _x_x_mozilla_cycle_collect,1966 05:09:29 INFO - PROCESS | 23723 | __end_cc_report 05:09:29 INFO - PROCESS | 23723 | __startTimestamp1482584969290__endTimestamp 05:09:29 INFO - PROCESS | 23723 | 05:09:29 INFO - PROCESS | 23723 | ------- Summary: start ------- 05:09:29 INFO - PROCESS | 23723 | Number of tests: 6 05:09:29 INFO - PROCESS | 23723 | 05:09:29 INFO - PROCESS | 23723 | [#0] dojo.html Cycles:165 Average:5339.42 Median:5495.34 stddev:612.13 (11.1%) stddev-sans-first:590.07 05:09:29 INFO - PROCESS | 23723 | Values: 3178.7 3233.6 3243.7 3273.7 3281.4 5260.7 5313.7 5375.9 5437.8 5458.7 5335.7 5350.0 5361.0 5361.5 5362.5 4807.2 4846.9 4883.8 4889.1 4950.1 4922.2 4984.4 4986.1 4987.3 4992.6 4838.9 4946.8 5019.3 5021.5 5034.8 4723.1 4754.1 4972.1 5027.3 5028.5 4769.4 4786.3 4793.0 4798.7 4805.9 5220.9 5263.0 5263.2 5274.6 5279.0 5594.2 5645.4 5749.5 5749.8 5808.0 5743.4 5785.9 5792.3 5796.6 5797.3 5248.4 5372.7 5388.5 5392.4 5395.2 5839.7 5884.3 5957.4 5960.1 6025.2 5655.0 5667.9 5716.2 5724.1 5753.9 5815.4 5827.5 5827.7 5828.1 5834.0 5488.0 5525.2 5766.3 5787.9 5799.5 5533.8 5572.8 5584.9 5707.5 5845.6 4319.5 4319.5 4341.8 4360.7 4368.9 3861.6 3873.8 3881.9 3912.1 3914.2 5275.0 5374.5 5436.7 5471.9 5545.9 5749.5 5754.3 5766.5 5780.3 5793.5 5474.7 5481.0 5487.0 5488.0 5489.1 4683.6 4725.4 4808.5 4812.8 4856.8 6083.2 6125.4 6142.1 6145.1 6153.0 5842.8 5846.9 5861.8 5869.3 5911.5 5819.5 5937.5 6131.9 6135.0 6137.4 5628.9 5646.4 5653.2 5701.7 5759.9 5774.3 5780.2 5784.6 5787.6 5804.5 5413.0 5654.7 5656.2 5658.2 5661.3 5437.8 5451.2 5509.5 5510.2 5515.0 5483.1 5483.3 5491.7 5495.3 5503.5 5501.1 5508.6 5558.4 5566.2 5591.4 5451.7 5509.0 5511.8 5551.8 5571.1 05:09:29 INFO - PROCESS | 23723 | 05:09:29 INFO - PROCESS | 23723 | [#1] ext.html Cycles:180 Average:61438.27 Median:36930.54 stddev:123699.88 (335.0%) stddev-sans-first:123962.42 05:09:29 INFO - PROCESS | 23723 | Values: 551.5 586.7 590.1 594.8 603.6 32877.5 33705.8 40355.0 41860.8 42412.4 45727.3 46113.8 46146.5 46186.5 46201.8 8888.5 9075.8 9080.2 9083.8 9084.2 8642.1 8804.6 8933.7 10463.4 10719.2 10897.4 10967.9 10973.5 10982.1 10982.6 10849.0 10904.9 10919.6 10920.3 10925.6 10577.2 10620.4 10629.0 10636.4 10638.3 41937.8 42199.4 42327.4 42353.8 42398.0 3354.5 3356.8 3423.4 3440.6 3483.3 31750.5 31807.4 32330.8 32404.2 33014.8 4276.8 4352.3 4355.3 4565.6 4593.7 342598.0 343059.0 343119.0 344609.3 345718.0 53139.7 53617.7 53798.5 53821.2 53882.5 63352.7 63354.0 63355.0 63521.4 63571.7 16489.4 16912.8 16981.0 17005.6 17022.1 11003.9 12768.2 12910.4 12914.7 12932.5 15950.0 17549.9 17577.5 17991.3 18541.4 5868.3 6082.9 6115.2 6132.8 6159.8 868.5 985.5 1052.7 1069.7 1084.3 33108.7 34356.5 34687.5 36778.8 37082.3 1024.2 1028.2 1034.4 1065.2 1071.5 15860.4 17139.2 17308.2 17311.2 17338.5 528419.0 531445.0 532090.0 534202.0 539593.0 40177.2 40513.4 40555.8 40592.4 40622.8 1398.8 1403.7 1419.8 1432.8 1433.6 29842.9 31081.4 31280.7 32202.0 32471.5 53229.7 54095.5 54157.5 54160.0 54228.7 495638.0 496893.0 499926.0 501070.0 501739.0 35786.5 43323.4 43620.6 44170.1 44234.3 47412.3 48720.8 48816.0 49096.3 49203.8 35525.0 39809.6 40322.2 41851.2 42098.9 34205.8 37573.6 37775.2 37793.4 37920.2 36172.8 36575.6 37596.4 37850.4 39716.0 32056.0 33778.7 34333.5 34627.8 34820.0 26316.0 26384.0 26538.5 26643.9 26782.2 05:09:29 INFO - PROCESS | 23723 | 05:09:29 INFO - PROCESS | 23723 | [#2] jquery.html Cycles:180 Average:26239.14 Median:388.90 stddev:67862.92 (17450.1%) stddev-sans-first:68025.53 05:09:29 INFO - PROCESS | 23723 | Values: 383.3 394.5 396.8 399.2 421.5 13460.5 15415.4 18291.6 18569.3 18934.4 18384.9 18885.9 18904.5 18930.0 19158.1 5511.1 5521.9 5709.2 5735.7 5736.1 5586.6 5591.1 5600.8 5607.5 5611.7 5454.8 5581.5 5586.4 5588.2 5589.2 5569.8 5595.0 5605.2 5635.2 5635.7 4855.8 5025.7 5036.2 5050.8 5050.9 18644.7 18784.5 18803.9 20954.2 22019.4 2222.4 2332.5 2422.5 2503.1 2906.8 13589.3 14266.4 14424.9 14547.0 14554.8 1899.3 1915.8 1935.4 1953.1 1961.8 66991.7 76818.0 78347.2 78839.0 80633.0 10812.9 10898.4 10925.5 10982.8 11082.5 14872.9 14911.4 15027.5 15062.8 15063.5 7371.6 7695.9 7801.0 7948.9 8022.7 7345.9 7354.8 7404.1 7553.5 7554.7 4683.5 4696.7 4730.4 4738.8 4819.8 1899.2 1937.5 1944.9 1983.0 1984.6 885.7 918.9 941.0 967.2 1045.7 16963.7 18351.3 18639.5 18778.8 18944.6 932.6 956.2 976.3 984.0 1019.4 9828.5 10583.5 10598.3 10667.6 10692.5 401892.0 407140.0 409447.0 414185.0 415345.0 73728.3 86341.6 89444.6 90538.0 91168.5 977.8 1017.5 1029.5 1041.1 1042.3 16284.8 16399.2 16878.7 17059.8 17071.7 40760.8 41090.8 41158.8 41202.8 42633.8 56248.4 58043.7 58148.7 58960.7 59707.1 8371.9 8493.9 8540.5 8696.1 8775.9 10645.8 12484.4 12754.2 12981.5 13077.1 10875.8 10936.6 10968.5 11572.5 11626.7 10829.5 10917.8 11095.6 11349.0 11819.8 11269.3 11377.7 11445.3 11650.7 11667.8 8256.5 8416.5 8427.1 8472.6 8620.3 10542.8 10982.3 11169.5 11298.3 11588.9 05:09:29 INFO - PROCESS | 23723 | 05:09:29 INFO - PROCESS | 23723 | [#3] mootools.html Cycles:180 Average:5531.43 Median:5206.52 stddev:3044.02 (58.5%) stddev-sans-first:3037.05 05:09:29 INFO - PROCESS | 23723 | Values: 1442.3 1470.5 1619.9 1782.9 1783.5 8090.6 8573.6 8591.8 8628.7 8636.2 7589.6 7826.0 8176.4 8375.5 8478.2 2305.1 2333.5 2336.2 2350.5 2354.9 2327.5 2347.9 2387.4 2387.6 2388.4 2291.3 2296.8 2318.2 2318.4 2321.0 2233.0 2247.3 2252.0 2256.9 2261.0 2295.3 2318.7 2320.2 2322.3 2328.4 8399.1 8589.2 8592.1 8595.2 8607.8 5140.8 5290.4 5291.7 5292.6 5296.0 3149.0 3260.7 3409.1 3411.7 3416.7 1043.6 1057.6 1072.3 1086.1 1097.9 11321.7 11398.8 11489.6 11515.5 11532.6 4481.0 4521.0 4557.0 4690.8 4702.3 9529.3 9533.8 9557.6 9560.9 9568.7 5223.9 5230.9 5236.5 5237.3 5238.2 4262.5 4358.5 4514.9 4691.5 4838.6 3855.8 3924.1 3958.8 3988.2 4000.2 1316.0 1321.1 1321.4 1324.0 1331.6 795.1 908.3 912.8 922.2 942.9 5516.5 5616.9 5631.2 5665.3 5744.9 890.6 910.3 916.0 938.7 946.6 3806.2 3833.3 3882.9 3916.9 3929.1 10148.0 10169.7 10269.8 10280.6 10628.9 10086.1 10168.2 10184.8 10192.3 10194.4 7488.6 7709.1 7754.7 7822.0 7836.9 5757.1 5823.0 5864.8 5920.9 6007.9 9246.0 9272.2 9292.3 9360.1 9404.8 9380.0 9509.0 9529.7 9602.1 9626.3 6547.8 6819.3 7304.9 7365.1 7524.1 7932.2 7997.3 8005.9 8009.7 8020.9 7223.7 7271.3 7408.4 7530.2 7531.6 7047.9 7181.2 7202.5 7205.4 7443.2 6459.6 6587.4 6588.0 6588.2 6588.5 4782.9 4815.9 4884.7 4972.9 5015.8 4958.1 4970.5 5153.3 5173.8 5189.2 05:09:29 INFO - PROCESS | 23723 | 05:09:29 INFO - PROCESS | 23723 | [#4] prototype.html Cycles:180 Average:4606.83 Median:5216.50 stddev:1326.75 (25.4%) stddev-sans-first:1291.94 05:09:29 INFO - PROCESS | 23723 | Values: 377.9 389.4 394.3 395.0 401.9 5213.7 5219.3 5221.3 5244.3 5250.9 4910.7 4990.6 5048.7 5097.5 5198.8 4121.6 4141.5 4141.6 4154.9 4167.8 4117.0 4134.4 4151.7 4161.5 4164.4 3887.3 3933.3 4024.1 4050.8 4100.3 4070.7 4074.5 4082.0 4094.3 4106.3 3674.1 3688.8 3695.0 3696.0 3700.1 4808.4 5016.2 5106.6 5108.0 5110.2 4715.4 4720.3 4728.3 4734.7 4749.8 5365.4 5437.8 5458.5 5472.0 5473.2 3974.6 4071.1 4079.9 4086.2 4123.4 5564.3 5597.7 5664.2 5664.6 5673.2 4183.8 4194.5 4206.8 4206.8 4209.9 3899.6 4261.9 4272.1 4293.2 4298.7 4625.7 4629.5 4651.2 4664.9 4670.1 5469.0 5474.8 5478.9 5508.4 5525.6 1327.9 1375.4 1379.3 1387.3 1391.2 954.9 955.6 956.9 959.1 961.9 5076.0 5150.5 5153.4 5184.4 5196.2 5418.6 5451.4 5461.5 5466.5 5482.5 5052.3 5144.0 5174.9 5200.1 5225.5 2861.5 2865.5 2872.4 2874.4 2903.3 5473.0 5667.5 5684.8 5698.8 5726.3 5514.9 5536.7 5541.9 5544.2 5547.3 5634.7 5671.2 5678.0 5708.7 5731.3 5309.2 5448.8 5473.9 5634.0 5637.6 5550.0 5556.9 5567.2 5597.6 5598.9 5733.7 5818.6 5839.4 5859.2 5916.7 4051.3 4054.4 4073.3 4074.0 4075.2 5362.6 5486.6 5539.1 5607.5 5617.7 5510.9 5529.1 5530.1 5540.4 5546.5 5422.8 5449.6 5479.2 5527.9 5538.0 5236.5 5412.8 5455.3 5461.6 5485.7 5440.0 5495.0 5525.4 5561.6 5616.3 5149.8 5365.9 5403.1 5441.9 5478.2 05:09:29 INFO - PROCESS | 23723 | 05:09:29 INFO - PROCESS | 23723 | [#5] yui.html Cycles:180 Average:17811.50 Median:4478.37 stddev:23480.84 (524.3%) stddev-sans-first:23511.84 05:09:29 INFO - PROCESS | 23723 | Values: 769.7 778.2 791.3 825.1 881.2 14651.1 15931.9 15945.9 15951.8 16074.5 14841.9 15004.0 15012.5 15021.3 15285.4 671.0 720.2 730.3 787.9 798.9 814.4 821.0 821.0 821.5 823.8 844.0 862.6 871.7 871.7 872.4 688.0 741.0 789.0 854.7 858.2 630.6 630.9 632.4 633.7 634.6 14591.5 15002.1 15003.8 15034.8 15045.5 12499.6 13099.1 13142.0 13185.6 13239.4 12160.4 12211.1 12285.8 12439.5 12561.7 3747.6 3787.3 3806.6 3824.8 3909.3 80826.2 81566.0 82066.6 83245.0 83712.6 11402.5 11417.6 11461.8 11498.8 11539.2 21481.5 21640.7 21796.9 21884.1 22105.9 7838.8 8029.5 8030.1 8078.0 8438.5 4380.4 4450.1 4557.4 4597.6 4644.9 3529.6 3602.8 3620.6 3633.6 3633.9 651.5 653.4 654.9 672.0 682.0 697.8 705.8 711.6 715.9 738.9 8615.0 9230.9 9232.5 9245.5 9502.8 550.6 558.6 639.7 665.7 700.2 4105.3 4311.3 4328.6 4452.4 4504.3 81625.0 82419.0 82615.0 84735.2 87512.0 68946.7 73410.6 73459.3 75215.0 77612.2 53001.2 63534.0 63795.7 64517.0 65197.7 9199.3 9408.6 9777.2 9784.7 9825.4 39794.0 40023.2 40437.2 41750.4 41832.2 55868.0 57892.4 58397.7 58927.1 59336.7 11697.8 11735.8 12163.6 12331.1 12733.2 13501.3 13673.5 13702.8 13795.4 14099.1 12938.0 13168.9 13181.7 13231.5 13858.4 12368.5 12811.9 12853.8 13347.5 13361.8 12878.9 12964.3 13030.2 13040.9 13122.1 10042.4 10292.3 10367.8 10660.1 10843.9 10708.3 10868.9 10947.7 11030.5 11059.8 05:09:29 INFO - PROCESS | 23723 | -------- Summary: end -------- 05:09:29 INFO - PROCESS | 23723 | 05:09:30 INFO - TEST-INFO | 23723: exit 0 05:09:30 INFO - TEST-OK | dromaeo_css | took 1116217ms 05:09:30 INFO - TEST-START | kraken 05:09:30 INFO - operating with platform_type : linux_ 05:09:30 INFO - Initialising browser for kraken test... 05:09:30 INFO - TEST-INFO | started process 24026 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmptf1ti9/profile http://localhost:42457/getInfo.html) 05:09:30 INFO - PROCESS | 24026 | 1482584970562 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4979 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3825 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2829 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 05:09:32 INFO - PROCESS | 24026 | JavaScript error: chrome://global/content/browser-content.js, line 1772: ReferenceError: event is not defined 05:09:32 INFO - PROCESS | 24026 | JavaScript error: chrome://global/content/browser-content.js, line 1772: ReferenceError: event is not defined 05:09:33 INFO - PROCESS | 24026 | JavaScript error: chrome://global/content/browser-content.js, line 1772: ReferenceError: event is not defined 05:09:33 INFO - PROCESS | 24026 | __metrics Screen width/height:1600/1200 05:09:33 INFO - PROCESS | 24026 | colorDepth:24 05:09:33 INFO - PROCESS | 24026 | Browser inner width/height: 1024/697 05:09:33 INFO - PROCESS | 24026 | __metrics 05:09:34 INFO - PROCESS | 24026 | 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" 05:09:34 INFO - PROCESS | 24026 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:09:34 INFO - PROCESS | 24026 | console.error: PushService: 05:09:34 INFO - PROCESS | 24026 | stateChangeProcessEnqueue: Error transitioning state 05:09:34 INFO - PROCESS | 24026 | UnknownError 05:09:35 INFO - TEST-INFO | 24026: exit 0 05:09:35 INFO - Browser initialized. 05:09:35 INFO - Running cycle 1/1 for kraken test... 05:09:35 INFO - TEST-INFO | started process 24114 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmptf1ti9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:09:47 INFO - PROCESS | 24114 | RSS: Main: 147296256 05:09:47 INFO - PROCESS | 24114 | 05:09:49 INFO - PROCESS | 24114 | Cycle 1(1): loaded http://localhost:42457/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:42457/tests/kraken/driver.html?testName=audio-beat-detection) 05:09:50 INFO - PROCESS | 24114 | RSS: Main: 201461760 05:09:50 INFO - PROCESS | 24114 | 05:09:53 INFO - PROCESS | 24114 | Cycle 1(1): loaded http://localhost:42457/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:42457/tests/kraken/driver.html?testName=audio-dft) 05:09:53 INFO - PROCESS | 24114 | RSS: Main: 180502528 05:09:53 INFO - PROCESS | 24114 | 05:09:56 INFO - PROCESS | 24114 | Cycle 1(1): loaded http://localhost:42457/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:42457/tests/kraken/driver.html?testName=audio-fft) 05:09:57 INFO - PROCESS | 24114 | RSS: Main: 262258688 05:09:57 INFO - PROCESS | 24114 | 05:09:59 INFO - PROCESS | 24114 | Cycle 1(1): loaded http://localhost:42457/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:42457/tests/kraken/driver.html?testName=audio-oscillator) 05:10:00 INFO - PROCESS | 24114 | RSS: Main: 173342720 05:10:00 INFO - PROCESS | 24114 | 05:10:02 INFO - PROCESS | 24114 | Cycle 1(1): loaded http://localhost:42457/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:42457/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:10:02 INFO - PROCESS | 24114 | RSS: Main: 177508352 05:10:02 INFO - PROCESS | 24114 | 05:10:05 INFO - PROCESS | 24114 | Cycle 1(1): loaded http://localhost:42457/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:42457/tests/kraken/driver.html?testName=imaging-darkroom) 05:10:06 INFO - PROCESS | 24114 | RSS: Main: 217456640 05:10:06 INFO - PROCESS | 24114 | 05:10:09 INFO - PROCESS | 24114 | Cycle 1(1): loaded http://localhost:42457/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:42457/tests/kraken/driver.html?testName=imaging-desaturate) 05:10:10 INFO - PROCESS | 24114 | RSS: Main: 217137152 05:10:10 INFO - PROCESS | 24114 | 05:10:13 INFO - PROCESS | 24114 | Cycle 1(1): loaded http://localhost:42457/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:42457/tests/kraken/driver.html?testName=json-parse-financial) 05:10:13 INFO - PROCESS | 24114 | RSS: Main: 214310912 05:10:13 INFO - PROCESS | 24114 | 05:10:15 INFO - PROCESS | 24114 | Cycle 1(1): loaded http://localhost:42457/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:42457/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:10:15 INFO - PROCESS | 24114 | RSS: Main: 177754112 05:10:15 INFO - PROCESS | 24114 | 05:10:17 INFO - PROCESS | 24114 | Cycle 1(1): loaded http://localhost:42457/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:42457/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:10:18 INFO - PROCESS | 24114 | RSS: Main: 185511936 05:10:18 INFO - PROCESS | 24114 | 05:10:20 INFO - PROCESS | 24114 | Cycle 1(1): loaded http://localhost:42457/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:42457/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:10:20 INFO - PROCESS | 24114 | RSS: Main: 193245184 05:10:20 INFO - PROCESS | 24114 | 05:10:23 INFO - PROCESS | 24114 | Cycle 1(1): loaded http://localhost:42457/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:42457/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:10:24 INFO - PROCESS | 24114 | RSS: Main: 202575872 05:10:24 INFO - PROCESS | 24114 | 05:10:26 INFO - PROCESS | 24114 | Cycle 1(1): loaded http://localhost:42457/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:42457/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:10:27 INFO - PROCESS | 24114 | RSS: Main: 199458816 05:10:27 INFO - PROCESS | 24114 | 05:10:29 INFO - PROCESS | 24114 | Cycle 1(1): loaded http://localhost:42457/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:42457/tests/kraken/driver.html?testName=ai-astar) 05:10:29 INFO - PROCESS | 24114 | RSS: Main: 251305984 05:10:29 INFO - PROCESS | 24114 | 05:10:29 INFO - PROCESS | 24114 | __start_tp_report 05:10:29 INFO - PROCESS | 24114 | _x_x_mozilla_page_load 05:10:29 INFO - PROCESS | 24114 | _x_x_mozilla_page_load_details 05:10:29 INFO - PROCESS | 24114 | |i|pagename|runs| 05:10:29 INFO - PROCESS | 24114 | |0;ai-astar;109;111;110;111;110;111;110;110;109;111 05:10:29 INFO - PROCESS | 24114 | |1;audio-beat-detection;145;121;123;170;121;121;119;120;146;119 05:10:29 INFO - PROCESS | 24114 | |2;audio-dft;160;160;161;161;164;161;162;160;161;163 05:10:29 INFO - PROCESS | 24114 | |3;audio-fft;75;76;76;111;77;77;77;75;98;75 05:10:29 INFO - PROCESS | 24114 | |4;audio-oscillator;84;84;107;83;83;85;94;84;83;107 05:10:29 INFO - PROCESS | 24114 | |5;imaging-gaussian-blur;117;117;117;117;118;117;117;117;117;117 05:10:29 INFO - PROCESS | 24114 | |6;imaging-darkroom;146;146;146;147;146;146;146;146;145;147 05:10:29 INFO - PROCESS | 24114 | |7;imaging-desaturate;96;96;95;95;95;95;96;96;95;95 05:10:29 INFO - PROCESS | 24114 | |8;json-parse-financial;64;51;51;51;52;56;61;53;53;53 05:10:29 INFO - PROCESS | 24114 | |9;json-stringify-tinderbox;65;66;65;65;65;65;65;66;65;67 05:10:29 INFO - PROCESS | 24114 | |10;stanford-crypto-aes;79;83;81;85;81;82;82;80;80;80 05:10:29 INFO - PROCESS | 24114 | |11;stanford-crypto-ccm;125;125;126;122;123;131;124;126;167;139 05:10:29 INFO - PROCESS | 24114 | |12;stanford-crypto-pbkdf2;172;172;164;168;170;165;168;164;164;163 05:10:29 INFO - PROCESS | 24114 | |13;stanford-crypto-sha256-iterative;67;68;67;61;62;61;62;60;63;61 05:10:29 INFO - PROCESS | 24114 | __end_tp_report 05:10:29 INFO - PROCESS | 24114 | __start_cc_report 05:10:29 INFO - PROCESS | 24114 | _x_x_mozilla_cycle_collect,1575 05:10:29 INFO - PROCESS | 24114 | __end_cc_report 05:10:29 INFO - PROCESS | 24114 | __startTimestamp1482585029465__endTimestamp 05:10:29 INFO - PROCESS | 24114 | 05:10:29 INFO - PROCESS | 24114 | ------- Summary: start ------- 05:10:29 INFO - PROCESS | 24114 | Number of tests: 14 05:10:29 INFO - PROCESS | 24114 | 05:10:29 INFO - PROCESS | 24114 | [#0] ai-astar Cycles:10 Average:110.20 Median:110.50 stddev:0.79 (0.7%) stddev-sans-first:0.71 05:10:29 INFO - PROCESS | 24114 | Values: 109.0 111.0 110.0 111.0 110.0 111.0 110.0 110.0 109.0 111.0 05:10:29 INFO - PROCESS | 24114 | 05:10:29 INFO - PROCESS | 24114 | [#1] audio-beat-detection Cycles:10 Average:130.50 Median:122.00 stddev:17.36 (14.2%) stddev-sans-first:17.60 05:10:29 INFO - PROCESS | 24114 | Values: 145.0 121.0 123.0 170.0 121.0 121.0 119.0 120.0 146.0 119.0 05:10:29 INFO - PROCESS | 24114 | 05:10:29 INFO - PROCESS | 24114 | [#2] audio-dft Cycles:10 Average:161.30 Median:161.00 stddev:1.34 (0.8%) stddev-sans-first:1.33 05:10:29 INFO - PROCESS | 24114 | Values: 160.0 160.0 161.0 161.0 164.0 161.0 162.0 160.0 161.0 163.0 05:10:29 INFO - PROCESS | 24114 | 05:10:29 INFO - PROCESS | 24114 | [#3] audio-fft Cycles:10 Average:81.70 Median:76.50 stddev:12.43 (16.2%) stddev-sans-first:12.94 05:10:29 INFO - PROCESS | 24114 | Values: 75.0 76.0 76.0 111.0 77.0 77.0 77.0 75.0 98.0 75.0 05:10:29 INFO - PROCESS | 24114 | 05:10:29 INFO - PROCESS | 24114 | [#4] audio-oscillator Cycles:10 Average:89.40 Median:84.00 stddev:9.83 (11.7%) stddev-sans-first:10.23 05:10:29 INFO - PROCESS | 24114 | Values: 84.0 84.0 107.0 83.0 83.0 85.0 94.0 84.0 83.0 107.0 05:10:29 INFO - PROCESS | 24114 | 05:10:29 INFO - PROCESS | 24114 | [#5] imaging-gaussian-blur Cycles:10 Average:117.10 Median:117.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 05:10:29 INFO - PROCESS | 24114 | Values: 117.0 117.0 117.0 117.0 118.0 117.0 117.0 117.0 117.0 117.0 05:10:29 INFO - PROCESS | 24114 | 05:10:29 INFO - PROCESS | 24114 | [#6] imaging-darkroom Cycles:10 Average:146.10 Median:146.00 stddev:0.57 (0.4%) stddev-sans-first:0.60 05:10:29 INFO - PROCESS | 24114 | Values: 146.0 146.0 146.0 147.0 146.0 146.0 146.0 146.0 145.0 147.0 05:10:29 INFO - PROCESS | 24114 | 05:10:29 INFO - PROCESS | 24114 | [#7] imaging-desaturate Cycles:10 Average:95.40 Median:95.50 stddev:0.52 (0.5%) stddev-sans-first:0.50 05:10:29 INFO - PROCESS | 24114 | Values: 96.0 96.0 95.0 95.0 95.0 95.0 96.0 96.0 95.0 95.0 05:10:29 INFO - PROCESS | 24114 | 05:10:29 INFO - PROCESS | 24114 | [#8] json-parse-financial Cycles:10 Average:54.50 Median:53.00 stddev:4.53 (8.5%) stddev-sans-first:3.24 05:10:29 INFO - PROCESS | 24114 | Values: 64.0 51.0 51.0 51.0 52.0 56.0 61.0 53.0 53.0 53.0 05:10:29 INFO - PROCESS | 24114 | 05:10:29 INFO - PROCESS | 24114 | [#9] json-stringify-tinderbox Cycles:10 Average:65.40 Median:65.00 stddev:0.70 (1.1%) stddev-sans-first:0.73 05:10:29 INFO - PROCESS | 24114 | Values: 65.0 66.0 65.0 65.0 65.0 65.0 65.0 66.0 65.0 67.0 05:10:29 INFO - PROCESS | 24114 | 05:10:29 INFO - PROCESS | 24114 | [#10] stanford-crypto-aes Cycles:10 Average:81.30 Median:81.50 stddev:1.77 (2.2%) stddev-sans-first:1.67 05:10:29 INFO - PROCESS | 24114 | Values: 79.0 83.0 81.0 85.0 81.0 82.0 82.0 80.0 80.0 80.0 05:10:29 INFO - PROCESS | 24114 | 05:10:29 INFO - PROCESS | 24114 | [#11] stanford-crypto-ccm Cycles:10 Average:130.80 Median:126.00 stddev:13.64 (10.8%) stddev-sans-first:14.31 05:10:29 INFO - PROCESS | 24114 | Values: 125.0 125.0 126.0 122.0 123.0 131.0 124.0 126.0 167.0 139.0 05:10:29 INFO - PROCESS | 24114 | 05:10:29 INFO - PROCESS | 24114 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.00 Median:168.00 stddev:3.46 (2.1%) stddev-sans-first:3.17 05:10:29 INFO - PROCESS | 24114 | Values: 172.0 172.0 164.0 168.0 170.0 165.0 168.0 164.0 164.0 163.0 05:10:29 INFO - PROCESS | 24114 | 05:10:29 INFO - PROCESS | 24114 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.20 Median:62.50 stddev:2.97 (4.8%) stddev-sans-first:2.82 05:10:29 INFO - PROCESS | 24114 | Values: 67.0 68.0 67.0 61.0 62.0 61.0 62.0 60.0 63.0 61.0 05:10:29 INFO - PROCESS | 24114 | -------- Summary: end -------- 05:10:29 INFO - PROCESS | 24114 | 05:10:30 INFO - TEST-INFO | 24114: exit 0 05:10:30 INFO - TEST-OK | kraken | took 60501ms 05:10:30 INFO - SUITE-END | took 1176s 05:10:30 INFO - Completed test suite (00:19:36) 05:10:30 INFO - javascript benchmark 05:10:30 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3178.682131786821, 3233.644299127097, 3243.691849274319, 3273.7217336526396, 3281.3765384576927, 5260.684358938466, 5313.73431328434, 5375.89248215036, 5437.809676661318, 5458.727063646822, 5335.653182543132, 5349.973250133754, 5361.0, 5361.490658387456, 5362.490563396471, 4807.158747219231, 4846.8545943621575, 4883.829065982692, 4889.095517329288, 4950.084234416627, 4922.21244600864, 4984.37695288089, 4986.052649996507, 4987.276844857493, 4992.550670439659, 4838.903221935577, 4946.777395017215, 5019.322391477159, 5021.5229553192385, 5034.84895453137, 4723.102610504027, 4754.144254034272, 4972.105021096201, 5027.321311622939, 5028.472010438888, 4769.403824521934, 4786.282057691375, 4793.0, 4798.736069516175, 4805.879853003668, 5220.947790522122, 5263.02634012705, 5263.157894736823, 5274.55166310864, 5279.049771041211, 5594.1608758686525, 5645.407232240596, 5749.453801888814, 5749.8275051748515, 5807.970960145172, 5743.368229494752, 5785.8553536161935, 5792.304923409218, 5796.594238403313, 5797.304323481167, 5248.448912864171, 5372.677639341613, 5388.46115388458, 5392.379876314196, 5395.244665746799, 5839.737211825478, 5884.264466941632, 5957.404259574095, 5960.135780311831, 6025.216721826135, 5655.0, 5667.914981275284, 5716.171155182476, 5724.0841465366175, 5753.8561535961935, 5815.447532484407, 5827.533797296291, 5827.65034097947, 5828.067509198508, 5833.970830145822, 5488.0, 5525.171224316385, 5766.3368712598585, 5787.913181302284, 5799.478046975708, 5533.778648854081, 5572.804951826748, 5584.91622625661, 5707.543396528268, 5845.620034697731, 4319.481662200493, 4319.4816622005565, 4341.848035318749, 4360.738355698605, 4368.8689339319235, 3861.613838616172, 3873.8063096845044, 3881.941770873439, 3912.139329347539, 3914.197589494147, 5275.0, 5374.462553744673, 5436.728163591804, 5471.863203419866, 5545.889082218414, 5749.511291540182, 5754.309482862083, 5766.452187042224, 5780.335261444903, 5793.536517078624, 5474.671519708831, 5481.040817856859, 5486.972565137149, 5487.972560137254, 5489.1217405214975, 4683.578477937002, 4725.362076119749, 4808.519148085164, 4812.807487700453, 4856.757162141878, 6083.178770865877, 6125.449118208436, 6142.069075791497, 6145.108959200801, 6153.0, 5842.824715258549, 5846.883062338815, 5861.794837180678, 5869.266341707286, 5911.467967882912, 5819.476247137778, 5937.5249980000635, 6131.877362452815, 6135.049067394561, 6137.447629713347, 5628.85927851807, 5646.35066967304, 5653.180288858175, 5701.743421545957, 5759.884802303846, 5774.2782152230975, 5780.248567686174, 5784.595078344475, 5787.623804452697, 5804.506616937439, 5412.972935135299, 5654.7455364509515, 5656.23640808502, 5658.207850900795, 5661.2923384576925, 5437.755301011385, 5451.236826844166, 5509.531689806411, 5510.173473978935, 5515.034868898007, 5483.095289277225, 5483.287172667528, 5491.670499770026, 5495.340559132929, 5503.477169668875, 5501.0648189807025, 5508.586855985737, 5558.44415558441, 5566.248556444828, 5591.356993945586, 5451.672899626035, 5508.9724551376985, 5511.751971161227, 5551.750171242223, 5571.052921003358], "value": 5298.965245130546, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [551.5229326632463, 586.6978506069372, 590.0676930449888, 594.771013159933, 603.5654328883203, 32877.50683739743, 33705.83147084264, 40355.0, 41860.790696046475, 42412.36381454273, 45727.314090288586, 46113.769431152796, 46146.53853461464, 46186.53813461864, 46201.768991155, 8888.511131887759, 9075.818483630323, 9080.182783549479, 9083.818323633523, 9084.227840633554, 8642.092580279075, 8804.64781408743, 8933.731988040368, 10463.372197668165, 10719.249652524326, 10897.400642964634, 10967.945160274227, 10973.451327433637, 10982.06652434544, 10982.560697572088, 10849.0, 10904.890951090547, 10919.563217471292, 10920.344779313187, 10925.617603383844, 10577.206709496742, 10620.40693895914, 10629.0, 10636.361818290927, 10638.255322127454, 41937.79031104856, 42199.36700949488, 42327.36508952368, 42353.788231058956, 42398.0, 3354.480055591387, 3356.832158392095, 3423.4351332030187, 3440.569928758905, 3483.303339332144, 31750.523742143887, 31807.363852723047, 32330.83834580812, 32404.18989525257, 33014.83492582522, 4276.764777937212, 4352.325389564622, 4355.3031514957775, 4565.61192298655, 4593.655475839292, 342598.0, 343059.0, 343119.0, 344609.2769536161, 345718.0, 53139.734301328244, 53617.731911340976, 53798.462015379344, 53821.23251372445, 53882.461175388526, 63352.68323658352, 63354.0, 63355.0, 63521.36478635247, 63571.68214158899, 16489.422870199487, 16912.83087169137, 16981.0, 17005.574860628334, 17022.148892555324, 11003.944980275048, 12768.170068945674, 12910.419031143621, 12914.67713307175, 12932.482700692055, 15950.0, 17549.859259148114, 17577.472675819747, 17991.280348786164, 18541.35105271336, 5868.3251426086545, 6082.908756368658, 6115.1744514490865, 6132.78535251264, 6159.81520554384, 868.4869702055202, 985.4973963278636, 1052.6894566103017, 1069.7432616172064, 1084.2572837606265, 33108.66891331104, 34356.48465273023, 34687.479687804705, 36778.8161059193, 37082.25835483275, 1024.1601886453036, 1028.2339656955467, 1034.3623759413615, 1065.227709910309, 1071.4535586850607, 15860.365585376456, 17139.22873470697, 17308.221130049176, 17311.220995055253, 17338.479845604652, 528419.0, 531445.0, 532090.0, 534202.0, 539593.0, 40177.1964560713, 40513.392299115534, 40555.79722101371, 40592.39111413331, 40622.796886015974, 1398.7552178368744, 1403.6911879386519, 1419.8012278281049, 1432.7564314066842, 1433.6487560547523, 29842.850785745934, 31081.37837243288, 31280.68719312778, 32202.0, 32471.512927306583, 53229.73385133049, 54095.45904540904, 54157.45842541524, 54160.0, 54228.72885635705, 495638.0, 496893.0, 499926.0, 501070.0, 501739.0, 35786.463203051455, 43323.35014974715, 43620.56379436165, 44170.11659766851, 44234.33648495211, 47412.288815668486, 48720.756396217796, 48816.0, 49096.2635560474, 49203.753981229864, 35525.0, 39809.60190398175, 40322.19355612813, 41851.1629767409, 42098.895055247725, 34205.828970854986, 37573.62426375701, 37775.24449510939, 37793.433098502996, 37920.241595168496, 36172.81913590415, 36575.63424365722, 37596.436053459765, 37850.43224351582, 39716.00709982274, 32056.0, 33778.662213378535, 34333.48499772555, 34627.826860865534, 34820.0, 26316.0, 26383.972956850575, 26538.469230614894, 26643.866780665972, 26782.19653410401], "value": 20225.7797591552, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [383.34065407499094, 394.45171212015293, 396.76590811421255, 399.1697269679067, 421.4647397804786, 13460.52888148915, 15415.383384664614, 18291.63416731666, 18569.25722971083, 18934.390576800954, 18384.874571322875, 18885.90557047213, 18904.527386815338, 18930.0, 19158.13788379523, 5511.145772405283, 5521.944780552193, 5709.200711900337, 5735.713214339287, 5736.082226843706, 5586.581006424514, 5591.133374326986, 5600.831975040755, 5607.467290607386, 5611.663300201981, 5454.754536045868, 5581.469760372758, 5586.41342659021, 5588.161775733653, 5589.189567512708, 5569.777208911639, 5594.9720251398885, 5605.1872478490795, 5635.211070450161, 5635.718214089279, 4855.830045948375, 5025.6733312334945, 5036.169032109698, 5050.823221187277, 5050.873728156826, 18644.720329195072, 18784.530386740305, 18803.905980470146, 20954.16183352664, 22019.449513762123, 2222.388843068153, 2332.498512819735, 2422.479166979108, 2503.111395454606, 2906.8401237931907, 13589.252591107484, 14266.3580138894, 14424.855751442456, 14547.0, 14554.781678274834, 1899.2972600138037, 1915.798841121676, 1935.4290484307023, 1953.1406181280333, 1961.8332441742468, 66991.66504167496, 76818.0, 78347.21652783456, 78839.0, 80633.0, 10812.891871081345, 10898.400587967579, 10925.453727313761, 10982.780344393068, 11082.501287442085, 14872.851271487363, 14911.403543858125, 15027.474038408604, 15062.774058389134, 15063.54809355721, 7371.64590111498, 7695.884561731578, 7800.9609951951015, 7948.920510794934, 8022.679092836222, 7345.853082938312, 7354.816129596803, 7404.148522919933, 7553.509021913668, 7554.660040298199, 4683.531646835289, 4696.671233013656, 4730.432348118248, 4738.763061846963, 4819.759012049453, 1899.1928430417017, 1937.5156210947264, 1944.8638595298194, 1982.9816506901484, 1984.563396052866, 885.703289398046, 918.912703293186, 940.9952950235205, 967.1923943507251, 1045.6967479431032, 16963.74554381685, 18351.265949362143, 18639.534011649546, 18778.795531182583, 18944.621107577772, 932.6036434515304, 956.2063487305659, 976.2921881635899, 984.0, 1019.4240254256322, 9828.459434731016, 10583.523741431654, 10598.31110977784, 10667.626633067805, 10692.465376731132, 401892.0, 407140.0, 409447.0, 414185.0, 415345.0, 73728.26271737214, 86341.56829215813, 89444.5527772357, 90538.0, 91168.54415727878, 977.750673578233, 1017.4658304390255, 1029.4955471818755, 1041.1358572384902, 1042.2652030318607, 16284.755728664317, 16399.180040997664, 16878.74681879748, 17059.829401705825, 17071.658566828843, 40760.79619601883, 41090.79454602827, 41158.794206028775, 41202.79398603108, 42633.78683106565, 56248.437515625956, 58043.70978145082, 58148.70925645345, 58960.70519647374, 59707.104393435, 8371.874421883555, 8493.87259191125, 8540.530270835165, 8696.130386961253, 8775.91224087751, 10645.840312395476, 12484.375781211085, 12754.234745915275, 12981.480740770263, 13077.149985250928, 10875.836862446911, 10936.56253749841, 10968.506417211085, 11572.537098515964, 11626.651200463999, 10829.512671929624, 10917.781644367227, 11095.611653592085, 11349.0, 11819.763604728028, 11269.323840569592, 11377.658670239905, 11445.25605835618, 11650.669866026998, 11667.824982625438, 8256.504609723435, 8416.537090460084, 8427.115152908857, 8472.576371181292, 8620.267277281502, 10542.841857371992, 10982.341059536453, 11169.497372618414, 11298.322100673986, 11588.884111159117], "value": 8985.630501153111, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1442.2860683961442, 1470.455931305417, 1619.9352025918963, 1782.884112532686, 1783.4827899909028, 8090.635921383538, 8573.57132143392, 8591.785205369868, 8628.654853805856, 8636.222739953402, 7589.582572958485, 7826.0, 8176.38677099217, 8375.455595386296, 8478.19457151571, 2305.1470955746354, 2333.53329334133, 2336.217367181996, 2350.5181437805295, 2354.9175778847743, 2327.4530485336018, 2347.94130146746, 2387.379281386843, 2387.5941090014653, 2388.414838364603, 2291.278247352087, 2296.7932886040253, 2318.246569864792, 2318.431984163882, 2321.0483701682315, 2233.0, 2247.325802259324, 2251.9887400562975, 2256.9210077647203, 2260.9660855087186, 2295.3228797504776, 2318.652202169671, 2320.245920075982, 2322.3381336319235, 2328.441174118216, 8399.11809260031, 8589.184027517376, 8592.140785921358, 8595.226429621302, 8607.82784344316, 5140.768665410065, 5290.391604965402, 5291.656042357235, 5292.629515933886, 5295.973520132375, 3148.9608429218533, 3260.690234427749, 3409.07954852191, 3411.7441191910457, 3416.7266618670446, 1043.5929987304958, 1057.6245432871297, 1072.3405105859918, 1086.0876863434673, 1097.9451027448677, 11321.660350189508, 11398.772024559545, 11489.597864074718, 11515.481803318788, 11532.596359127474, 4481.0, 4520.954790452053, 4556.954430455719, 4690.788914498856, 4702.271147972001, 9529.285303602119, 9533.761655958519, 9557.569909353957, 9560.856587151198, 9568.712938611852, 5223.869403264949, 5230.869228269324, 5236.450172731885, 5237.3453318335205, 5238.214267859851, 4262.531121576624, 4358.5205627380965, 4514.864554063383, 4691.507391723888, 4838.588719958843, 3855.768653880776, 3924.117073658404, 3958.8416463341146, 3988.2422339755353, 4000.1599664070873, 1315.9934200328937, 1321.0818481155504, 1321.378951892609, 1324.0466863858007, 1331.6138319887125, 795.1452188896959, 908.2506931781306, 912.7763697894059, 922.2431268433645, 942.9198518126036, 5516.531094856902, 5616.9157462638095, 5631.2397826293745, 5665.291838520185, 5744.942550574524, 890.643742503004, 910.2672348759312, 915.9542022898962, 938.671464987249, 946.5787724462685, 3806.200697853482, 3833.3291673956946, 3882.902927426837, 3916.8629097981434, 3929.135590170158, 10148.0, 10169.745756356151, 10269.794604108025, 10280.588776448858, 10628.946855265829, 10086.092251697235, 10168.237382196365, 10184.796304073878, 10192.286539942133, 10194.388336699822, 7488.5506869588, 7709.074911010603, 7754.728584499629, 7822.0, 7836.92163078362, 5757.107648314518, 5823.0, 5864.794732184353, 5920.940790592039, 6007.90988135187, 9246.0, 9272.211861991806, 9292.303077269096, 9360.11078947499, 9404.811903761749, 9379.953100234456, 9509.0, 9529.714108576754, 9602.08780165883, 9626.278029147701, 6547.836304092436, 6819.318068193141, 7304.926950730424, 7365.116186057708, 7524.134724506752, 7932.246436588515, 7997.320227780704, 8005.919940800517, 8009.719659811879, 8020.919790802017, 7223.747168849065, 7271.272872712686, 7408.407327413686, 7530.246975302426, 7531.623418828927, 7047.894281585678, 7181.174164971095, 7202.459815513753, 7205.387542058774, 7443.218462061405, 6459.61242325462, 6587.4071333580205, 6588.04473351371, 6588.176477940257, 6588.472922166311, 4782.880427989189, 4815.855524334275, 4884.65807393479, 4972.8756781080765, 5015.774290156879, 4958.107540642719, 4970.5029497051455, 5153.252778347193, 5173.793048278027, 5189.169732842727], "value": 4480.155682205432, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [377.8899655832499, 389.3595036165508, 394.3335762561272, 394.9940750888739, 401.90756126091014, 5213.687178769276, 5219.321488206538, 5221.269022336871, 5244.292020577221, 5250.921236181455, 4910.7299098549565, 4990.625703072266, 5048.671836330636, 5097.541221290083, 5198.818041368553, 4121.609466509417, 4141.503019637633, 4141.6065473779945, 4154.940490175001, 4167.770772607505, 4117.0, 4134.441850350209, 4151.73013754105, 4161.521425036114, 4164.354525048622, 3887.280853042182, 3933.331333673268, 4024.1146947671614, 4050.7974601269966, 4100.323446631317, 4070.7354021988476, 4074.470318858559, 4082.0, 4094.28350038745, 4106.32245679464, 3674.0527520414225, 3688.8340024698955, 3695.020819482839, 3695.981520092409, 3700.111973126457, 4808.403462130445, 5016.247562865563, 5106.64673861427, 5108.0, 5110.207917772751, 4715.41057367829, 4720.33915251865, 4728.314394412825, 4734.668573199878, 4749.833755818529, 5365.382980957201, 5437.782488700447, 5458.536024437927, 5472.017750326042, 5473.221203109006, 3974.602539746031, 4071.145059537501, 4079.8980025499304, 4086.162336720965, 4123.422720819088, 5564.304461942257, 5597.7201139943245, 5664.20701101846, 5664.631798933056, 5673.23411339468, 4183.769892655933, 4194.517630472474, 4206.810693518799, 4206.831726730958, 4209.915801683949, 3899.5710471848074, 4261.893452663646, 4272.102858399709, 4293.248681480728, 4298.742075475481, 4625.653076019312, 4629.4676112247525, 4651.186042442559, 4664.906701866012, 4670.1126785910565, 5468.972655136698, 5474.835754927359, 5478.945210547843, 5508.366537848118, 5525.557955363642, 1327.8576997372963, 1375.4498200719793, 1379.3103448275863, 1387.257817067864, 1391.193107997359, 954.9474778887089, 955.571420726019, 956.9090936361034, 959.1271942532262, 961.8890181839986, 5076.0, 5150.536451719363, 5153.381594208644, 5184.422748303734, 5196.168613021899, 5418.566514678816, 5451.427600102012, 5461.453854614586, 5466.508014278734, 5482.451754824486, 5052.317937078521, 5144.0, 5174.922376164285, 5200.115980283361, 5225.529702326809, 2861.513542697705, 2865.5271880139544, 2872.3967966727223, 2874.375977572665, 2903.288694269875, 5472.972635136878, 5667.489925906688, 5684.8010319638615, 5698.7747634258, 5726.284214473191, 5514.92458970505, 5536.667799932017, 5541.889162216814, 5544.1960915667005, 5547.281831902339, 5634.703698336437, 5671.234383358276, 5678.0, 5708.686022268732, 5731.312242530923, 5309.150535914235, 5448.7548060336585, 5473.917891231555, 5634.0, 5637.633553819152, 5549.972250138723, 5556.860843526986, 5567.24842146305, 5597.5726189821335, 5598.888022239451, 5733.741981610921, 5818.563607729353, 5839.445252700986, 5859.179714840011, 5916.674582897982, 4051.2505186540584, 4054.39184122384, 4073.287174744467, 4074.022234663719, 4075.2460794753065, 5362.597805164551, 5486.61593688438, 5539.14143307788, 5607.523360514402, 5617.6910269933915, 5510.9448905510435, 5529.115341545334, 5530.1197592818635, 5540.445955404427, 5546.500814926676, 5422.755975981169, 5449.564034877121, 5479.205515200353, 5527.944720552743, 5537.950958145512, 5236.476352364733, 5412.78348866041, 5455.290812194389, 5461.590380721383, 5485.6960076768655, 5439.972800135974, 5494.972525137348, 5525.4474552546035, 5561.63849349791, 5616.326040875121, 5149.768260428365, 5365.946340536545, 5403.108487099585, 5441.8981180726605, 5478.233047373451], "value": 4200.165115249548, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [769.6690423118059, 778.1790211327047, 791.2522666080553, 825.1253671108625, 881.1937077574019, 14651.12093274403, 15931.920340398296, 15945.920270398661, 15951.760723589126, 16074.517764467055, 14841.925790371033, 15004.0, 15012.549623511284, 15021.32404041818, 15285.388584456636, 671.03036112817, 720.240146645289, 730.2551397574471, 787.94484386093, 798.9081255655601, 814.3851392198882, 820.995895020524, 821.0147822612859, 821.4701517521195, 823.7858156879224, 843.9915600843974, 862.6247582301719, 871.6861929705301, 871.7079778274285, 872.402404353017, 688.0333131949619, 741.0329519976428, 789.0491957191571, 854.6880388658158, 858.1804376820122, 630.6090224061065, 630.8517498387876, 632.3897438971414, 633.6704913445029, 634.6255709131589, 14591.489297874523, 15002.099874007594, 15003.774943375858, 15034.774478382833, 15045.473408430762, 12499.62578958397, 13099.148555343967, 13142.0, 13185.60443186706, 13239.404226809816, 12160.391980401033, 12211.145219834616, 12285.754284914252, 12439.502419903192, 12561.748765024648, 3747.6065013173497, 3787.261484010619, 3806.638369354907, 3824.827882745283, 3909.2767837949787, 80826.19173808246, 81566.0, 82066.58966705189, 83245.0, 83712.58143709303, 11402.543898243977, 11417.60038398652, 11461.770764584662, 11498.827517587242, 11539.19225654213, 21481.46296342604, 21640.675389869164, 21796.89101554482, 21884.12463501442, 22105.889470552545, 7838.843223135619, 8029.518228906284, 8030.116687164407, 8077.959610202029, 8438.493690378475, 4380.364847097217, 4450.109978004412, 4557.384753058294, 4597.632189424838, 4644.930326045112, 3529.6470352964498, 3602.8290805488323, 3620.637936206411, 3633.6184700606054, 3633.927321453556, 651.5471747135695, 653.4282502810041, 654.8755736410067, 671.9529632925648, 681.9693113809891, 697.7592730507967, 705.8059033765755, 711.5872793779596, 715.9463040271896, 738.8596166728305, 8615.003298087095, 9230.907690923139, 9232.492212928353, 9245.491497967541, 9502.762430939281, 550.5513006899364, 558.6148732450233, 639.7153266796231, 665.7270519087248, 700.2122612061431, 4105.281575724236, 4311.288637374798, 4328.567143285646, 4452.4211852458975, 4504.324351347324, 81625.0, 82419.0, 82615.0, 84735.15264847272, 87512.0, 68946.65526672335, 73410.63294683493, 73459.26540734524, 75215.0, 77612.2238777605, 53001.2049819253, 63534.0, 63795.68102159553, 64517.0, 65197.674011629635, 9199.310051746279, 9408.64783804049, 9777.21782257432, 9784.706458806248, 9825.4104753715, 39794.0, 40023.1995360097, 40437.1912561753, 41750.37374439446, 41832.16335673209, 55868.0, 57892.421075788705, 58397.70801145967, 58927.11609325778, 59336.70331648487, 11697.824532632187, 11735.767744387058, 12163.57427490069, 12331.136820422484, 12733.236005839679, 13501.257430841006, 13673.521426750414, 13702.794458083335, 13795.448182072605, 14099.083559568595, 12938.0, 13168.868311316764, 13181.736365272449, 13231.536896208587, 13858.445662173803, 12368.505259789508, 12811.871881281068, 12853.807192892302, 13347.532836350681, 13361.799573006607, 12878.93560532229, 12964.28696421668, 13030.226438097383, 13040.869591304345, 13122.14706044104, 10042.447665378475, 10292.279540432397, 10367.792644147226, 10660.093892019266, 10843.891561084289, 10708.250422470664, 10868.891311086787, 10947.671569852917, 11030.50362733663, 11059.778804424028], "value": 7141.127460501185, "unit": "score"}], "name": "dromaeo_css", "value": 7112.1833842157575, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 111.0, 110.0, 111.0, 110.0, 111.0, 110.0, 110.0, 109.0, 111.0], "value": 110.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 121.0, 123.0, 170.0, 121.0, 121.0, 119.0, 120.0, 146.0, 119.0], "value": 130.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 160.0, 161.0, 161.0, 164.0, 161.0, 162.0, 160.0, 161.0, 163.0], "value": 161.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 76.0, 111.0, 77.0, 77.0, 77.0, 75.0, 98.0, 75.0], "value": 81.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 107.0, 83.0, 83.0, 85.0, 94.0, 84.0, 83.0, 107.0], "value": 89.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 118.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 117.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 147.0, 146.0, 146.0, 146.0, 146.0, 145.0, 147.0], "value": 146.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 95.0, 95.0, 95.0, 95.0, 96.0, 96.0, 95.0, 95.0], "value": 95.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 51.0, 51.0, 51.0, 52.0, 56.0, 61.0, 53.0, 53.0, 53.0], "value": 54.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 65.0, 65.0, 65.0, 65.0, 65.0, 66.0, 65.0, 67.0], "value": 65.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 83.0, 81.0, 85.0, 81.0, 82.0, 82.0, 80.0, 80.0, 80.0], "value": 81.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [125.0, 125.0, 126.0, 122.0, 123.0, 131.0, 124.0, 126.0, 167.0, 139.0], "value": 130.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 172.0, 164.0, 168.0, 170.0, 165.0, 168.0, 164.0, 164.0, 163.0], "value": 167.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 67.0, 61.0, 62.0, 61.0, 62.0, 60.0, 63.0, 61.0], "value": 63.2, "unit": "score"}], "name": "kraken", "value": 1493.9, "alertThreshold": 2.0}]} 05:10:30 INFO - Return code: 0 05:10:30 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:10:30 INFO - # TBPL SUCCESS # 05:10:30 INFO - Running post-action listener: _resource_record_post_action 05:10:30 INFO - [mozharness: 2016-12-24 13:10:30.790498Z] Finished run-tests step (success) 05:10:30 INFO - Running post-run listener: _resource_record_post_run 05:10:30 INFO - Total resource usage - Wall time: 1194s; CPU: 13.0%; Read bytes: 4096; Write bytes: 412340224; Read time: 12; Write time: 270292 05:10:30 INFO - TinderboxPrint: CPU usage
13.0% 05:10:30 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 05:10:30 INFO - TinderboxPrint: I/O write bytes / time
412,340,224 / 270,292 05:10:30 INFO - TinderboxPrint: CPU idle
8,285.0 (86.9%) 05:10:30 INFO - TinderboxPrint: CPU user
1,198.2 (12.6%) 05:10:30 INFO - TinderboxPrint: Swap in / out
0 / 0 05:10:30 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 116056064; Read time: 0; Write time: 72776 05:10:30 INFO - run-tests - Wall time: 1177s; CPU: 13.0%; Read bytes: 0; Write bytes: 295395328; Read time: 0; Write time: 197492 05:10:31 INFO - Running post-run listener: _upload_blobber_files 05:10:31 INFO - Blob upload gear active. 05:10:31 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:10:31 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 05:10:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:10:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:10:31 INFO - (blobuploader) - INFO - Open directory for files ... 05:10:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:10:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:10:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:10:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:10:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:10:32 INFO - (blobuploader) - INFO - Done attempting. 05:10:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 05:10:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:10:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:10:33 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 05:10:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:10:33 INFO - (blobuploader) - INFO - Done attempting. 05:10:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:10:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:10:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:10:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:10:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:10:34 INFO - (blobuploader) - INFO - Done attempting. 05:10:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:10:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:10:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:10:38 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:10:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:10:38 INFO - (blobuploader) - INFO - Done attempting. 05:10:38 INFO - (blobuploader) - INFO - Iteration through files over. 05:10:38 INFO - Return code: 0 05:10:38 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:10:38 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:10:38 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3ae72c6f40ab789efd3cbadd30f076d8ad11c5c41513fcaa3e48c090ce836b721118829e6edcde24ecf11c19a515933dd2eaaa4ec94d6758d152969675913c42", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8cbec4ef3a40beda770ecfad8c7946fb6f7f6a71a2a5a91ac6dff014f9a9304885e9bc4b2689307eefcb2ce73368fc1897d87d84bfa88bb73cc8a21bab014725", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8959f7eaeaea9d40684f1543b4eed6c70cda438d16563c08c1ace0cae51f163d17360f07809e95cbfd930622fef00b0d950bfc7e364093874373396ca50a8146", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6d151f31ffe1726d29c0e188cd84d507a70f0796484f7e6b97d16beff42656aad629969c37c25f37171088b37602025ccafcb6b6f611575ebd378b65072099bd"} 05:10:38 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:10:38 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:10:38 INFO - Contents: 05:10:38 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3ae72c6f40ab789efd3cbadd30f076d8ad11c5c41513fcaa3e48c090ce836b721118829e6edcde24ecf11c19a515933dd2eaaa4ec94d6758d152969675913c42", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8cbec4ef3a40beda770ecfad8c7946fb6f7f6a71a2a5a91ac6dff014f9a9304885e9bc4b2689307eefcb2ce73368fc1897d87d84bfa88bb73cc8a21bab014725", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8959f7eaeaea9d40684f1543b4eed6c70cda438d16563c08c1ace0cae51f163d17360f07809e95cbfd930622fef00b0d950bfc7e364093874373396ca50a8146", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6d151f31ffe1726d29c0e188cd84d507a70f0796484f7e6b97d16beff42656aad629969c37c25f37171088b37602025ccafcb6b6f611575ebd378b65072099bd"} 05:10:38 INFO - Running post-run listener: copy_logs_to_upload_dir 05:10:38 INFO - Copying logs to upload dir... 05:10:38 INFO - mkdir: /builds/slave/test/build/upload/logs 05:10:38 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1239.306539 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2016-12-24 05:10:37.684224) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-24 05:10:37.687998) ========= 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=d6bbd613908ccac0c6c9cb47000001f7-1482576190.421265-884628426 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/sha512/3ae72c6f40ab789efd3cbadd30f076d8ad11c5c41513fcaa3e48c090ce836b721118829e6edcde24ecf11c19a515933dd2eaaa4ec94d6758d152969675913c42", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8cbec4ef3a40beda770ecfad8c7946fb6f7f6a71a2a5a91ac6dff014f9a9304885e9bc4b2689307eefcb2ce73368fc1897d87d84bfa88bb73cc8a21bab014725", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8959f7eaeaea9d40684f1543b4eed6c70cda438d16563c08c1ace0cae51f163d17360f07809e95cbfd930622fef00b0d950bfc7e364093874373396ca50a8146", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6d151f31ffe1726d29c0e188cd84d507a70f0796484f7e6b97d16beff42656aad629969c37c25f37171088b37602025ccafcb6b6f611575ebd378b65072099bd"} build_url:https://queue.taskcluster.net/v1/task/dx8uU0KPQWW9lHCdmv6MUg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011816 build_url: 'https://queue.taskcluster.net/v1/task/dx8uU0KPQWW9lHCdmv6MUg/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/sha512/3ae72c6f40ab789efd3cbadd30f076d8ad11c5c41513fcaa3e48c090ce836b721118829e6edcde24ecf11c19a515933dd2eaaa4ec94d6758d152969675913c42", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8cbec4ef3a40beda770ecfad8c7946fb6f7f6a71a2a5a91ac6dff014f9a9304885e9bc4b2689307eefcb2ce73368fc1897d87d84bfa88bb73cc8a21bab014725", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8959f7eaeaea9d40684f1543b4eed6c70cda438d16563c08c1ace0cae51f163d17360f07809e95cbfd930622fef00b0d950bfc7e364093874373396ca50a8146", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6d151f31ffe1726d29c0e188cd84d507a70f0796484f7e6b97d16beff42656aad629969c37c25f37171088b37602025ccafcb6b6f611575ebd378b65072099bd"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-24 05:10:37.714070) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-24 05:10:37.714390) ========= 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=d6bbd613908ccac0c6c9cb47000001f7-1482576190.421265-884628426 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004518 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-24 05:10:37.797459) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-24 05:10:37.797793) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-24 05:10:37.798089) ========= ========= Total master_lag: 0.17 =========