builder: graphics_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-017 starttime: 1482822001.24 results: success (0) buildid: 20161226221337 builduid: 24828ac6d39d438982f2aa24f0a93d37 revision: 2f4e31c8bc98d606f7631c15bbff41c0ed781136 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-26 23:00:01.239716) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-26 23:00:01.240174) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-26 23:00:01.240498) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-26 23:00:01.256152) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-26 23:00:01.256454) ========= 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=0333a256a5e4f052382dd180000001b1-1482820167.797212-42797055 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-12-26 23:00:01-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 88.5M=0s 2016-12-26 23:00:01 (88.5 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.128848 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-26 23:00:01.435720) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-26 23:00:01.441086) ========= 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=0333a256a5e4f052382dd180000001b1-1482820167.797212-42797055 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016910 ========= master_lag: 0.01 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-26 23:00:01.470421) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-26 23:00:01.470770) ========= bash -c 'python archiver_client.py mozharness --repo projects/graphics --rev 2f4e31c8bc98d606f7631c15bbff41c0ed781136 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/graphics --rev 2f4e31c8bc98d606f7631c15bbff41c0ed781136 --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=0333a256a5e4f052382dd180000001b1-1482820167.797212-42797055 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-12-26 23:00:01,731 truncating revision to first 12 chars 2016-12-26 23:00:01,731 Setting DEBUG logging. 2016-12-26 23:00:01,731 attempt 1/10 2016-12-26 23:00:01,731 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/graphics/2f4e31c8bc98?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-26 23:00:02,326 unpacking tar archive at: graphics-2f4e31c8bc98/testing/mozharness/ program finished with exit code 0 elapsedTime=0.955484 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-26 23:00:02.442983) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-26 23:00:02.446382) ========= script_repo_revision: 2f4e31c8bc98d606f7631c15bbff41c0ed781136 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-26 23:00:02.446826) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-26 23:00:02.447145) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-26 23:00:02.456768) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 45 secs) (at 2016-12-26 23:00:02.457118) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Graphics --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics 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', 'Graphics', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Graphics'] 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=0333a256a5e4f052382dd180000001b1-1482820167.797212-42797055 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 23:00:02 INFO - MultiFileLogger online at 20161226 23:00:02 in /builds/slave/test 23:00:02 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Graphics --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics 23:00:02 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 23:00:02 INFO - {'append_to_log': False, 23:00:02 INFO - 'base_work_dir': '/builds/slave/test', 23:00:02 INFO - 'blob_upload_branch': 'Graphics', 23:00:02 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 23:00:02 INFO - 'branch': 'Graphics', 23:00:02 INFO - 'buildbot_json_path': 'buildprops.json', 23:00:02 INFO - 'config_files': ('talos/linux_config.py',), 23:00:02 INFO - 'default_actions': ('clobber', 23:00:02 INFO - 'read-buildbot-config', 23:00:02 INFO - 'download-and-extract', 23:00:02 INFO - 'populate-webroot', 23:00:02 INFO - 'create-virtualenv', 23:00:02 INFO - 'install', 23:00:02 INFO - 'run-tests'), 23:00:02 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 23:00:02 INFO - 'download_minidump_stackwalk': True, 23:00:02 INFO - 'download_symbols': 'ondemand', 23:00:02 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 23:00:02 INFO - 'tooltool.py': '/tools/tooltool.py', 23:00:02 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 23:00:02 INFO - '/tools/misc-python/virtualenv.py')}, 23:00:02 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 23:00:02 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 23:00:02 INFO - 'installer_path': 'installer.exe', 23:00:02 INFO - 'log_level': 'info', 23:00:02 INFO - 'log_name': 'talos', 23:00:02 INFO - 'log_to_console': True, 23:00:02 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 23:00:02 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 23:00:02 INFO - 'opt_config_files': (), 23:00:02 INFO - 'pip_index': False, 23:00:02 INFO - 'sps_profile': False, 23:00:02 INFO - 'sps_profile_interval': 0, 23:00:02 INFO - 'suite': 'dromaeojs', 23:00:02 INFO - 'system_bits': '32', 23:00:02 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 23:00:02 INFO - 'title': 'talos-linux64-ix-017', 23:00:02 INFO - 'tooltool_cache': '/builds/tooltool_cache', 23:00:02 INFO - 'use_talos_json': True, 23:00:02 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 23:00:02 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 23:00:02 INFO - 'work_dir': 'build'} 23:00:02 INFO - [mozharness: 2016-12-27 07:00:02.837117Z] Running clobber step. 23:00:02 INFO - Running pre-action listener: _resource_record_pre_action 23:00:02 INFO - Running main action method: clobber 23:00:02 INFO - rmtree: /builds/slave/test/build 23:00:02 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 23:00:03 INFO - Running post-action listener: _resource_record_post_action 23:00:03 INFO - [mozharness: 2016-12-27 07:00:03.820493Z] Finished clobber step (success) 23:00:03 INFO - [mozharness: 2016-12-27 07:00:03.820592Z] Running read-buildbot-config step. 23:00:03 INFO - Running pre-action listener: _resource_record_pre_action 23:00:03 INFO - Running main action method: read_buildbot_config 23:00:03 INFO - Using buildbot properties: 23:00:03 INFO - { 23:00:03 INFO - "project": "", 23:00:03 INFO - "product": "firefox", 23:00:03 INFO - "script_repo_revision": "default", 23:00:03 INFO - "builddir": "graphics_ubuntu64_hw_test-dromaeojs", 23:00:03 INFO - "repository": "", 23:00:03 INFO - "buildername": "Ubuntu HW 12.04 x64 graphics talos dromaeojs", 23:00:03 INFO - "buildid": "20161226221337", 23:00:03 INFO - "pgo_build": "False", 23:00:03 INFO - "basedir": "/builds/slave/test", 23:00:03 INFO - "buildnumber": 34, 23:00:03 INFO - "slavename": "talos-linux64-ix-017", 23:00:03 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 23:00:03 INFO - "platform": "ubuntu64_hw", 23:00:03 INFO - "scheduler": "tests-graphics-linux64-talos", 23:00:03 INFO - "branch": "graphics", 23:00:03 INFO - "repo_path": "projects/graphics", 23:00:03 INFO - "revision": "2f4e31c8bc98d606f7631c15bbff41c0ed781136", 23:00:03 INFO - "stage_platform": "linux64", 23:00:03 INFO - "builduid": "24828ac6d39d438982f2aa24f0a93d37", 23:00:03 INFO - "slavebuilddir": "test" 23:00:03 INFO - } 23:00:03 INFO - Found installer url https://queue.taskcluster.net/v1/task/bbC1Ymu0Sca5raJ3Fbmu7w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2. 23:00:03 INFO - Running post-action listener: _resource_record_post_action 23:00:03 INFO - [mozharness: 2016-12-27 07:00:03.823048Z] Finished read-buildbot-config step (success) 23:00:03 INFO - [mozharness: 2016-12-27 07:00:03.823137Z] Running download-and-extract step. 23:00:03 INFO - Running pre-action listener: _resource_record_pre_action 23:00:03 INFO - Running main action method: download_and_extract 23:00:03 INFO - mkdir: /builds/slave/test/build/tests 23:00:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:00:03 INFO - trying https://queue.taskcluster.net/v1/task/bbC1Ymu0Sca5raJ3Fbmu7w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 23:00:03 INFO - Downloading https://queue.taskcluster.net/v1/task/bbC1Ymu0Sca5raJ3Fbmu7w/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 23:00:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bbC1Ymu0Sca5raJ3Fbmu7w/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 23:00:04 INFO - Downloaded 1361 bytes. 23:00:04 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 23:00:04 INFO - Using the following test package requirements: 23:00:04 INFO - {u'common': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 23:00:04 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 23:00:04 INFO - u'firefox-53.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 23:00:04 INFO - u'gtest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 23:00:04 INFO - u'firefox-53.0a1.en-US.linux-x86_64.gtest.tests.zip'], 23:00:04 INFO - u'jittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 23:00:04 INFO - u'jsshell-linux-x86_64.zip'], 23:00:04 INFO - u'mochitest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 23:00:04 INFO - u'firefox-53.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 23:00:04 INFO - u'mozbase': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 23:00:04 INFO - u'reftest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 23:00:04 INFO - u'firefox-53.0a1.en-US.linux-x86_64.reftest.tests.zip'], 23:00:04 INFO - u'talos': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 23:00:04 INFO - u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'], 23:00:04 INFO - u'web-platform': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 23:00:04 INFO - u'firefox-53.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 23:00:04 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 23:00:04 INFO - u'firefox-53.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 23:00:04 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 23:00:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bbC1Ymu0Sca5raJ3Fbmu7w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 23:00:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bbC1Ymu0Sca5raJ3Fbmu7w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 23:00:04 INFO - Fetch https://queue.taskcluster.net/v1/task/bbC1Ymu0Sca5raJ3Fbmu7w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 23:00:05 INFO - Expected file size: 20236321 23:00:05 INFO - Obtained file size: 20236321 23:00:09 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 23:00:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bbC1Ymu0Sca5raJ3Fbmu7w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 23:00:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bbC1Ymu0Sca5raJ3Fbmu7w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 23:00:09 INFO - Fetch https://queue.taskcluster.net/v1/task/bbC1Ymu0Sca5raJ3Fbmu7w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 23:00:09 INFO - Expected file size: 20236321 23:00:09 INFO - Obtained file size: 20236321 23:00:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bbC1Ymu0Sca5raJ3Fbmu7w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip 23:00:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bbC1Ymu0Sca5raJ3Fbmu7w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 23:00:14 INFO - Fetch https://queue.taskcluster.net/v1/task/bbC1Ymu0Sca5raJ3Fbmu7w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip into memory 23:00:14 INFO - Expected file size: 14409776 23:00:14 INFO - Obtained file size: 14409776 23:00:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:00:15 INFO - trying https://queue.taskcluster.net/v1/task/bbC1Ymu0Sca5raJ3Fbmu7w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 23:00:15 INFO - Downloading https://queue.taskcluster.net/v1/task/bbC1Ymu0Sca5raJ3Fbmu7w/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 23:00:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bbC1Ymu0Sca5raJ3Fbmu7w/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 23:00:16 INFO - Downloaded 55925577 bytes. 23:00:16 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/bbC1Ymu0Sca5raJ3Fbmu7w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 23:00:16 INFO - mkdir: /builds/slave/test/properties 23:00:16 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 23:00:16 INFO - Writing to file /builds/slave/test/properties/build_url 23:00:16 INFO - Contents: 23:00:16 INFO - build_url:https://queue.taskcluster.net/v1/task/bbC1Ymu0Sca5raJ3Fbmu7w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 23:00:16 INFO - Running post-action listener: _resource_record_post_action 23:00:16 INFO - Running post-action listener: set_extra_try_arguments 23:00:16 INFO - [mozharness: 2016-12-27 07:00:16.877239Z] Finished download-and-extract step (success) 23:00:16 INFO - [mozharness: 2016-12-27 07:00:16.877470Z] Running populate-webroot step. 23:00:16 INFO - Running pre-action listener: _resource_record_pre_action 23:00:16 INFO - Running main action method: populate_webroot 23:00:16 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 23:00:16 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 23:00:16 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 23:00:16 INFO - '--tppagecycles', 23:00:16 INFO - '7'], 23:00:16 INFO - 'tests': ['tsvgm']}}, 23:00:16 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 23:00:16 INFO - 'tests': ['tresize', 'tcanvasmark']}, 23:00:16 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 23:00:16 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 23:00:16 INFO - 'tests': ['dromaeo_css', 'kraken']}, 23:00:16 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 23:00:16 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 23:00:16 INFO - 'talos_options': ['--disable-e10s'], 23:00:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:00:16 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 23:00:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:00:16 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 23:00:16 INFO - 'talos_options': ['--disable-e10s'], 23:00:16 INFO - 'tests': ['damp', 'tps']}, 23:00:16 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 23:00:16 INFO - 'tests': ['damp', 'tps']}, 23:00:16 INFO - 'g3': {'talos_options': ['--disable-e10s'], 23:00:16 INFO - 'tests': ['dromaeo_dom']}, 23:00:16 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 23:00:16 INFO - 'g4': {'talos_options': ['--disable-e10s'], 23:00:16 INFO - 'tests': ['basic_compositor_video']}, 23:00:16 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 23:00:16 INFO - 'other': {'talos_options': ['--disable-e10s'], 23:00:16 INFO - 'tests': ['a11yr', 23:00:16 INFO - 'ts_paint', 23:00:16 INFO - 'tpaint', 23:00:16 INFO - 'sessionrestore', 23:00:16 INFO - 'sessionrestore_no_auto_restore', 23:00:16 INFO - 'tabpaint']}, 23:00:16 INFO - 'other-e10s': {'tests': ['a11yr', 23:00:16 INFO - 'ts_paint', 23:00:16 INFO - 'tpaint', 23:00:16 INFO - 'sessionrestore', 23:00:16 INFO - 'sessionrestore_no_auto_restore', 23:00:16 INFO - 'tabpaint']}, 23:00:16 INFO - 'other-e10s_l64': {'tests': ['a11yr', 23:00:16 INFO - 'ts_paint', 23:00:16 INFO - 'tpaint', 23:00:16 INFO - 'sessionrestore', 23:00:16 INFO - 'sessionrestore_no_auto_restore', 23:00:16 INFO - 'tabpaint']}, 23:00:16 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 23:00:16 INFO - 'ts_paint', 23:00:16 INFO - 'tpaint', 23:00:16 INFO - 'sessionrestore', 23:00:16 INFO - 'sessionrestore_no_auto_restore', 23:00:16 INFO - 'tabpaint']}, 23:00:16 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 23:00:16 INFO - 'tests': ['a11yr', 23:00:16 INFO - 'ts_paint', 23:00:16 INFO - 'tpaint', 23:00:16 INFO - 'sessionrestore', 23:00:16 INFO - 'sessionrestore_no_auto_restore', 23:00:16 INFO - 'tabpaint']}, 23:00:16 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 23:00:16 INFO - 'tests': ['a11yr', 23:00:16 INFO - 'ts_paint', 23:00:16 INFO - 'tpaint', 23:00:16 INFO - 'sessionrestore', 23:00:16 INFO - 'sessionrestore_no_auto_restore', 23:00:16 INFO - 'tabpaint']}, 23:00:16 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 23:00:16 INFO - 'tests': ['tsvgx', 23:00:16 INFO - 'tsvgr_opacity', 23:00:16 INFO - 'tart', 23:00:16 INFO - 'tscrollx', 23:00:16 INFO - 'cart', 23:00:16 INFO - 'tsvg_static']}, 23:00:16 INFO - 'svgr-e10s': {'tests': ['tsvgx', 23:00:16 INFO - 'tsvgr_opacity', 23:00:16 INFO - 'tart', 23:00:16 INFO - 'tscrollx', 23:00:16 INFO - 'cart', 23:00:16 INFO - 'tsvg_static']}, 23:00:16 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 23:00:16 INFO - 'talos_options': ['--disable-e10s'], 23:00:16 INFO - 'tests': ['tp5o']}, 23:00:16 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 23:00:16 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 23:00:16 INFO - 'talos_options': ['--disable-e10s', 23:00:16 INFO - '--xperf_path', 23:00:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:00:16 INFO - 'tests': ['tp5n']}, 23:00:16 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 23:00:16 INFO - 'talos_options': ['--xperf_path', 23:00:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:00:16 INFO - 'tests': ['tp5n']}}, 23:00:16 INFO - 'talos.zip': {'path': '', 23:00:16 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 23:00:16 INFO - Running post-action listener: _resource_record_post_action 23:00:16 INFO - [mozharness: 2016-12-27 07:00:16.897416Z] Finished populate-webroot step (success) 23:00:16 INFO - [mozharness: 2016-12-27 07:00:16.897510Z] Running create-virtualenv step. 23:00:16 INFO - Running pre-action listener: _resource_record_pre_action 23:00:16 INFO - Running main action method: create_virtualenv 23:00:16 INFO - Creating virtualenv /builds/slave/test/build/venv 23:00:16 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 23:00:16 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 23:00:16 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 23:00:16 INFO - Using real prefix '/usr' 23:00:16 INFO - New python executable in /builds/slave/test/build/venv/bin/python 23:00:17 INFO - Installing distribute.............................................................................................................................................................................................done. 23:00:19 INFO - Installing pip.................done. 23:00:19 INFO - Return code: 0 23:00:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 23:00:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 23:00:19 INFO - Reading from file tmpfile_stdout 23:00:19 INFO - Output received: 23:00:19 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 23:00:19 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 23:00: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')]} 23:00:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:00:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:00: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')]} 23:00:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:00:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:00:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1aa31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c6fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e14c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e123f0>, '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 0x1e12cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e131a0>, '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': '0333a256a5e4f052382dd180000001b1-1482820167.797212-42797055', '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 23:00:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 23:00:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 23:00:19 INFO - Using env: {'DISPLAY': ':0', 23:00:19 INFO - 'HOME': '/home/cltbld', 23:00:19 INFO - 'LANG': 'en_US.UTF-8', 23:00:19 INFO - 'LANGUAGE': 'en_US:en', 23:00:19 INFO - 'LOGNAME': 'cltbld', 23:00:19 INFO - 'MAIL': '/var/mail/cltbld', 23:00:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:00:19 INFO - 'MOZ_NO_REMOTE': '1', 23:00:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:00:19 INFO - 'NO_EM_RESTART': '1', 23:00:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:00:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:00:19 INFO - 'PWD': '/builds/slave/test', 23:00:19 INFO - 'SHELL': '/bin/bash', 23:00:19 INFO - 'SHLVL': '1', 23:00:19 INFO - 'TERM': 'linux', 23:00:19 INFO - 'TMOUT': '86400', 23:00:19 INFO - 'USER': 'cltbld', 23:00:19 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1482820167.797212-42797055', 23:00:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:00:19 INFO - '_': '/tools/buildbot/bin/python'} 23:00:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:00:19 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 23:00:19 INFO - Cleaning up... 23:00:19 INFO - Return code: 0 23:00:19 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 23:00: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')]} 23:00:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:00:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:00: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')]} 23:00:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:00:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:00:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1aa31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c6fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e14c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e123f0>, '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 0x1e12cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e131a0>, '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': '0333a256a5e4f052382dd180000001b1-1482820167.797212-42797055', '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 23:00:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 23:00:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 23:00:19 INFO - Using env: {'DISPLAY': ':0', 23:00:19 INFO - 'HOME': '/home/cltbld', 23:00:19 INFO - 'LANG': 'en_US.UTF-8', 23:00:19 INFO - 'LANGUAGE': 'en_US:en', 23:00:19 INFO - 'LOGNAME': 'cltbld', 23:00:19 INFO - 'MAIL': '/var/mail/cltbld', 23:00:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:00:19 INFO - 'MOZ_NO_REMOTE': '1', 23:00:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:00:19 INFO - 'NO_EM_RESTART': '1', 23:00:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:00:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:00:19 INFO - 'PWD': '/builds/slave/test', 23:00:19 INFO - 'SHELL': '/bin/bash', 23:00:19 INFO - 'SHLVL': '1', 23:00:19 INFO - 'TERM': 'linux', 23:00:19 INFO - 'TMOUT': '86400', 23:00:19 INFO - 'USER': 'cltbld', 23:00:19 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1482820167.797212-42797055', 23:00:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:00:19 INFO - '_': '/tools/buildbot/bin/python'} 23:00:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:00:20 INFO - Downloading/unpacking psutil>=3.1.1 23:00:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:00:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:00:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:00:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:00:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:00:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:00:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 23:00:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:00:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:00:22 INFO - Installing collected packages: psutil 23:00:22 INFO - Running setup.py install for psutil 23:00:22 INFO - building 'psutil._psutil_linux' extension 23:00:22 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 23:00:22 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 23:00:22 INFO - building 'psutil._psutil_posix' extension 23:00:22 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 23:00:22 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 23:00:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:00:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:00:22 INFO - Successfully installed psutil 23:00:22 INFO - Cleaning up... 23:00:22 INFO - Return code: 0 23:00:22 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 23:00:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:00:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:00:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:00:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:00:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:00:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:00:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1aa31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c6fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e14c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e123f0>, '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 0x1e12cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e131a0>, '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': '0333a256a5e4f052382dd180000001b1-1482820167.797212-42797055', '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 23:00:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 23:00:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 23:00:22 INFO - Using env: {'DISPLAY': ':0', 23:00:22 INFO - 'HOME': '/home/cltbld', 23:00:22 INFO - 'LANG': 'en_US.UTF-8', 23:00:22 INFO - 'LANGUAGE': 'en_US:en', 23:00:22 INFO - 'LOGNAME': 'cltbld', 23:00:22 INFO - 'MAIL': '/var/mail/cltbld', 23:00:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:00:22 INFO - 'MOZ_NO_REMOTE': '1', 23:00:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:00:22 INFO - 'NO_EM_RESTART': '1', 23:00:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:00:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:00:22 INFO - 'PWD': '/builds/slave/test', 23:00:22 INFO - 'SHELL': '/bin/bash', 23:00:22 INFO - 'SHLVL': '1', 23:00:22 INFO - 'TERM': 'linux', 23:00:22 INFO - 'TMOUT': '86400', 23:00:22 INFO - 'USER': 'cltbld', 23:00:22 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1482820167.797212-42797055', 23:00:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:00:22 INFO - '_': '/tools/buildbot/bin/python'} 23:00:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:00:23 INFO - Downloading/unpacking mozsystemmonitor==0.3 23:00: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 23:00: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 23:00: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 23:00: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 23:00: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 23:00: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 23:00:25 INFO - Downloading mozsystemmonitor-0.3.tar.gz 23:00:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 23:00:25 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 23:00:25 INFO - Installing collected packages: mozsystemmonitor 23:00:25 INFO - Running setup.py install for mozsystemmonitor 23:00:25 INFO - Successfully installed mozsystemmonitor 23:00:25 INFO - Cleaning up... 23:00:25 INFO - Return code: 0 23:00:25 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 23:00:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:00:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:00:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:00:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:00:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:00:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:00:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1aa31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c6fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e14c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e123f0>, '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 0x1e12cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e131a0>, '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': '0333a256a5e4f052382dd180000001b1-1482820167.797212-42797055', '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 23:00:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 23:00:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 23:00:25 INFO - Using env: {'DISPLAY': ':0', 23:00:25 INFO - 'HOME': '/home/cltbld', 23:00:25 INFO - 'LANG': 'en_US.UTF-8', 23:00:25 INFO - 'LANGUAGE': 'en_US:en', 23:00:25 INFO - 'LOGNAME': 'cltbld', 23:00:25 INFO - 'MAIL': '/var/mail/cltbld', 23:00:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:00:25 INFO - 'MOZ_NO_REMOTE': '1', 23:00:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:00:25 INFO - 'NO_EM_RESTART': '1', 23:00:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:00:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:00:25 INFO - 'PWD': '/builds/slave/test', 23:00:25 INFO - 'SHELL': '/bin/bash', 23:00:25 INFO - 'SHLVL': '1', 23:00:25 INFO - 'TERM': 'linux', 23:00:25 INFO - 'TMOUT': '86400', 23:00:25 INFO - 'USER': 'cltbld', 23:00:25 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1482820167.797212-42797055', 23:00:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:00:25 INFO - '_': '/tools/buildbot/bin/python'} 23:00:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:00:25 INFO - Downloading/unpacking jsonschema==2.5.1 23:00:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:00:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:00:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:00:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:00:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:00:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:00:27 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 23:00:27 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 23:00: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 23:00: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 23:00: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 23:00: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 23:00: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 23:00: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 23:00:27 INFO - Downloading functools32-3.2.3-2.tar.gz 23:00:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 23:00:27 INFO - warning: no files found matching '*.txt' 23:00:27 INFO - no previously-included directories found matching 'build' 23:00:27 INFO - no previously-included directories found matching 'dist' 23:00:27 INFO - no previously-included directories found matching '.git*' 23:00:27 INFO - Installing collected packages: jsonschema, functools32 23:00:27 INFO - Running setup.py install for functools32 23:00:27 INFO - warning: no files found matching '*.txt' 23:00:27 INFO - no previously-included directories found matching 'build' 23:00:27 INFO - no previously-included directories found matching 'dist' 23:00:27 INFO - no previously-included directories found matching '.git*' 23:00:27 INFO - Successfully installed jsonschema functools32 23:00:27 INFO - Cleaning up... 23:00:27 INFO - Return code: 0 23:00:27 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 23:00:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:00:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:00:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:00:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:00:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:00:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:00:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1aa31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c6fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e14c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e123f0>, '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 0x1e12cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e131a0>, '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': '0333a256a5e4f052382dd180000001b1-1482820167.797212-42797055', '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 23:00:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 23:00:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 23:00:27 INFO - Using env: {'DISPLAY': ':0', 23:00:27 INFO - 'HOME': '/home/cltbld', 23:00:27 INFO - 'LANG': 'en_US.UTF-8', 23:00:27 INFO - 'LANGUAGE': 'en_US:en', 23:00:27 INFO - 'LOGNAME': 'cltbld', 23:00:27 INFO - 'MAIL': '/var/mail/cltbld', 23:00:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:00:27 INFO - 'MOZ_NO_REMOTE': '1', 23:00:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:00:27 INFO - 'NO_EM_RESTART': '1', 23:00:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:00:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:00:27 INFO - 'PWD': '/builds/slave/test', 23:00:27 INFO - 'SHELL': '/bin/bash', 23:00:27 INFO - 'SHLVL': '1', 23:00:27 INFO - 'TERM': 'linux', 23:00:27 INFO - 'TMOUT': '86400', 23:00:27 INFO - 'USER': 'cltbld', 23:00:27 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1482820167.797212-42797055', 23:00:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:00:27 INFO - '_': '/tools/buildbot/bin/python'} 23:00:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:00:28 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 23:00:28 INFO - Cleaning up... 23:00:28 INFO - Return code: 0 23:00:28 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 23:00: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')]} 23:00:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:00:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:00: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')]} 23:00:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:00:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:00:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1aa31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c6fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e14c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e123f0>, '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 0x1e12cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e131a0>, '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': '0333a256a5e4f052382dd180000001b1-1482820167.797212-42797055', '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 23:00:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 23:00:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 23:00:28 INFO - Using env: {'DISPLAY': ':0', 23:00:28 INFO - 'HOME': '/home/cltbld', 23:00:28 INFO - 'LANG': 'en_US.UTF-8', 23:00:28 INFO - 'LANGUAGE': 'en_US:en', 23:00:28 INFO - 'LOGNAME': 'cltbld', 23:00:28 INFO - 'MAIL': '/var/mail/cltbld', 23:00:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:00:28 INFO - 'MOZ_NO_REMOTE': '1', 23:00:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:00:28 INFO - 'NO_EM_RESTART': '1', 23:00:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:00:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:00:28 INFO - 'PWD': '/builds/slave/test', 23:00:28 INFO - 'SHELL': '/bin/bash', 23:00:28 INFO - 'SHLVL': '1', 23:00:28 INFO - 'TERM': 'linux', 23:00:28 INFO - 'TMOUT': '86400', 23:00:28 INFO - 'USER': 'cltbld', 23:00:28 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1482820167.797212-42797055', 23:00:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:00:28 INFO - '_': '/tools/buildbot/bin/python'} 23:00:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:00:28 INFO - Downloading/unpacking blobuploader==1.2.4 23:00:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:00:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:00:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:00:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:00:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:00:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:00:30 INFO - Downloading blobuploader-1.2.4.tar.gz 23:00:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 23:00:30 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 23:00:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:00:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:00:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:00:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:00:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:00:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:00:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 23:00:30 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 23:00:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:00:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:00:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:00:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:00:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:00:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:00:31 INFO - Downloading docopt-0.6.1.tar.gz 23:00:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 23:00:31 INFO - Installing collected packages: blobuploader, requests, docopt 23:00:31 INFO - Running setup.py install for blobuploader 23:00:31 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 23:00:31 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 23:00:31 INFO - Running setup.py install for requests 23:00:31 INFO - Running setup.py install for docopt 23:00:31 INFO - Successfully installed blobuploader requests docopt 23:00:31 INFO - Cleaning up... 23:00:31 INFO - Return code: 0 23:00:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:00: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')]} 23:00:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:00:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:00: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')]} 23:00:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:00:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:00:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1aa31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c6fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e14c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e123f0>, '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 0x1e12cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e131a0>, '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': '0333a256a5e4f052382dd180000001b1-1482820167.797212-42797055', '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 23:00:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 23:00:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 23:00:31 INFO - Using env: {'DISPLAY': ':0', 23:00:31 INFO - 'HOME': '/home/cltbld', 23:00:31 INFO - 'LANG': 'en_US.UTF-8', 23:00:31 INFO - 'LANGUAGE': 'en_US:en', 23:00:31 INFO - 'LOGNAME': 'cltbld', 23:00:31 INFO - 'MAIL': '/var/mail/cltbld', 23:00:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:00:31 INFO - 'MOZ_NO_REMOTE': '1', 23:00:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:00:31 INFO - 'NO_EM_RESTART': '1', 23:00:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:00:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:00:31 INFO - 'PWD': '/builds/slave/test', 23:00:31 INFO - 'SHELL': '/bin/bash', 23:00:31 INFO - 'SHLVL': '1', 23:00:31 INFO - 'TERM': 'linux', 23:00:31 INFO - 'TMOUT': '86400', 23:00:31 INFO - 'USER': 'cltbld', 23:00:31 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1482820167.797212-42797055', 23:00:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:00:31 INFO - '_': '/tools/buildbot/bin/python'} 23:00:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:00:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:00:32 INFO - Running setup.py (path:/tmp/pip-8EdyAJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:00:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:00:32 INFO - Running setup.py (path:/tmp/pip-QBMdXV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:00:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:00:32 INFO - Running setup.py (path:/tmp/pip-jMN8gz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:00:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:00:32 INFO - Running setup.py (path:/tmp/pip-k8B0i3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:00:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:00:32 INFO - Running setup.py (path:/tmp/pip-YQgxqi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:00:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:00:32 INFO - Running setup.py (path:/tmp/pip-I0sAPD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:00:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:00:32 INFO - Running setup.py (path:/tmp/pip-8MDT6v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:00:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:00:32 INFO - Running setup.py (path:/tmp/pip-N3iyoY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:00:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:00:32 INFO - Running setup.py (path:/tmp/pip-mP8bOi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:00:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:00:32 INFO - Running setup.py (path:/tmp/pip-dnf326-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:00:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:00:32 INFO - Running setup.py (path:/tmp/pip-O9_daw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:00:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:00:32 INFO - Running setup.py (path:/tmp/pip-1nXZBu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:00:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:00:32 INFO - Running setup.py (path:/tmp/pip-2D0wiw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:00:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:00:32 INFO - Running setup.py (path:/tmp/pip-zNyJmX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:00:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:00:33 INFO - Running setup.py (path:/tmp/pip-UUBzEA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:00:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:00:33 INFO - Running setup.py (path:/tmp/pip-HHay1P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:00:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:00:33 INFO - Running setup.py (path:/tmp/pip-lK2vuO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:00:33 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 23:00:33 INFO - Running setup.py install for manifestparser 23:00:33 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 23:00:33 INFO - Running setup.py install for mozcrash 23:00:33 INFO - Running setup.py install for mozdebug 23:00:33 INFO - Running setup.py install for mozdevice 23:00:33 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 23:00:33 INFO - Installing dm script to /builds/slave/test/build/venv/bin 23:00:33 INFO - Running setup.py install for mozfile 23:00:33 INFO - Running setup.py install for mozhttpd 23:00:33 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 23:00:33 INFO - Running setup.py install for mozinfo 23:00:33 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 23:00:33 INFO - Running setup.py install for mozInstall 23:00:34 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 23:00:34 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 23:00:34 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 23:00:34 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 23:00:34 INFO - Running setup.py install for mozleak 23:00:34 INFO - Running setup.py install for mozlog 23:00:34 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 23:00:34 INFO - Running setup.py install for moznetwork 23:00:34 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 23:00:34 INFO - Running setup.py install for mozprocess 23:00:34 INFO - Running setup.py install for mozprofile 23:00:34 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 23:00:34 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 23:00:34 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 23:00:34 INFO - Running setup.py install for mozrunner 23:00:34 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 23:00:34 INFO - Running setup.py install for mozscreenshot 23:00:34 INFO - Running setup.py install for moztest 23:00:34 INFO - Running setup.py install for mozversion 23:00:34 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 23:00:34 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 23:00:34 INFO - Cleaning up... 23:00:35 INFO - Return code: 0 23:00:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:00: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')]} 23:00:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:00:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:00: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')]} 23:00:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:00:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:00:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1aa31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c6fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e14c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e123f0>, '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 0x1e12cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e131a0>, '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': '0333a256a5e4f052382dd180000001b1-1482820167.797212-42797055', '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 23:00:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 23:00:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 23:00:35 INFO - Using env: {'DISPLAY': ':0', 23:00:35 INFO - 'HOME': '/home/cltbld', 23:00:35 INFO - 'LANG': 'en_US.UTF-8', 23:00:35 INFO - 'LANGUAGE': 'en_US:en', 23:00:35 INFO - 'LOGNAME': 'cltbld', 23:00:35 INFO - 'MAIL': '/var/mail/cltbld', 23:00:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:00:35 INFO - 'MOZ_NO_REMOTE': '1', 23:00:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:00:35 INFO - 'NO_EM_RESTART': '1', 23:00:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:00:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:00:35 INFO - 'PWD': '/builds/slave/test', 23:00:35 INFO - 'SHELL': '/bin/bash', 23:00:35 INFO - 'SHLVL': '1', 23:00:35 INFO - 'TERM': 'linux', 23:00:35 INFO - 'TMOUT': '86400', 23:00:35 INFO - 'USER': 'cltbld', 23:00:35 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1482820167.797212-42797055', 23:00:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:00:35 INFO - '_': '/tools/buildbot/bin/python'} 23:00:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:00:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:00:35 INFO - Running setup.py (path:/tmp/pip-Bhxr6C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:00:35 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 23:00:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:00:35 INFO - Running setup.py (path:/tmp/pip-Ml6QHs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:00:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 23:00:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:00:35 INFO - Running setup.py (path:/tmp/pip-NekE2w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:00:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 23:00:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:00:35 INFO - Running setup.py (path:/tmp/pip-yTj5ht-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:00:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 23:00:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:00:35 INFO - Running setup.py (path:/tmp/pip-0glyUR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:00:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 23:00:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:00:35 INFO - Running setup.py (path:/tmp/pip-osaqyj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:00:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 23:00:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:00:35 INFO - Running setup.py (path:/tmp/pip-08JnxU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:00:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 23:00:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:00:35 INFO - Running setup.py (path:/tmp/pip-r2taNV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:00:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 23:00:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:00:35 INFO - Running setup.py (path:/tmp/pip-poxqpe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:00:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 23:00:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:00:35 INFO - Running setup.py (path:/tmp/pip-_cx1TW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:00:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 23:00:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:00:35 INFO - Running setup.py (path:/tmp/pip-AjAl8j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:00:36 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 23:00:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:00:36 INFO - Running setup.py (path:/tmp/pip-aMbnye-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:00:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 23:00:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:00:36 INFO - Running setup.py (path:/tmp/pip-S_ijxq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:00:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 23:00:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:00:36 INFO - Running setup.py (path:/tmp/pip-4wN3po-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:00:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 23:00:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:00:36 INFO - Running setup.py (path:/tmp/pip-4WKQMd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:00:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 23:00:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:00:36 INFO - Running setup.py (path:/tmp/pip-CAF32v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:00:36 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 23:00:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:00:36 INFO - Running setup.py (path:/tmp/pip-yJlyVe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:00:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 23:00:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 23:00:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 23:00:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 23:00:36 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 23:00:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 23:00:36 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 23:00:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:00:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:00:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:00:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:00:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:00:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:00:38 INFO - Downloading blessings-1.6.tar.gz 23:00:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 23:00:38 INFO - Installing collected packages: blessings 23:00:38 INFO - Running setup.py install for blessings 23:00:38 INFO - Successfully installed blessings 23:00:38 INFO - Cleaning up... 23:00:38 INFO - Return code: 0 23:00:38 INFO - Done creating virtualenv /builds/slave/test/build/venv. 23:00:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:00:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:00:38 INFO - Reading from file tmpfile_stdout 23:00:38 INFO - Current package versions: 23:00:38 INFO - argparse == 1.2.1 23:00:38 INFO - blessings == 1.6 23:00:38 INFO - blobuploader == 1.2.4 23:00:38 INFO - docopt == 0.6.1 23:00:38 INFO - functools32 == 3.2.3-2 23:00:38 INFO - jsonschema == 2.5.1 23:00:38 INFO - manifestparser == 1.1 23:00:38 INFO - mozInstall == 1.12 23:00:38 INFO - mozcrash == 1.0 23:00:38 INFO - mozdebug == 0.1 23:00:38 INFO - mozdevice == 0.49 23:00:38 INFO - mozfile == 1.2 23:00:38 INFO - mozhttpd == 0.7 23:00:38 INFO - mozinfo == 0.9 23:00:38 INFO - mozleak == 0.1 23:00:38 INFO - mozlog == 3.4 23:00:38 INFO - moznetwork == 0.27 23:00:38 INFO - mozprocess == 0.23 23:00:38 INFO - mozprofile == 0.28 23:00:38 INFO - mozrunner == 6.13 23:00:38 INFO - mozscreenshot == 0.1 23:00:38 INFO - mozsystemmonitor == 0.3 23:00:38 INFO - moztest == 0.7 23:00:38 INFO - mozversion == 1.4 23:00:38 INFO - psutil == 3.1.1 23:00:38 INFO - requests == 1.2.3 23:00:38 INFO - wsgiref == 0.1.2 23:00:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:00:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:00:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:00:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:00:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:00:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:00:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:00:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1aa31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c6fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e14c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e123f0>, '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 0x1e12cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e131a0>, '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': '0333a256a5e4f052382dd180000001b1-1482820167.797212-42797055', '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 23:00:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 23:00:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 23:00:38 INFO - Using env: {'DISPLAY': ':0', 23:00:38 INFO - 'HOME': '/home/cltbld', 23:00:38 INFO - 'LANG': 'en_US.UTF-8', 23:00:38 INFO - 'LANGUAGE': 'en_US:en', 23:00:38 INFO - 'LOGNAME': 'cltbld', 23:00:38 INFO - 'MAIL': '/var/mail/cltbld', 23:00:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:00:38 INFO - 'MOZ_NO_REMOTE': '1', 23:00:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:00:38 INFO - 'NO_EM_RESTART': '1', 23:00:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:00:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:00:38 INFO - 'PWD': '/builds/slave/test', 23:00:38 INFO - 'SHELL': '/bin/bash', 23:00:38 INFO - 'SHLVL': '1', 23:00:38 INFO - 'TERM': 'linux', 23:00:38 INFO - 'TMOUT': '86400', 23:00:38 INFO - 'USER': 'cltbld', 23:00:38 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1482820167.797212-42797055', 23:00:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:00:38 INFO - '_': '/tools/buildbot/bin/python'} 23:00:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:00:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 23:00:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 23:00:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 23:00:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 23:00:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 23:00:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 23:00:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 23:00:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 23:00:39 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 23:00:39 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 23:00:39 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 23:00:39 INFO - Cleaning up... 23:00:39 INFO - Return code: 0 23:00:39 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 23:00:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:00:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:00:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:00:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:00:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:00:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:00:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1aa31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c6fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e14c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e123f0>, '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 0x1e12cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e131a0>, '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': '0333a256a5e4f052382dd180000001b1-1482820167.797212-42797055', '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 23:00:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 23:00:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 23:00:39 INFO - Using env: {'DISPLAY': ':0', 23:00:39 INFO - 'HOME': '/home/cltbld', 23:00:39 INFO - 'LANG': 'en_US.UTF-8', 23:00:39 INFO - 'LANGUAGE': 'en_US:en', 23:00:39 INFO - 'LOGNAME': 'cltbld', 23:00:39 INFO - 'MAIL': '/var/mail/cltbld', 23:00:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:00:39 INFO - 'MOZ_NO_REMOTE': '1', 23:00:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:00:39 INFO - 'NO_EM_RESTART': '1', 23:00:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:00:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:00:39 INFO - 'PWD': '/builds/slave/test', 23:00:39 INFO - 'SHELL': '/bin/bash', 23:00:39 INFO - 'SHLVL': '1', 23:00:39 INFO - 'TERM': 'linux', 23:00:39 INFO - 'TMOUT': '86400', 23:00:39 INFO - 'USER': 'cltbld', 23:00:39 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1482820167.797212-42797055', 23:00:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:00:39 INFO - '_': '/tools/buildbot/bin/python'} 23:00:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:00:39 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 23:00:39 INFO - Cleaning up... 23:00:39 INFO - Return code: 0 23:00:39 INFO - Running post-action listener: _resource_record_post_action 23:00:39 INFO - Running post-action listener: _start_resource_monitoring 23:00:39 INFO - Starting resource monitoring. 23:00:39 INFO - [mozharness: 2016-12-27 07:00:39.436819Z] Finished create-virtualenv step (success) 23:00:39 INFO - [mozharness: 2016-12-27 07:00:39.437348Z] Running install step. 23:00:39 INFO - Running pre-action listener: _resource_record_pre_action 23:00:39 INFO - Running main action method: install 23:00:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:00:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:00:39 INFO - Reading from file tmpfile_stdout 23:00:39 INFO - Detecting whether we're running mozinstall >=1.0... 23:00:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 23:00:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 23:00:39 INFO - Reading from file tmpfile_stdout 23:00:39 INFO - Output received: 23:00:39 INFO - Usage: mozinstall [options] installer 23:00:39 INFO - Options: 23:00:39 INFO - -h, --help show this help message and exit 23:00:39 INFO - -d DEST, --destination=DEST 23:00:39 INFO - Directory to install application into. [default: 23:00:39 INFO - "/builds/slave/test"] 23:00:39 INFO - --app=APP Application being installed. [default: firefox] 23:00:39 INFO - mkdir: /builds/slave/test/build/application 23:00:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 23:00:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 23:00:56 INFO - Reading from file tmpfile_stdout 23:00:56 INFO - Output received: 23:00:56 INFO - /builds/slave/test/build/application/firefox/firefox 23:00:56 INFO - Running post-action listener: _resource_record_post_action 23:00:56 INFO - [mozharness: 2016-12-27 07:00:56.205159Z] Finished install step (success) 23:00:56 INFO - [mozharness: 2016-12-27 07:00:56.205407Z] Running run-tests step. 23:00:56 INFO - Running pre-action listener: _resource_record_pre_action 23:00:56 INFO - Running main action method: run_tests 23:00:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 23:00:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 23:00:56 INFO - Python 2.7.3 23:00:56 INFO - Return code: 0 23:00:56 INFO - grabbing minidump binary from tooltool 23:00:56 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')]} 23:00:56 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 0x1e123f0>, '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 0x1e12cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e131a0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 23:00:56 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 23:00:56 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 23:00:56 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 23:00:56 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 23:00:56 INFO - Return code: 0 23:00:56 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 23:00:56 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 23:00:56 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 23:00:56 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 23:00:56 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 23:00:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/bbC1Ymu0Sca5raJ3Fbmu7w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-017', '--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 23:00:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Graphics --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/bbC1Ymu0Sca5raJ3Fbmu7w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-017 --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 23:00:56 INFO - Using env: {'DISPLAY': ':0', 23:00:56 INFO - 'HOME': '/home/cltbld', 23:00:56 INFO - 'LANG': 'en_US.UTF-8', 23:00:56 INFO - 'LANGUAGE': 'en_US:en', 23:00:56 INFO - 'LOGNAME': 'cltbld', 23:00:56 INFO - 'MAIL': '/var/mail/cltbld', 23:00:56 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 23:00:56 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 23:00:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:00:56 INFO - 'MOZ_NO_REMOTE': '1', 23:00:56 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 23:00:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:00:56 INFO - 'NO_EM_RESTART': '1', 23:00:56 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:00:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:00:56 INFO - 'PWD': '/builds/slave/test', 23:00:56 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 23:00:56 INFO - 'SHELL': '/bin/bash', 23:00:56 INFO - 'SHLVL': '1', 23:00:56 INFO - 'TERM': 'linux', 23:00:56 INFO - 'TMOUT': '86400', 23:00:56 INFO - 'USER': 'cltbld', 23:00:56 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1482820167.797212-42797055', 23:00:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:00:56 INFO - '_': '/tools/buildbot/bin/python'} 23:00:56 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/bbC1Ymu0Sca5raJ3Fbmu7w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-017', '--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 23:00:56 INFO - mozversion application_buildid: 20161226221337 23:00:56 INFO - mozversion application_changeset: 2f4e31c8bc98d606f7631c15bbff41c0ed781136 23:00:56 INFO - mozversion application_display_name: Nightly 23:00:56 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 23:00:56 INFO - mozversion application_name: Firefox 23:00:56 INFO - mozversion application_remotingname: firefox 23:00:56 INFO - mozversion application_repository: https://hg.mozilla.org/projects/graphics 23:00:56 INFO - mozversion application_vendor: Mozilla 23:00:56 INFO - mozversion application_version: 53.0a1 23:00:56 INFO - mozversion platform_buildid: 20161226221337 23:00:56 INFO - mozversion platform_changeset: 2f4e31c8bc98d606f7631c15bbff41c0ed781136 23:00:56 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/graphics 23:00:56 INFO - mozversion platform_version: 53.0a1 23:00:56 INFO - using testdate: 1482822056 23:00:56 INFO - actual date: 1482822056 23:00:56 INFO - starting webserver on 'localhost:53140' 23:00:56 INFO - SUITE-START | Running 2 tests 23:00:56 INFO - TEST-START | dromaeo_css 23:00:56 INFO - operating with platform_type : linux_ 23:00:56 INFO - Initialising browser for dromaeo_css test... 23:00:56 INFO - TEST-INFO | started process 13275 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpTZJEZN/profile http://localhost:53140/getInfo.html) 23:00:57 INFO - PROCESS | 13275 | 1482822057023 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 23:00:59 INFO - PROCESS | 13275 | JavaScript error: chrome://global/content/browser-content.js, line 1772: ReferenceError: event is not defined 23:00:59 INFO - PROCESS | 13275 | JavaScript error: chrome://global/content/browser-content.js, line 1772: ReferenceError: event is not defined 23:01:00 INFO - PROCESS | 13275 | JavaScript error: chrome://global/content/browser-content.js, line 1772: ReferenceError: event is not defined 23:01:00 INFO - PROCESS | 13275 | __metrics Screen width/height:1600/1200 23:01:00 INFO - PROCESS | 13275 | colorDepth:24 23:01:00 INFO - PROCESS | 13275 | Browser inner width/height: 1024/697 23:01:00 INFO - PROCESS | 13275 | __metrics 23:01:00 INFO - PROCESS | 13275 | 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" 23:01:00 INFO - PROCESS | 13275 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 23:01:01 INFO - PROCESS | 13275 | console.error: PushService: 23:01:01 INFO - PROCESS | 13275 | stateChangeProcessEnqueue: Error transitioning state 23:01:01 INFO - PROCESS | 13275 | UnknownError 23:01:01 INFO - TEST-INFO | 13275: exit 0 23:01:01 INFO - Browser initialized. 23:01:01 INFO - Running cycle 1/1 for dromaeo_css test... 23:01:01 INFO - TEST-INFO | started process 13358 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpTZJEZN/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:01:13 INFO - PROCESS | 13358 | RSS: Main: 151035904 23:01:13 INFO - PROCESS | 13358 | 23:02:05 INFO - PROCESS | 13358 | 1482822125399 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] 23:04:18 INFO - PROCESS | 13358 | Cycle 1(1): loaded http://localhost:53140/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53140/tests/dromaeo/cssquery-ext.html) 23:04:20 INFO - PROCESS | 13358 | RSS: Main: 270696448 23:04:20 INFO - PROCESS | 13358 | 23:06:02 INFO - PROCESS | 13358 | ************************* 23:06:02 INFO - PROCESS | 13358 | A coding exception was thrown and uncaught in a Task. 23:06:02 INFO - PROCESS | 13358 | 23:06:02 INFO - PROCESS | 13358 | Full message: ReferenceError: fetch is not defined 23:06:02 INFO - PROCESS | 13358 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 23:06:02 INFO - PROCESS | 13358 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 23:06:02 INFO - PROCESS | 13358 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 23:06:02 INFO - PROCESS | 13358 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 23:06:02 INFO - PROCESS | 13358 | TaskImpl@resource://gre/modules/Task.jsm:277:3 23:06:02 INFO - PROCESS | 13358 | asyncFunction@resource://gre/modules/Task.jsm:252:14 23:06:02 INFO - PROCESS | 13358 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 23:06:02 INFO - PROCESS | 13358 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 23:06:02 INFO - PROCESS | 13358 | TaskImpl@resource://gre/modules/Task.jsm:277:3 23:06:02 INFO - PROCESS | 13358 | asyncFunction@resource://gre/modules/Task.jsm:252:14 23:06:02 INFO - PROCESS | 13358 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 23:06:02 INFO - PROCESS | 13358 | 23:06:02 INFO - PROCESS | 13358 | ************************* 23:07:22 INFO - PROCESS | 13358 | Cycle 1(1): loaded http://localhost:53140/tests/dromaeo/cssquery-ext.html (next: http://localhost:53140/tests/dromaeo/cssquery-jquery.html) 23:07:23 INFO - PROCESS | 13358 | RSS: Main: 246841344 23:07:23 INFO - PROCESS | 13358 | 23:10:25 INFO - PROCESS | 13358 | Cycle 1(1): loaded http://localhost:53140/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53140/tests/dromaeo/cssquery-mootools.html) 23:10:26 INFO - PROCESS | 13358 | RSS: Main: 229294080 23:10:26 INFO - PROCESS | 13358 | 23:13:28 INFO - PROCESS | 13358 | Cycle 1(1): loaded http://localhost:53140/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53140/tests/dromaeo/cssquery-prototype.html) 23:13:29 INFO - PROCESS | 13358 | RSS: Main: 187809792 23:13:29 INFO - PROCESS | 13358 | 23:16:32 INFO - PROCESS | 13358 | Cycle 1(1): loaded http://localhost:53140/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53140/tests/dromaeo/cssquery-yui.html) 23:16:33 INFO - PROCESS | 13358 | RSS: Main: 193474560 23:16:33 INFO - PROCESS | 13358 | 23:19:35 INFO - PROCESS | 13358 | Cycle 1(1): loaded http://localhost:53140/tests/dromaeo/cssquery-yui.html (next: http://localhost:53140/tests/dromaeo/cssquery-dojo.html) 23:19:36 INFO - PROCESS | 13358 | RSS: Main: 268189696 23:19:36 INFO - PROCESS | 13358 | 23:19:36 INFO - PROCESS | 13358 | __start_tp_report 23:19:36 INFO - PROCESS | 13358 | _x_x_mozilla_page_load 23:19:36 INFO - PROCESS | 13358 | _x_x_mozilla_page_load_details 23:19:36 INFO - PROCESS | 13358 | |i|pagename|runs| 23:19:36 INFO - PROCESS | 13358 | |0;dojo.html;2571.2671888511773;2593.50723362561;2603.2710840964523;2623.2785983854437;2627.97508971501;4675.20521511343;4675.836345727896;4697.8120875165;4701.976490117545;4716.57550820427;4724.267738500538;4757.785899634516;4791.736454495011;4803.495632958541;4876.975615121928;4295.269804133305;4296.419983302261;4332.0469496710675;4374.168907907488;4453.84411545596;4025.4364388985564;4364.454443194601;4466.307722303032;4493.370928070073;4543;4249.68127390447;4271.9572804271875;4412.492563355223;4413.602775750166;4506.188886000518;4310.827566897352;4316.848910288156;4331.45856767904;4339.1972485090355;4342.044750154961;4304.655627549789;4305.806238719265;4306.612404883575;4313.309870420718;4318.589733975267;4677.321788340671;4679.181143299909;4685.273782563708;4688.906221875577;4700.835470758541;5013.222950442687;5022.296878437021;5042.697438153723;5083.8983220335385;5127.410347809975;5096.311997880276;5103.362079740033;5108.208227724708;5108.310378098946;5155.9484405155845;4625.444946606429;4739;4787.162246606865;4789.425268967677;4831.371921650233;5041.974790126099;5072.315237442972;5076.82231121909;5097.0315640028275;5174.793008279702;4883.755812209446;4914.238293064581;4925.556699896955;4929;4979.850604481871;4846.36997190363;4999.200127979579;5000.649954503223;5002.024605202024;5007.874803129878;4833.299171620095;4892.779824907888;4894.412670479565;4894.730789806595;4908.2637604358915;4887.706737595756;4908.11653902301;4908.803647854046;4910.459849416633;4912.1649319615035;4207.789610519461;4214.726042807208;4215.409842622035;4215.957840421618;4218.409422680827;3684.797336146538;3774.7168962327937;3774.9433758493647;3775.6413140751583;3777.660010599004;4324.978375108104;4344.891377715582;4388.626966707866;4393.802278897393;4394.912101757947;5035.370578677665;5038.949610503848;5042.092423363756;5047.369078865142;5089.51649593288;4348.63036641889;4353.73877567347;4354.802429331896;4387.912241755211;4389.978050109729;4416.873697207192;4449.265871131227;4461.107778444324;4521.660875434355;4543.136804007228;4716.3632909557455;4759.119562880878;4762.16662084127;4792.9281060784815;4807.423109226916;5104.438511763628;5113.539781419541;5117.2068329409;5117.9575889528705;5159.122949098587;4611.815527378867;4657.953420465752;4744.217204161413;4753.429588449409;4798.568128868418;4922.1140194765285;4959.950400495949;4979.278004689228;5018.673786203885;5072.112380333353;5099.69401835891;5101.234814777665;5103.897922041612;5111.897762044812;5159.535641792257;4841.782119804547;4964.578010869117;4968.3044373788425;4970.453250142409;4971.676841005323;4836.419629644465;4858.295547145714;4859.659823812441;4873.195922672861;4933.333999909966;4813.663043586916;4821.63837712166;4840.298156767179;4843.903121937612;4891.682040667345;4893.7063776173545;4935.284383764262;4941.209406494943;4943.159662857395;4990.775415106256;4803.495632958628;4841;4864.513548645249;4872.975635121802;4873.342098816614 23:19:36 INFO - PROCESS | 13358 | |1;ext.html;506.5563145036646;536.5519790974536;548.6762809942135;561.1050374652428;567.7473524281692;27953.72046279539;37349;38806.41790373147;38813.83558493243;43323.13353732931;40286.79856600713;40774.18451630966;40802.38796418056;40810;40861.79569102158;7402.814929626762;7502.137254215753;7587.241275872396;7589.620518974057;7591.316781489665;7462.664180111908;9373.531323433835;9643.758906027335;9643.758906027335;9996.150327222194;9938.602455901755;10003.599856005787;10016.198704103654;10018.298719089667;10018.749531261705;9906.900930990705;9964.900350996542;9971.75070623233;9976;9979.900200998007;9805.215582753362;9852.753681158028;9877.90122098777;9880.95059524705;9896.554655040469;36039.27921441583;36216.45675314846;36269.63730362689;36303.63696363029;36385.63614363849;3093.535969604555;3150.6691797361177;3222.6132864056462;3370.359631669975;3412.328897006347;27350.985837404885;27807.304817379525;27896.163115106578;28360.432791344057;28390.57414138769;3397.286569820341;3447.6563123852234;3466.497357883118;3545.4327307630915;3616.8553257869653;232367;234059;234696;235060;235261;49518.50481495211;49864.252036219485;49887.50112498901;49917;49936.75031624819;52052.21921671178;52168.261491235935;52201;52241.73879130656;52260.21609675858;14034.29828508591;14694.706105877822;14700;14700.264986750619;14711.55865324042;9433.433993960385;9534.809303813885;11041.39272340029;11095.1123910087;11320;14569.64898073449;15131.848681513044;15155.621109472351;15177.165255911032;15307.846921530641;5648.63283886546;5663.716814159276;5860.824175274749;5889.911651325234;5907.4683278505145;762.725418849225;856.6230858422399;889.3818795936891;899.1547944931874;913.4427998920653;25295.62056569153;26765.330866728487;27402.17793466199;27492.58761118585;27619.86190069077;978.6183388478499;990.6631745206664;1021.3667526133845;1070.4808168038496;1093.5188517052472;13771.655708607364;14673;14673.559793206221;14716.705665886837;14813.925930370278;338330;341967;342770;343560;343611;48847.02305953932;48992.265116023285;49101;49163.262551061765;49409;1211.2550781269456;1218.6709588411256;1236.3014896583402;1242.3912282981273;1250.0999280518013;25731.742682572934;26306.21081367562;26844.328891777863;26890.462190756465;27164.592531111655;40988.1802363957;41408.37887431631;41467.37798933079;41470.37794433146;41668;297520;297887;298185;298437;298718;29930.25174370658;34276;34794.82602586971;37976;38200.235995279385;39131.41302880516;39321.41017884677;39762.60237397589;40468.392974106;40523;34143.31713365669;34709.47935780915;34797.652023479444;34832.30335393328;34924.65075349214;31070.689293106778;33375.83312083424;33729;34165.82917085498;34670.65329346674;32816.67183328136;33265.83367083149;33290;33419;33755.83122084374;29526.261843454085;30625.846870765505;31075.689243108183;31146;31239;23630.527389452458;24247.87876060679;24253.272401827973;24582.139625113035;24693 23:19:36 INFO - PROCESS | 13358 | |2;jquery.html;349.73070735533656;366.91377526281326;367.5424096999236;370.70158522389477;392.2977869613392;12741.426635801388;15486.845131548682;15749.21253937303;15782.921085394586;16800.66398672029;16390.344386224566;16452.506424807245;17000.3199872005;17093.40173093942;17118.743218851727;4821.807127714896;5075.441701412841;5123.2315152727015;5326.547243484309;5373.220882971967;3637.327094487515;3798.6771124454453;5262.105442074857;5304.681719096867;5328.280682107924;5118.897622047557;5323.893522129555;5347.197920311945;5409.43200963899;5410.3507579090365;5391.299131112969;5400.486953739428;5412.756425960822;5702.344230413514;5942.494887934487;5260.263563101169;5264.157734762458;5272.973635131838;5280.128778751539;5281.63028587999;12190.2076365036;12393.318367489785;12559.183653062522;12657.493700251978;12659.810102848465;1858.8327050565379;1863.543431859203;1922.663533881579;2090.372888133569;2281.4752606900506;8511.531865747382;8663.263622592087;9003.504807235666;9173;9184.219341355993;1587.992060039704;1692.7714758507566;1723.2331612432472;1754;1788.8658350623755;63426.68286658537;64531;65882.3411765881;65999.67000164969;67639.32360676379;8921.197092261727;9284.628614855465;9318.114779095975;9529.857052144223;9530.52347382628;12395.566155184526;12400.069994750429;12408.131430799938;12422.440990155465;12459.750804983849;7084.2915708428745;7151.2848715128075;7200.855982880313;7238.674259658328;7310.890336644955;6497.155369801895;6596.373344532358;6812.557183783038;6840.657967101724;6863.10779598659;3909.6285852843935;3958.7030972677167;3962.3660214365564;3970.622790834866;4013.097053163015;1608.3245037084453;1685.9578510537335;1707.9146042697816;1724.430937790526;1757.2092558348795;816.5508970066439;833.7040350675422;840.9950109619069;850.6299759604592;904.8371293167162;15848.92075539615;16910;16934.83065169357;17174.22715977784;17380.65238695219;807.9676812927535;831.5966756123275;838.1534650003418;839.7858546070835;856.5973992223645;8974.641014359353;9111.498867562348;9501.572429240701;9506.47714375716;9694.854577181348;334652;339050;339758;341389;341718;51783.48216517862;52926.20610690843;70946.29053709397;71821.28178718251;73314.63342683253;880.2122100719948;896.7758060484748;936.6019441737235;936.8032713130319;939.3940908114365;14533.78199327032;14648.560543183721;14785.630359241106;15126.319315630602;15548.68902621964;35422.64577354193;35488.822555887054;35546;36014;36069.63930360768;47407;49708.254376183664;49758.53621956735;50839;51543.226851598;7150.213476517683;7280.235575264733;7487.663055162422;7509.211532788979;7528.924710752823;10038.849417258893;10946;11082.722931926766;11426.771464570496;11521.251118677259;8762.430442021248;9532.475713835805;9708;9799.461029643438;10208.540615672164;9185.357025008452;10107.090361867467;10206.387616742722;10254.436006019741;10413.791724165625;9749.512524373895;9796.216302695624;9930.801383972424;10038.598456061676;10441.783093828319;7018;7211.314925082109;7395.075615548057;7443.106827180773;7651.120121186135;8561.48631082137;8712.695055673275;9716.3198576099;9811.166050885755;9927.702168934944 23:19:36 INFO - PROCESS | 13358 | |3;mootools.html;1357.6537982814384;1361.0268657909596;1479.5709244319148;1670.582354411397;1706.5136436115702;7102.46731495138;7591.354734847545;7602.847943041143;7604.543727376361;7608.733694320693;6680.532362734611;6832.077669514614;7105.147382314117;7403.444741644371;7485.700571977128;2130.3395947256363;2188.9014994325294;2193.6928829963817;2194.484296190402;2195.4730864592543;2213.269621025058;2243.741969673484;2258.288639078685;2260.762619924909;2266.660000999847;2145.420736401171;2149.3659370485743;2172.804447599716;2174.967375489369;2180.8364372672036;2154.8707077575395;2171.8045375916163;2172.9782702172934;2174.7390313162364;2175.967360489578;2205.3163519309064;2231.944201394962;2238.6977758002786;2240.8543444676206;2243.0354947372784;7418.332350088464;7466.775996720053;7485.363744081759;7486.5508069516;7488.925110748877;4550.38569793077;4562.1560011398;4619.14545809023;4690.132325519756;4701.529847015306;2970.301979034925;3047.9847600762077;3137.4666306727913;3148.212946763309;3157.4790159623635;891.527490430073;920.1764420843375;947.3842002698233;962.7544976031137;966.3284017607765;9706.61173553057;9990.150837178848;9992.100710935978;9999.450030248334;10000.549975251131;3988.4017397390044;4245.8513952011535;4277.914441711148;4308.870733877926;4320.265554855682;8675.783105422415;8680.435771674822;8682.43564168327;8683.348748843862;8684.56577171139;4656.3946686930485;4659.510751371125;4666.393368862026;4673.6728429009645;4757.3577567028005;3782.432635104756;3785.2051069275763;4177.415161877339;4224.577542245751;4311.33174357975;3599.8200089995394;3650.634936506328;3657.926841463156;3681.2637472505608;3754.5494540655295;1213.6723084767063;1214.5141943222782;1228.2323547782637;1242.7763002659428;1246.4889395347864;792.1761368177147;793.4683761879554;794.2970471133088;809.6194788449419;812.5246730662628;4621.260598304322;4670.486246512881;4791;4809.470958194595;4935.925961110586;405.56807000544325;418.49571266623644;843.3175814250609;932.792034315563;948.0139108567678;3083.2291927018246;3083.8612262448246;3152.763542734304;3289.0626171540994;3311;8799.384043117048;9192.862107068398;9205.125513076247;9207.447553146698;9212.585433655368;9141.588628511732;9153.267738581031;9158.221551168226;9172;9184.311176661777;7042.295770422917;7173.10336207974;7177.354038136488;7179.317964793337;7187.281271872876;4688.3436318916065;4730.574248317668;4748.857534273978;4805.327254184347;4815.181419158822;8311.33509319265;8453.577321134042;8483.36374771907;8490.702825401328;8530.616122274389;8420.073791883011;8438.240558349778;8452.281556067803;8488.915110849059;8598;5632.971835140962;5776.162456443876;5796.130580412803;6167.198264225622;6299.874002519832;6770.45836333102;6823.829404264933;7028.121484814398;7200.675969581277;7202.71189152428;6563.803085907431;6766.492513061638;6846.965765171142;6886.51794374389;6924.515283930278;6573.342665733579;6595.8021259362295;6631.568948018364;6698.83252918681;6726.7981960541265;5735.197072409783;5741.712914354349;5787.797427090031;5790.392008839011;5842.883142337045;4296.398504209351;4325.372820941009;4333.913321733485;4382.5179230285285;4391.099824536026;4267.637250833714;4338.9566104338555;4466.687331886867;4476.194285028726;4531.252343363308 23:19:36 INFO - PROCESS | 13358 | |4;prototype.html;353.06437939460426;353.1418652674002;354.7623092528006;364.1696930997327;365.02902279935375;4796.400449943757;4816.783244753986;4818.927716084261;4826.927596086057;4850.805967761294;4724.385829842125;4762.261849413338;4770.809167633291;4777.378940737708;4780.832670856521;3602.9279414411694;3709.777413355194;3715.516982792233;3755.3428150073764;3765.1904840459433;3727.3413331067686;3795.9810200948955;3803.9429408558894;3810.9618903811024;3816.618338166189;3720.2931443025873;3723.2739615774935;3748.444395575833;3755.906102347436;3775.433684947266;3606.711463082947;3718.4236443351324;3727;3766.531052889375;3766.679832214265;3433.9656603433896;3437.415639341318;3438.484227365915;3438.644528497987;3443.483477478398;4626.190416677102;4635.8377456789185;4639.979204574988;4654.650901182391;4667.4632417272105;4208.299398104926;4348.152110338485;4353.8027042563235;4362.211493287737;4388.912221755547;4955.677880937727;4975.925361119586;4980.950190498121;4990.625703072248;5007.649464537484;3728.813559322023;3735.831887565066;3745.6628903398823;3746.1758413149064;3768.660820526138;5167.431582525956;5184.481551844785;5209.905919756818;5212.765425555859;5219.373675158968;3714.622641509436;3805.6955443564448;3840.539135303781;3842.481265029241;3867.303885300673;3894.162755007687;3895.3962135868987;3896.8636097736444;3897.18159186574;3902.8681682311812;4322.891927701769;4328.653707703376;4338.566143385699;4349.739015659071;4350.3451307101095;4686.718796872198;4967.155583550805;5021.322121513549;5021.899562008739;5028.220625803006;1191.6901605582611;1205.6021512900722;1212.175720510044;1212.472284921006;1219.3659297165602;784.372501998399;824.3528829868525;824.9175082491823;834.2533432577809;836.2306677856386;4223.239816832999;4257.1698518788535;4307.203167414038;4363.80362883671;4445.955540444619;5146.202338637517;5149.639525233272;5177.870553236199;5187.403448603459;5199.142141546602;4462.085272519126;4465.598096171295;4470.687051906335;4474.955250447454;4480.910381792346;2553.4893021395796;2633.552296109666;2649.0331029174213;2657.162993656992;2662.454196889633;4627.722336659744;4658.928446457266;4666.719996800203;4677.906441871143;4678.461976872703;5035.345405097387;5064.797408103708;5149.227615857576;5163.3545806774155;5187.740612969412;4406.317020861771;4677.859664210079;4707.8587642370785;4715.316279139574;4723.748206725152;4965.379327584052;5026.899462010812;5031.698098114125;5041.798328066983;5042.520960508746;4913.410390753133;4914.975425122851;4919.3604831371695;4928.968684259083;4932.704037757746;4436.667249956202;4594.632429405707;4647.930281045719;4653.464851542116;4685.742284174266;3465.410880150431;3476.443768996948;3487.773294735834;3507.351140038999;3511.8595256189465;4976.178930476431;4984.551390374883;4990.925136122888;5004.724740139327;5028.491452564289;4696.835610753607;4811.591014763644;4841.540053694894;4852.466228714909;4907.68100073494;4818.8795280118275;4822.035592881368;4832.468428472795;4835.975820120877;4837.637177211653;4801.063792560417;4802.903941921212;4805.183118869871;4808.543188397097;4815.975920120377;4673.228917228619;4860.9548946976565;4865.367502224688;4898.387701537308;4919.237518184687;4707.540662394532;4713.515242698539;4721.268203428474;4732.73969931657;4780.569748722632 23:19:36 INFO - PROCESS | 13358 | |5;yui.html;668.665667166417;695.8434352270739;700.1982729774405;743.4349894080498;767.9270469305416;12563.120581559295;13861.722765544682;13862.029657923937;13873.445062197512;13886.097403668755;13455.192688438701;13566;13608.523701670443;13635.590932272024;13715.519956801514;672.4049216443458;675.1729131813514;800.519688187089;813.2071230550196;815.2458975447717;791.9722809701661;806.6773290683715;812.2243257688923;812.305478815613;823.7322870067252;854.692310768123;855.5465603230298;855.6534603485599;858.635080090962;858.8325276571071;701.8420855307565;707.1125737199833;849.7875531117221;849.9362547808909;850.9829803403928;535.5180337696065;538.6175815171233;612.9938700612981;614.2874265851591;632.7690393006566;13156.868431315661;13194.53819116336;13210.141340812912;13210.273434961073;13225.272610006446;12862.614121576367;12965.935170324183;12996.41516131767;13162.473501059947;13251.403686834115;10004;10209.38743675382;10326.535305911177;11062.115030797517;11189.104871610332;4102.405151253081;4138.275801734684;4139.19285739281;4178.6657067434535;4225.302825033866;61146;62306.68846655783;63061.36938630601;63971.680141599456;64862.838030387036;10094.293399461967;10137.69586912379;10233.334833235816;10327.431991240404;10447.373157610416;18212;18224.908875455538;18239.27042918295;18242.270309187483;18244.179011944496;6232.719527621268;6237.189165408558;6403.615783053032;6460.644664543402;6485.156929599122;3628.1111127773866;3681.2637472505608;3702.851885924533;3729.6083911189476;3743.082944678571;2788.8884444622395;2834.0930902111127;2852.914412567626;2911.78161637878;2919.737223649882;487.8658368948497;498.2252597211299;500.60202139299344;506.65729878432023;517.1312195511501;426.75035104464234;507.92381142828873;521.903611236278;552.3234038303046;576.0437673462033;7168.17565979919;7381.907441776978;7709.267619576131;7800.843983120306;7831.960840195762;519.2626470412024;527.4646234072419;542.3112646938366;542.3573065916901;546.0907588864576;3623.293457775761;3760.9059773505332;3796.8101594920145;3844.6539811417106;3964.900877478086;51829.48170518322;54324.45675543273;54333.45666543363;59687;60431.395686043455;42374;45617.771911140895;47162.76418617885;53597.46402536003;53902.73048634732;49296.50703492919;49507.53447258657;50022.499775002514;51158.23262650989;51825;8038.718644847402;8494.447860889022;8798.912010879809;8911.821763564822;8984.55077246122;35352;36011;36105.638943610225;36241;37255.25489490249;41406.79296603498;45665;46683.09827468067;47129.528704713885;49137;11284.32294062359;11334.60328888485;11355.318680879174;11547.653570392902;12061;11723.531058757895;12387.504499819906;12520.248785072925;12547.686307842378;12944.805827912778;11747.823782643438;12062.276263424223;12087.818682719942;12106.576269830513;12123.696907577381;12062.336571488653;12126.696832579257;12224.266544007389;12380;12835.294058826854;11433.428328583705;11562.94218528902;12050.879491205327;12085.637430877088;12142.635720928387;9556.283278753983;9736.172425343924;9891.406515609087;10114.342567733074;10213.642522511676;9830.557624906753;10210.642627508001;10263.332883362558;10278;10316.690499285034 23:19:36 INFO - PROCESS | 13358 | __end_tp_report 23:19:36 INFO - PROCESS | 13358 | __start_cc_report 23:19:36 INFO - PROCESS | 13358 | _x_x_mozilla_cycle_collect,1217 23:19:36 INFO - PROCESS | 13358 | __end_cc_report 23:19:36 INFO - PROCESS | 13358 | __startTimestamp1482823176241__endTimestamp 23:19:36 INFO - PROCESS | 13358 | 23:19:36 INFO - PROCESS | 13358 | ------- Summary: start ------- 23:19:36 INFO - PROCESS | 13358 | Number of tests: 6 23:19:36 INFO - PROCESS | 13358 | 23:19:36 INFO - PROCESS | 13358 | [#0] dojo.html Cycles:165 Average:4655.01 Median:4803.50 stddev:489.22 (10.2%) stddev-sans-first:462.61 23:19:36 INFO - PROCESS | 13358 | Values: 2571.3 2593.5 2603.3 2623.3 2628.0 4675.2 4675.8 4697.8 4702.0 4716.6 4724.3 4757.8 4791.7 4803.5 4877.0 4295.3 4296.4 4332.0 4374.2 4453.8 4025.4 4364.5 4466.3 4493.4 4543.0 4249.7 4272.0 4412.5 4413.6 4506.2 4310.8 4316.8 4331.5 4339.2 4342.0 4304.7 4305.8 4306.6 4313.3 4318.6 4677.3 4679.2 4685.3 4688.9 4700.8 5013.2 5022.3 5042.7 5083.9 5127.4 5096.3 5103.4 5108.2 5108.3 5155.9 4625.4 4739.0 4787.2 4789.4 4831.4 5042.0 5072.3 5076.8 5097.0 5174.8 4883.8 4914.2 4925.6 4929.0 4979.9 4846.4 4999.2 5000.6 5002.0 5007.9 4833.3 4892.8 4894.4 4894.7 4908.3 4887.7 4908.1 4908.8 4910.5 4912.2 4207.8 4214.7 4215.4 4216.0 4218.4 3684.8 3774.7 3774.9 3775.6 3777.7 4325.0 4344.9 4388.6 4393.8 4394.9 5035.4 5038.9 5042.1 5047.4 5089.5 4348.6 4353.7 4354.8 4387.9 4390.0 4416.9 4449.3 4461.1 4521.7 4543.1 4716.4 4759.1 4762.2 4792.9 4807.4 5104.4 5113.5 5117.2 5118.0 5159.1 4611.8 4658.0 4744.2 4753.4 4798.6 4922.1 4960.0 4979.3 5018.7 5072.1 5099.7 5101.2 5103.9 5111.9 5159.5 4841.8 4964.6 4968.3 4970.5 4971.7 4836.4 4858.3 4859.7 4873.2 4933.3 4813.7 4821.6 4840.3 4843.9 4891.7 4893.7 4935.3 4941.2 4943.2 4990.8 4803.5 4841.0 4864.5 4873.0 4873.3 23:19:36 INFO - PROCESS | 13358 | 23:19:36 INFO - PROCESS | 13358 | [#1] ext.html Cycles:180 Average:44760.46 Median:19068.58 stddev:77249.75 (405.1%) stddev-sans-first:77395.00 23:19:36 INFO - PROCESS | 13358 | Values: 506.6 536.6 548.7 561.1 567.7 27953.7 37349.0 38806.4 38813.8 43323.1 40286.8 40774.2 40802.4 40810.0 40861.8 7402.8 7502.1 7587.2 7589.6 7591.3 7462.7 9373.5 9643.8 9643.8 9996.2 9938.6 10003.6 10016.2 10018.3 10018.7 9906.9 9964.9 9971.8 9976.0 9979.9 9805.2 9852.8 9877.9 9881.0 9896.6 36039.3 36216.5 36269.6 36303.6 36385.6 3093.5 3150.7 3222.6 3370.4 3412.3 27351.0 27807.3 27896.2 28360.4 28390.6 3397.3 3447.7 3466.5 3545.4 3616.9 232367.0 234059.0 234696.0 235060.0 235261.0 49518.5 49864.3 49887.5 49917.0 49936.8 52052.2 52168.3 52201.0 52241.7 52260.2 14034.3 14694.7 14700.0 14700.3 14711.6 9433.4 9534.8 11041.4 11095.1 11320.0 14569.6 15131.8 15155.6 15177.2 15307.8 5648.6 5663.7 5860.8 5889.9 5907.5 762.7 856.6 889.4 899.2 913.4 25295.6 26765.3 27402.2 27492.6 27619.9 978.6 990.7 1021.4 1070.5 1093.5 13771.7 14673.0 14673.6 14716.7 14813.9 338330.0 341967.0 342770.0 343560.0 343611.0 48847.0 48992.3 49101.0 49163.3 49409.0 1211.3 1218.7 1236.3 1242.4 1250.1 25731.7 26306.2 26844.3 26890.5 27164.6 40988.2 41408.4 41467.4 41470.4 41668.0 297520.0 297887.0 298185.0 298437.0 298718.0 29930.3 34276.0 34794.8 37976.0 38200.2 39131.4 39321.4 39762.6 40468.4 40523.0 34143.3 34709.5 34797.7 34832.3 34924.7 31070.7 33375.8 33729.0 34165.8 34670.7 32816.7 33265.8 33290.0 33419.0 33755.8 29526.3 30625.8 31075.7 31146.0 31239.0 23630.5 24247.9 24253.3 24582.1 24693.0 23:19:36 INFO - PROCESS | 13358 | 23:19:36 INFO - PROCESS | 13358 | [#2] jquery.html Cycles:180 Average:21885.21 Median:5263.13 stddev:56088.40 (1065.7%) stddev-sans-first:56222.43 23:19:36 INFO - PROCESS | 13358 | Values: 349.7 366.9 367.5 370.7 392.3 12741.4 15486.8 15749.2 15782.9 16800.7 16390.3 16452.5 17000.3 17093.4 17118.7 4821.8 5075.4 5123.2 5326.5 5373.2 3637.3 3798.7 5262.1 5304.7 5328.3 5118.9 5323.9 5347.2 5409.4 5410.4 5391.3 5400.5 5412.8 5702.3 5942.5 5260.3 5264.2 5273.0 5280.1 5281.6 12190.2 12393.3 12559.2 12657.5 12659.8 1858.8 1863.5 1922.7 2090.4 2281.5 8511.5 8663.3 9003.5 9173.0 9184.2 1588.0 1692.8 1723.2 1754.0 1788.9 63426.7 64531.0 65882.3 65999.7 67639.3 8921.2 9284.6 9318.1 9529.9 9530.5 12395.6 12400.1 12408.1 12422.4 12459.8 7084.3 7151.3 7200.9 7238.7 7310.9 6497.2 6596.4 6812.6 6840.7 6863.1 3909.6 3958.7 3962.4 3970.6 4013.1 1608.3 1686.0 1707.9 1724.4 1757.2 816.6 833.7 841.0 850.6 904.8 15848.9 16910.0 16934.8 17174.2 17380.7 808.0 831.6 838.2 839.8 856.6 8974.6 9111.5 9501.6 9506.5 9694.9 334652.0 339050.0 339758.0 341389.0 341718.0 51783.5 52926.2 70946.3 71821.3 73314.6 880.2 896.8 936.6 936.8 939.4 14533.8 14648.6 14785.6 15126.3 15548.7 35422.6 35488.8 35546.0 36014.0 36069.6 47407.0 49708.3 49758.5 50839.0 51543.2 7150.2 7280.2 7487.7 7509.2 7528.9 10038.8 10946.0 11082.7 11426.8 11521.3 8762.4 9532.5 9708.0 9799.5 10208.5 9185.4 10107.1 10206.4 10254.4 10413.8 9749.5 9796.2 9930.8 10038.6 10441.8 7018.0 7211.3 7395.1 7443.1 7651.1 8561.5 8712.7 9716.3 9811.2 9927.7 23:19:36 INFO - PROCESS | 13358 | 23:19:36 INFO - PROCESS | 13358 | [#3] mootools.html Cycles:180 Average:4923.18 Median:4812.33 stddev:2689.18 (55.9%) stddev-sans-first:2683.37 23:19:36 INFO - PROCESS | 13358 | Values: 1357.7 1361.0 1479.6 1670.6 1706.5 7102.5 7591.4 7602.8 7604.5 7608.7 6680.5 6832.1 7105.1 7403.4 7485.7 2130.3 2188.9 2193.7 2194.5 2195.5 2213.3 2243.7 2258.3 2260.8 2266.7 2145.4 2149.4 2172.8 2175.0 2180.8 2154.9 2171.8 2173.0 2174.7 2176.0 2205.3 2231.9 2238.7 2240.9 2243.0 7418.3 7466.8 7485.4 7486.6 7488.9 4550.4 4562.2 4619.1 4690.1 4701.5 2970.3 3048.0 3137.5 3148.2 3157.5 891.5 920.2 947.4 962.8 966.3 9706.6 9990.2 9992.1 9999.5 10000.5 3988.4 4245.9 4277.9 4308.9 4320.3 8675.8 8680.4 8682.4 8683.3 8684.6 4656.4 4659.5 4666.4 4673.7 4757.4 3782.4 3785.2 4177.4 4224.6 4311.3 3599.8 3650.6 3657.9 3681.3 3754.5 1213.7 1214.5 1228.2 1242.8 1246.5 792.2 793.5 794.3 809.6 812.5 4621.3 4670.5 4791.0 4809.5 4935.9 405.6 418.5 843.3 932.8 948.0 3083.2 3083.9 3152.8 3289.1 3311.0 8799.4 9192.9 9205.1 9207.4 9212.6 9141.6 9153.3 9158.2 9172.0 9184.3 7042.3 7173.1 7177.4 7179.3 7187.3 4688.3 4730.6 4748.9 4805.3 4815.2 8311.3 8453.6 8483.4 8490.7 8530.6 8420.1 8438.2 8452.3 8488.9 8598.0 5633.0 5776.2 5796.1 6167.2 6299.9 6770.5 6823.8 7028.1 7200.7 7202.7 6563.8 6766.5 6847.0 6886.5 6924.5 6573.3 6595.8 6631.6 6698.8 6726.8 5735.2 5741.7 5787.8 5790.4 5842.9 4296.4 4325.4 4333.9 4382.5 4391.1 4267.6 4339.0 4466.7 4476.2 4531.3 23:19:36 INFO - PROCESS | 13358 | 23:19:36 INFO - PROCESS | 13358 | [#4] prototype.html Cycles:180 Average:4121.09 Median:4662.82 stddev:1170.73 (25.1%) stddev-sans-first:1139.34 23:19:36 INFO - PROCESS | 13358 | Values: 353.1 353.1 354.8 364.2 365.0 4796.4 4816.8 4818.9 4826.9 4850.8 4724.4 4762.3 4770.8 4777.4 4780.8 3602.9 3709.8 3715.5 3755.3 3765.2 3727.3 3796.0 3803.9 3811.0 3816.6 3720.3 3723.3 3748.4 3755.9 3775.4 3606.7 3718.4 3727.0 3766.5 3766.7 3434.0 3437.4 3438.5 3438.6 3443.5 4626.2 4635.8 4640.0 4654.7 4667.5 4208.3 4348.2 4353.8 4362.2 4388.9 4955.7 4975.9 4981.0 4990.6 5007.6 3728.8 3735.8 3745.7 3746.2 3768.7 5167.4 5184.5 5209.9 5212.8 5219.4 3714.6 3805.7 3840.5 3842.5 3867.3 3894.2 3895.4 3896.9 3897.2 3902.9 4322.9 4328.7 4338.6 4349.7 4350.3 4686.7 4967.2 5021.3 5021.9 5028.2 1191.7 1205.6 1212.2 1212.5 1219.4 784.4 824.4 824.9 834.3 836.2 4223.2 4257.2 4307.2 4363.8 4446.0 5146.2 5149.6 5177.9 5187.4 5199.1 4462.1 4465.6 4470.7 4475.0 4480.9 2553.5 2633.6 2649.0 2657.2 2662.5 4627.7 4658.9 4666.7 4677.9 4678.5 5035.3 5064.8 5149.2 5163.4 5187.7 4406.3 4677.9 4707.9 4715.3 4723.7 4965.4 5026.9 5031.7 5041.8 5042.5 4913.4 4915.0 4919.4 4929.0 4932.7 4436.7 4594.6 4647.9 4653.5 4685.7 3465.4 3476.4 3487.8 3507.4 3511.9 4976.2 4984.6 4990.9 5004.7 5028.5 4696.8 4811.6 4841.5 4852.5 4907.7 4818.9 4822.0 4832.5 4836.0 4837.6 4801.1 4802.9 4805.2 4808.5 4816.0 4673.2 4861.0 4865.4 4898.4 4919.2 4707.5 4713.5 4721.3 4732.7 4780.6 23:19:36 INFO - PROCESS | 13358 | 23:19:36 INFO - PROCESS | 13358 | [#5] yui.html Cycles:180 Average:14338.16 Median:4201.98 stddev:17131.31 (407.7%) stddev-sans-first:17148.61 23:19:36 INFO - PROCESS | 13358 | Values: 668.7 695.8 700.2 743.4 767.9 12563.1 13861.7 13862.0 13873.4 13886.1 13455.2 13566.0 13608.5 13635.6 13715.5 672.4 675.2 800.5 813.2 815.2 792.0 806.7 812.2 812.3 823.7 854.7 855.5 855.7 858.6 858.8 701.8 707.1 849.8 849.9 851.0 535.5 538.6 613.0 614.3 632.8 13156.9 13194.5 13210.1 13210.3 13225.3 12862.6 12965.9 12996.4 13162.5 13251.4 10004.0 10209.4 10326.5 11062.1 11189.1 4102.4 4138.3 4139.2 4178.7 4225.3 61146.0 62306.7 63061.4 63971.7 64862.8 10094.3 10137.7 10233.3 10327.4 10447.4 18212.0 18224.9 18239.3 18242.3 18244.2 6232.7 6237.2 6403.6 6460.6 6485.2 3628.1 3681.3 3702.9 3729.6 3743.1 2788.9 2834.1 2852.9 2911.8 2919.7 487.9 498.2 500.6 506.7 517.1 426.8 507.9 521.9 552.3 576.0 7168.2 7381.9 7709.3 7800.8 7832.0 519.3 527.5 542.3 542.4 546.1 3623.3 3760.9 3796.8 3844.7 3964.9 51829.5 54324.5 54333.5 59687.0 60431.4 42374.0 45617.8 47162.8 53597.5 53902.7 49296.5 49507.5 50022.5 51158.2 51825.0 8038.7 8494.4 8798.9 8911.8 8984.6 35352.0 36011.0 36105.6 36241.0 37255.3 41406.8 45665.0 46683.1 47129.5 49137.0 11284.3 11334.6 11355.3 11547.7 12061.0 11723.5 12387.5 12520.2 12547.7 12944.8 11747.8 12062.3 12087.8 12106.6 12123.7 12062.3 12126.7 12224.3 12380.0 12835.3 11433.4 11562.9 12050.9 12085.6 12142.6 9556.3 9736.2 9891.4 10114.3 10213.6 9830.6 10210.6 10263.3 10278.0 10316.7 23:19:36 INFO - PROCESS | 13358 | -------- Summary: end -------- 23:19:36 INFO - PROCESS | 13358 | 23:19:37 INFO - TEST-INFO | 13358: exit 0 23:19:37 INFO - TEST-OK | dromaeo_css | took 1120674ms 23:19:37 INFO - TEST-START | kraken 23:19:37 INFO - operating with platform_type : linux_ 23:19:37 INFO - Initialising browser for kraken test... 23:19:37 INFO - TEST-INFO | started process 13661 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpHHvMkV/profile http://localhost:53140/getInfo.html) 23:19:37 INFO - PROCESS | 13661 | 1482823177642 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 23:19:39 INFO - PROCESS | 13661 | JavaScript error: chrome://global/content/browser-content.js, line 1772: ReferenceError: event is not defined 23:19:39 INFO - PROCESS | 13661 | JavaScript error: chrome://global/content/browser-content.js, line 1772: ReferenceError: event is not defined 23:19:40 INFO - PROCESS | 13661 | JavaScript error: chrome://global/content/browser-content.js, line 1772: ReferenceError: event is not defined 23:19:40 INFO - PROCESS | 13661 | __metrics Screen width/height:1600/1200 23:19:40 INFO - PROCESS | 13661 | colorDepth:24 23:19:40 INFO - PROCESS | 13661 | Browser inner width/height: 1024/697 23:19:40 INFO - PROCESS | 13661 | __metrics 23:19:41 INFO - PROCESS | 13661 | 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" 23:19:41 INFO - PROCESS | 13661 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 23:19:42 INFO - PROCESS | 13661 | console.error: PushService: 23:19:42 INFO - PROCESS | 13661 | stateChangeProcessEnqueue: Error transitioning state 23:19:42 INFO - PROCESS | 13661 | UnknownError 23:19:42 INFO - TEST-INFO | 13661: exit 0 23:19:42 INFO - Browser initialized. 23:19:42 INFO - Running cycle 1/1 for kraken test... 23:19:42 INFO - TEST-INFO | started process 13743 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpHHvMkV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:19:54 INFO - PROCESS | 13743 | RSS: Main: 151744512 23:19:54 INFO - PROCESS | 13743 | 23:19:57 INFO - PROCESS | 13743 | Cycle 1(1): loaded http://localhost:53140/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53140/tests/kraken/driver.html?testName=audio-beat-detection) 23:19:58 INFO - PROCESS | 13743 | RSS: Main: 205324288 23:19:58 INFO - PROCESS | 13743 | 23:20:01 INFO - PROCESS | 13743 | Cycle 1(1): loaded http://localhost:53140/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53140/tests/kraken/driver.html?testName=audio-dft) 23:20:01 INFO - PROCESS | 13743 | RSS: Main: 184627200 23:20:01 INFO - PROCESS | 13743 | 23:20:04 INFO - PROCESS | 13743 | Cycle 1(1): loaded http://localhost:53140/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53140/tests/kraken/driver.html?testName=audio-fft) 23:20:05 INFO - PROCESS | 13743 | RSS: Main: 267616256 23:20:05 INFO - PROCESS | 13743 | 23:20:07 INFO - PROCESS | 13743 | Cycle 1(1): loaded http://localhost:53140/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53140/tests/kraken/driver.html?testName=audio-oscillator) 23:20:08 INFO - PROCESS | 13743 | RSS: Main: 178765824 23:20:08 INFO - PROCESS | 13743 | 23:20:10 INFO - PROCESS | 13743 | Cycle 1(1): loaded http://localhost:53140/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53140/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:20:10 INFO - PROCESS | 13743 | RSS: Main: 181469184 23:20:10 INFO - PROCESS | 13743 | 23:20:14 INFO - PROCESS | 13743 | Cycle 1(1): loaded http://localhost:53140/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53140/tests/kraken/driver.html?testName=imaging-darkroom) 23:20:14 INFO - PROCESS | 13743 | RSS: Main: 220389376 23:20:14 INFO - PROCESS | 13743 | 23:20:18 INFO - PROCESS | 13743 | Cycle 1(1): loaded http://localhost:53140/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53140/tests/kraken/driver.html?testName=imaging-desaturate) 23:20:18 INFO - PROCESS | 13743 | RSS: Main: 215277568 23:20:18 INFO - PROCESS | 13743 | 23:20:21 INFO - PROCESS | 13743 | Cycle 1(1): loaded http://localhost:53140/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53140/tests/kraken/driver.html?testName=json-parse-financial) 23:20:22 INFO - PROCESS | 13743 | RSS: Main: 216870912 23:20:22 INFO - PROCESS | 13743 | 23:20:24 INFO - PROCESS | 13743 | Cycle 1(1): loaded http://localhost:53140/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53140/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:20:24 INFO - PROCESS | 13743 | RSS: Main: 180301824 23:20:24 INFO - PROCESS | 13743 | 23:20:26 INFO - PROCESS | 13743 | Cycle 1(1): loaded http://localhost:53140/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53140/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:20:27 INFO - PROCESS | 13743 | RSS: Main: 187285504 23:20:27 INFO - PROCESS | 13743 | 23:20:29 INFO - PROCESS | 13743 | Cycle 1(1): loaded http://localhost:53140/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53140/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:20:30 INFO - PROCESS | 13743 | RSS: Main: 196259840 23:20:30 INFO - PROCESS | 13743 | 23:20:32 INFO - PROCESS | 13743 | Cycle 1(1): loaded http://localhost:53140/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53140/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:20:33 INFO - PROCESS | 13743 | RSS: Main: 205717504 23:20:33 INFO - PROCESS | 13743 | 23:20:36 INFO - PROCESS | 13743 | Cycle 1(1): loaded http://localhost:53140/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53140/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:20:37 INFO - PROCESS | 13743 | RSS: Main: 202358784 23:20:37 INFO - PROCESS | 13743 | 23:20:39 INFO - PROCESS | 13743 | Cycle 1(1): loaded http://localhost:53140/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53140/tests/kraken/driver.html?testName=ai-astar) 23:20:39 INFO - PROCESS | 13743 | RSS: Main: 253431808 23:20:39 INFO - PROCESS | 13743 | 23:20:39 INFO - PROCESS | 13743 | __start_tp_report 23:20:39 INFO - PROCESS | 13743 | _x_x_mozilla_page_load 23:20:39 INFO - PROCESS | 13743 | _x_x_mozilla_page_load_details 23:20:39 INFO - PROCESS | 13743 | |i|pagename|runs| 23:20:39 INFO - PROCESS | 13743 | |0;ai-astar;105;114;114;114;117;114;122;115;116;116 23:20:39 INFO - PROCESS | 13743 | |1;audio-beat-detection;150;129;125;157;127;127;131;125;145;124 23:20:39 INFO - PROCESS | 13743 | |2;audio-dft;163;161;161;163;165;163;162;163;162;160 23:20:39 INFO - PROCESS | 13743 | |3;audio-fft;76;77;77;105;77;79;84;76;90;76 23:20:39 INFO - PROCESS | 13743 | |4;audio-oscillator;85;84;110;86;84;85;99;85;86;117 23:20:39 INFO - PROCESS | 13743 | |5;imaging-gaussian-blur;117;117;117;117;117;118;117;117;118;117 23:20:39 INFO - PROCESS | 13743 | |6;imaging-darkroom;150;148;148;149;149;150;149;149;149;151 23:20:39 INFO - PROCESS | 13743 | |7;imaging-desaturate;95;96;95;95;95;95;95;96;96;96 23:20:39 INFO - PROCESS | 13743 | |8;json-parse-financial;59;59;58;58;59;72;60;59;61;59 23:20:39 INFO - PROCESS | 13743 | |9;json-stringify-tinderbox;68;67;66;66;67;67;67;67;68;67 23:20:39 INFO - PROCESS | 13743 | |10;stanford-crypto-aes;88;85;88;85;86;87;86;94;85;85 23:20:39 INFO - PROCESS | 13743 | |11;stanford-crypto-ccm;154;143;132;141;143;134;138;131;177;164 23:20:39 INFO - PROCESS | 13743 | |12;stanford-crypto-pbkdf2;174;173;176;179;174;175;174;176;174;175 23:20:39 INFO - PROCESS | 13743 | |13;stanford-crypto-sha256-iterative;68;66;76;67;68;68;65;70;67;66 23:20:39 INFO - PROCESS | 13743 | __end_tp_report 23:20:39 INFO - PROCESS | 13743 | __start_cc_report 23:20:39 INFO - PROCESS | 13743 | _x_x_mozilla_cycle_collect,1818 23:20:39 INFO - PROCESS | 13743 | __end_cc_report 23:20:39 INFO - PROCESS | 13743 | __startTimestamp1482823239323__endTimestamp 23:20:39 INFO - PROCESS | 13743 | 23:20:39 INFO - PROCESS | 13743 | ------- Summary: start ------- 23:20:39 INFO - PROCESS | 13743 | Number of tests: 14 23:20:39 INFO - PROCESS | 13743 | 23:20:39 INFO - PROCESS | 13743 | [#0] ai-astar Cycles:10 Average:114.70 Median:115.50 stddev:4.19 (3.6%) stddev-sans-first:2.59 23:20:39 INFO - PROCESS | 13743 | Values: 105.0 114.0 114.0 114.0 117.0 114.0 122.0 115.0 116.0 116.0 23:20:39 INFO - PROCESS | 13743 | 23:20:39 INFO - PROCESS | 13743 | [#1] audio-beat-detection Cycles:10 Average:134.00 Median:130.00 stddev:12.02 (9.2%) stddev-sans-first:11.27 23:20:39 INFO - PROCESS | 13743 | Values: 150.0 129.0 125.0 157.0 127.0 127.0 131.0 125.0 145.0 124.0 23:20:39 INFO - PROCESS | 13743 | 23:20:39 INFO - PROCESS | 13743 | [#2] audio-dft Cycles:10 Average:162.30 Median:163.00 stddev:1.42 (0.9%) stddev-sans-first:1.48 23:20:39 INFO - PROCESS | 13743 | Values: 163.0 161.0 161.0 163.0 165.0 163.0 162.0 163.0 162.0 160.0 23:20:39 INFO - PROCESS | 13743 | 23:20:39 INFO - PROCESS | 13743 | [#3] audio-fft Cycles:10 Average:81.70 Median:77.00 stddev:9.36 (12.2%) stddev-sans-first:9.70 23:20:39 INFO - PROCESS | 13743 | Values: 76.0 77.0 77.0 105.0 77.0 79.0 84.0 76.0 90.0 76.0 23:20:39 INFO - PROCESS | 13743 | 23:20:39 INFO - PROCESS | 13743 | [#4] audio-oscillator Cycles:10 Average:92.10 Median:85.00 stddev:12.22 (14.4%) stddev-sans-first:12.69 23:20:39 INFO - PROCESS | 13743 | Values: 85.0 84.0 110.0 86.0 84.0 85.0 99.0 85.0 86.0 117.0 23:20:39 INFO - PROCESS | 13743 | 23:20:39 INFO - PROCESS | 13743 | [#5] imaging-gaussian-blur Cycles:10 Average:117.20 Median:117.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 23:20:39 INFO - PROCESS | 13743 | Values: 117.0 117.0 117.0 117.0 117.0 118.0 117.0 117.0 118.0 117.0 23:20:39 INFO - PROCESS | 13743 | 23:20:39 INFO - PROCESS | 13743 | [#6] imaging-darkroom Cycles:10 Average:149.20 Median:149.00 stddev:0.92 (0.6%) stddev-sans-first:0.93 23:20:39 INFO - PROCESS | 13743 | Values: 150.0 148.0 148.0 149.0 149.0 150.0 149.0 149.0 149.0 151.0 23:20:39 INFO - PROCESS | 13743 | 23:20:39 INFO - PROCESS | 13743 | [#7] imaging-desaturate Cycles:10 Average:95.40 Median:95.50 stddev:0.52 (0.5%) stddev-sans-first:0.53 23:20:39 INFO - PROCESS | 13743 | Values: 95.0 96.0 95.0 95.0 95.0 95.0 95.0 96.0 96.0 96.0 23:20:39 INFO - PROCESS | 13743 | 23:20:39 INFO - PROCESS | 13743 | [#8] json-parse-financial Cycles:10 Average:60.40 Median:59.00 stddev:4.17 (7.1%) stddev-sans-first:4.39 23:20:39 INFO - PROCESS | 13743 | Values: 59.0 59.0 58.0 58.0 59.0 72.0 60.0 59.0 61.0 59.0 23:20:39 INFO - PROCESS | 13743 | 23:20:39 INFO - PROCESS | 13743 | [#9] json-stringify-tinderbox Cycles:10 Average:67.00 Median:67.00 stddev:0.67 (1.0%) stddev-sans-first:0.60 23:20:39 INFO - PROCESS | 13743 | Values: 68.0 67.0 66.0 66.0 67.0 67.0 67.0 67.0 68.0 67.0 23:20:39 INFO - PROCESS | 13743 | 23:20:39 INFO - PROCESS | 13743 | [#10] stanford-crypto-aes Cycles:10 Average:86.90 Median:86.50 stddev:2.77 (3.2%) stddev-sans-first:2.91 23:20:39 INFO - PROCESS | 13743 | Values: 88.0 85.0 88.0 85.0 86.0 87.0 86.0 94.0 85.0 85.0 23:20:39 INFO - PROCESS | 13743 | 23:20:39 INFO - PROCESS | 13743 | [#11] stanford-crypto-ccm Cycles:10 Average:145.70 Median:143.00 stddev:14.98 (10.5%) stddev-sans-first:15.59 23:20:39 INFO - PROCESS | 13743 | Values: 154.0 143.0 132.0 141.0 143.0 134.0 138.0 131.0 177.0 164.0 23:20:39 INFO - PROCESS | 13743 | 23:20:39 INFO - PROCESS | 13743 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.00 Median:175.00 stddev:1.70 (1.0%) stddev-sans-first:1.76 23:20:39 INFO - PROCESS | 13743 | Values: 174.0 173.0 176.0 179.0 174.0 175.0 174.0 176.0 174.0 175.0 23:20:39 INFO - PROCESS | 13743 | 23:20:39 INFO - PROCESS | 13743 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.10 Median:68.00 stddev:3.11 (4.6%) stddev-sans-first:3.30 23:20:39 INFO - PROCESS | 13743 | Values: 68.0 66.0 76.0 67.0 68.0 68.0 65.0 70.0 67.0 66.0 23:20:39 INFO - PROCESS | 13743 | -------- Summary: end -------- 23:20:39 INFO - PROCESS | 13743 | 23:20:40 INFO - TEST-INFO | 13743: exit 0 23:20:40 INFO - TEST-OK | kraken | took 63333ms 23:20:40 INFO - SUITE-END | took 1184s 23:20:40 INFO - Completed test suite (00:19:44) 23:20:40 INFO - javascript benchmark 23:20:41 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2571.2671888511773, 2593.50723362561, 2603.2710840964523, 2623.2785983854437, 2627.97508971501, 4675.20521511343, 4675.836345727896, 4697.8120875165, 4701.976490117545, 4716.57550820427, 4724.267738500538, 4757.785899634516, 4791.736454495011, 4803.495632958541, 4876.975615121928, 4295.269804133305, 4296.419983302261, 4332.0469496710675, 4374.168907907488, 4453.84411545596, 4025.4364388985564, 4364.454443194601, 4466.307722303032, 4493.370928070073, 4543.0, 4249.68127390447, 4271.9572804271875, 4412.492563355223, 4413.602775750166, 4506.188886000518, 4310.827566897352, 4316.848910288156, 4331.45856767904, 4339.1972485090355, 4342.044750154961, 4304.655627549789, 4305.806238719265, 4306.612404883575, 4313.309870420718, 4318.589733975267, 4677.321788340671, 4679.181143299909, 4685.273782563708, 4688.906221875577, 4700.835470758541, 5013.222950442687, 5022.296878437021, 5042.697438153723, 5083.8983220335385, 5127.410347809975, 5096.311997880276, 5103.362079740033, 5108.208227724708, 5108.310378098946, 5155.9484405155845, 4625.444946606429, 4739.0, 4787.162246606865, 4789.425268967677, 4831.371921650233, 5041.974790126099, 5072.315237442972, 5076.82231121909, 5097.0315640028275, 5174.793008279702, 4883.755812209446, 4914.238293064581, 4925.556699896955, 4929.0, 4979.850604481871, 4846.36997190363, 4999.200127979579, 5000.649954503223, 5002.024605202024, 5007.874803129878, 4833.299171620095, 4892.779824907888, 4894.412670479565, 4894.730789806595, 4908.2637604358915, 4887.706737595756, 4908.11653902301, 4908.803647854046, 4910.459849416633, 4912.1649319615035, 4207.789610519461, 4214.726042807208, 4215.409842622035, 4215.957840421618, 4218.409422680827, 3684.797336146538, 3774.7168962327937, 3774.9433758493647, 3775.6413140751583, 3777.660010599004, 4324.978375108104, 4344.891377715582, 4388.626966707866, 4393.802278897393, 4394.912101757947, 5035.370578677665, 5038.949610503848, 5042.092423363756, 5047.369078865142, 5089.51649593288, 4348.63036641889, 4353.73877567347, 4354.802429331896, 4387.912241755211, 4389.978050109729, 4416.873697207192, 4449.265871131227, 4461.107778444324, 4521.660875434355, 4543.136804007228, 4716.3632909557455, 4759.119562880878, 4762.16662084127, 4792.9281060784815, 4807.423109226916, 5104.438511763628, 5113.539781419541, 5117.2068329409, 5117.9575889528705, 5159.122949098587, 4611.815527378867, 4657.953420465752, 4744.217204161413, 4753.429588449409, 4798.568128868418, 4922.1140194765285, 4959.950400495949, 4979.278004689228, 5018.673786203885, 5072.112380333353, 5099.69401835891, 5101.234814777665, 5103.897922041612, 5111.897762044812, 5159.535641792257, 4841.782119804547, 4964.578010869117, 4968.3044373788425, 4970.453250142409, 4971.676841005323, 4836.419629644465, 4858.295547145714, 4859.659823812441, 4873.195922672861, 4933.333999909966, 4813.663043586916, 4821.63837712166, 4840.298156767179, 4843.903121937612, 4891.682040667345, 4893.7063776173545, 4935.284383764262, 4941.209406494943, 4943.159662857395, 4990.775415106256, 4803.495632958628, 4841.0, 4864.513548645249, 4872.975635121802, 4873.342098816614], "value": 4623.528333190027, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [506.5563145036646, 536.5519790974536, 548.6762809942135, 561.1050374652428, 567.7473524281692, 27953.72046279539, 37349.0, 38806.41790373147, 38813.83558493243, 43323.13353732931, 40286.79856600713, 40774.18451630966, 40802.38796418056, 40810.0, 40861.79569102158, 7402.814929626762, 7502.137254215753, 7587.241275872396, 7589.620518974057, 7591.316781489665, 7462.664180111908, 9373.531323433835, 9643.758906027335, 9643.758906027335, 9996.150327222194, 9938.602455901755, 10003.599856005787, 10016.198704103654, 10018.298719089667, 10018.749531261705, 9906.900930990705, 9964.900350996542, 9971.75070623233, 9976.0, 9979.900200998007, 9805.215582753362, 9852.753681158028, 9877.90122098777, 9880.95059524705, 9896.554655040469, 36039.27921441583, 36216.45675314846, 36269.63730362689, 36303.63696363029, 36385.63614363849, 3093.535969604555, 3150.6691797361177, 3222.6132864056462, 3370.359631669975, 3412.328897006347, 27350.985837404885, 27807.304817379525, 27896.163115106578, 28360.432791344057, 28390.57414138769, 3397.286569820341, 3447.6563123852234, 3466.497357883118, 3545.4327307630915, 3616.8553257869653, 232367.0, 234059.0, 234696.0, 235060.0, 235261.0, 49518.50481495211, 49864.252036219485, 49887.50112498901, 49917.0, 49936.75031624819, 52052.21921671178, 52168.261491235935, 52201.0, 52241.73879130656, 52260.21609675858, 14034.29828508591, 14694.706105877822, 14700.0, 14700.264986750619, 14711.55865324042, 9433.433993960385, 9534.809303813885, 11041.39272340029, 11095.1123910087, 11320.0, 14569.64898073449, 15131.848681513044, 15155.621109472351, 15177.165255911032, 15307.846921530641, 5648.63283886546, 5663.716814159276, 5860.824175274749, 5889.911651325234, 5907.4683278505145, 762.725418849225, 856.6230858422399, 889.3818795936891, 899.1547944931874, 913.4427998920653, 25295.62056569153, 26765.330866728487, 27402.17793466199, 27492.58761118585, 27619.86190069077, 978.6183388478499, 990.6631745206664, 1021.3667526133845, 1070.4808168038496, 1093.5188517052472, 13771.655708607364, 14673.0, 14673.559793206221, 14716.705665886837, 14813.925930370278, 338330.0, 341967.0, 342770.0, 343560.0, 343611.0, 48847.02305953932, 48992.265116023285, 49101.0, 49163.262551061765, 49409.0, 1211.2550781269456, 1218.6709588411256, 1236.3014896583402, 1242.3912282981273, 1250.0999280518013, 25731.742682572934, 26306.21081367562, 26844.328891777863, 26890.462190756465, 27164.592531111655, 40988.1802363957, 41408.37887431631, 41467.37798933079, 41470.37794433146, 41668.0, 297520.0, 297887.0, 298185.0, 298437.0, 298718.0, 29930.25174370658, 34276.0, 34794.82602586971, 37976.0, 38200.235995279385, 39131.41302880516, 39321.41017884677, 39762.60237397589, 40468.392974106, 40523.0, 34143.31713365669, 34709.47935780915, 34797.652023479444, 34832.30335393328, 34924.65075349214, 31070.689293106778, 33375.83312083424, 33729.0, 34165.82917085498, 34670.65329346674, 32816.67183328136, 33265.83367083149, 33290.0, 33419.0, 33755.83122084374, 29526.261843454085, 30625.846870765505, 31075.689243108183, 31146.0, 31239.0, 23630.527389452458, 24247.87876060679, 24253.272401827973, 24582.139625113035, 24693.0], "value": 17446.5808257209, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [349.73070735533656, 366.91377526281326, 367.5424096999236, 370.70158522389477, 392.2977869613392, 12741.426635801388, 15486.845131548682, 15749.21253937303, 15782.921085394586, 16800.66398672029, 16390.344386224566, 16452.506424807245, 17000.3199872005, 17093.40173093942, 17118.743218851727, 4821.807127714896, 5075.441701412841, 5123.2315152727015, 5326.547243484309, 5373.220882971967, 3637.327094487515, 3798.6771124454453, 5262.105442074857, 5304.681719096867, 5328.280682107924, 5118.897622047557, 5323.893522129555, 5347.197920311945, 5409.43200963899, 5410.3507579090365, 5391.299131112969, 5400.486953739428, 5412.756425960822, 5702.344230413514, 5942.494887934487, 5260.263563101169, 5264.157734762458, 5272.973635131838, 5280.128778751539, 5281.63028587999, 12190.2076365036, 12393.318367489785, 12559.183653062522, 12657.493700251978, 12659.810102848465, 1858.8327050565379, 1863.543431859203, 1922.663533881579, 2090.372888133569, 2281.4752606900506, 8511.531865747382, 8663.263622592087, 9003.504807235666, 9173.0, 9184.219341355993, 1587.992060039704, 1692.7714758507566, 1723.2331612432472, 1754.0, 1788.8658350623755, 63426.68286658537, 64531.0, 65882.3411765881, 65999.67000164969, 67639.32360676379, 8921.197092261727, 9284.628614855465, 9318.114779095975, 9529.857052144223, 9530.52347382628, 12395.566155184526, 12400.069994750429, 12408.131430799938, 12422.440990155465, 12459.750804983849, 7084.2915708428745, 7151.2848715128075, 7200.855982880313, 7238.674259658328, 7310.890336644955, 6497.155369801895, 6596.373344532358, 6812.557183783038, 6840.657967101724, 6863.10779598659, 3909.6285852843935, 3958.7030972677167, 3962.3660214365564, 3970.622790834866, 4013.097053163015, 1608.3245037084453, 1685.9578510537335, 1707.9146042697816, 1724.430937790526, 1757.2092558348795, 816.5508970066439, 833.7040350675422, 840.9950109619069, 850.6299759604592, 904.8371293167162, 15848.92075539615, 16910.0, 16934.83065169357, 17174.22715977784, 17380.65238695219, 807.9676812927535, 831.5966756123275, 838.1534650003418, 839.7858546070835, 856.5973992223645, 8974.641014359353, 9111.498867562348, 9501.572429240701, 9506.47714375716, 9694.854577181348, 334652.0, 339050.0, 339758.0, 341389.0, 341718.0, 51783.48216517862, 52926.20610690843, 70946.29053709397, 71821.28178718251, 73314.63342683253, 880.2122100719948, 896.7758060484748, 936.6019441737235, 936.8032713130319, 939.3940908114365, 14533.78199327032, 14648.560543183721, 14785.630359241106, 15126.319315630602, 15548.68902621964, 35422.64577354193, 35488.822555887054, 35546.0, 36014.0, 36069.63930360768, 47407.0, 49708.254376183664, 49758.53621956735, 50839.0, 51543.226851598, 7150.213476517683, 7280.235575264733, 7487.663055162422, 7509.211532788979, 7528.924710752823, 10038.849417258893, 10946.0, 11082.722931926766, 11426.771464570496, 11521.251118677259, 8762.430442021248, 9532.475713835805, 9708.0, 9799.461029643438, 10208.540615672164, 9185.357025008452, 10107.090361867467, 10206.387616742722, 10254.436006019741, 10413.791724165625, 9749.512524373895, 9796.216302695624, 9930.801383972424, 10038.598456061676, 10441.783093828319, 7018.0, 7211.314925082109, 7395.075615548057, 7443.106827180773, 7651.120121186135, 8561.48631082137, 8712.695055673275, 9716.3198576099, 9811.166050885755, 9927.702168934944], "value": 7758.312986303217, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1357.6537982814384, 1361.0268657909596, 1479.5709244319148, 1670.582354411397, 1706.5136436115702, 7102.46731495138, 7591.354734847545, 7602.847943041143, 7604.543727376361, 7608.733694320693, 6680.532362734611, 6832.077669514614, 7105.147382314117, 7403.444741644371, 7485.700571977128, 2130.3395947256363, 2188.9014994325294, 2193.6928829963817, 2194.484296190402, 2195.4730864592543, 2213.269621025058, 2243.741969673484, 2258.288639078685, 2260.762619924909, 2266.660000999847, 2145.420736401171, 2149.3659370485743, 2172.804447599716, 2174.967375489369, 2180.8364372672036, 2154.8707077575395, 2171.8045375916163, 2172.9782702172934, 2174.7390313162364, 2175.967360489578, 2205.3163519309064, 2231.944201394962, 2238.6977758002786, 2240.8543444676206, 2243.0354947372784, 7418.332350088464, 7466.775996720053, 7485.363744081759, 7486.5508069516, 7488.925110748877, 4550.38569793077, 4562.1560011398, 4619.14545809023, 4690.132325519756, 4701.529847015306, 2970.301979034925, 3047.9847600762077, 3137.4666306727913, 3148.212946763309, 3157.4790159623635, 891.527490430073, 920.1764420843375, 947.3842002698233, 962.7544976031137, 966.3284017607765, 9706.61173553057, 9990.150837178848, 9992.100710935978, 9999.450030248334, 10000.549975251131, 3988.4017397390044, 4245.8513952011535, 4277.914441711148, 4308.870733877926, 4320.265554855682, 8675.783105422415, 8680.435771674822, 8682.43564168327, 8683.348748843862, 8684.56577171139, 4656.3946686930485, 4659.510751371125, 4666.393368862026, 4673.6728429009645, 4757.3577567028005, 3782.432635104756, 3785.2051069275763, 4177.415161877339, 4224.577542245751, 4311.33174357975, 3599.8200089995394, 3650.634936506328, 3657.926841463156, 3681.2637472505608, 3754.5494540655295, 1213.6723084767063, 1214.5141943222782, 1228.2323547782637, 1242.7763002659428, 1246.4889395347864, 792.1761368177147, 793.4683761879554, 794.2970471133088, 809.6194788449419, 812.5246730662628, 4621.260598304322, 4670.486246512881, 4791.0, 4809.470958194595, 4935.925961110586, 405.56807000544325, 418.49571266623644, 843.3175814250609, 932.792034315563, 948.0139108567678, 3083.2291927018246, 3083.8612262448246, 3152.763542734304, 3289.0626171540994, 3311.0, 8799.384043117048, 9192.862107068398, 9205.125513076247, 9207.447553146698, 9212.585433655368, 9141.588628511732, 9153.267738581031, 9158.221551168226, 9172.0, 9184.311176661777, 7042.295770422917, 7173.10336207974, 7177.354038136488, 7179.317964793337, 7187.281271872876, 4688.3436318916065, 4730.574248317668, 4748.857534273978, 4805.327254184347, 4815.181419158822, 8311.33509319265, 8453.577321134042, 8483.36374771907, 8490.702825401328, 8530.616122274389, 8420.073791883011, 8438.240558349778, 8452.281556067803, 8488.915110849059, 8598.0, 5632.971835140962, 5776.162456443876, 5796.130580412803, 6167.198264225622, 6299.874002519832, 6770.45836333102, 6823.829404264933, 7028.121484814398, 7200.675969581277, 7202.71189152428, 6563.803085907431, 6766.492513061638, 6846.965765171142, 6886.51794374389, 6924.515283930278, 6573.342665733579, 6595.8021259362295, 6631.568948018364, 6698.83252918681, 6726.7981960541265, 5735.197072409783, 5741.712914354349, 5787.797427090031, 5790.392008839011, 5842.883142337045, 4296.398504209351, 4325.372820941009, 4333.913321733485, 4382.5179230285285, 4391.099824536026, 4267.637250833714, 4338.9566104338555, 4466.687331886867, 4476.194285028726, 4531.252343363308], "value": 4001.0649880560186, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [353.06437939460426, 353.1418652674002, 354.7623092528006, 364.1696930997327, 365.02902279935375, 4796.400449943757, 4816.783244753986, 4818.927716084261, 4826.927596086057, 4850.805967761294, 4724.385829842125, 4762.261849413338, 4770.809167633291, 4777.378940737708, 4780.832670856521, 3602.9279414411694, 3709.777413355194, 3715.516982792233, 3755.3428150073764, 3765.1904840459433, 3727.3413331067686, 3795.9810200948955, 3803.9429408558894, 3810.9618903811024, 3816.618338166189, 3720.2931443025873, 3723.2739615774935, 3748.444395575833, 3755.906102347436, 3775.433684947266, 3606.711463082947, 3718.4236443351324, 3727.0, 3766.531052889375, 3766.679832214265, 3433.9656603433896, 3437.415639341318, 3438.484227365915, 3438.644528497987, 3443.483477478398, 4626.190416677102, 4635.8377456789185, 4639.979204574988, 4654.650901182391, 4667.4632417272105, 4208.299398104926, 4348.152110338485, 4353.8027042563235, 4362.211493287737, 4388.912221755547, 4955.677880937727, 4975.925361119586, 4980.950190498121, 4990.625703072248, 5007.649464537484, 3728.813559322023, 3735.831887565066, 3745.6628903398823, 3746.1758413149064, 3768.660820526138, 5167.431582525956, 5184.481551844785, 5209.905919756818, 5212.765425555859, 5219.373675158968, 3714.622641509436, 3805.6955443564448, 3840.539135303781, 3842.481265029241, 3867.303885300673, 3894.162755007687, 3895.3962135868987, 3896.8636097736444, 3897.18159186574, 3902.8681682311812, 4322.891927701769, 4328.653707703376, 4338.566143385699, 4349.739015659071, 4350.3451307101095, 4686.718796872198, 4967.155583550805, 5021.322121513549, 5021.899562008739, 5028.220625803006, 1191.6901605582611, 1205.6021512900722, 1212.175720510044, 1212.472284921006, 1219.3659297165602, 784.372501998399, 824.3528829868525, 824.9175082491823, 834.2533432577809, 836.2306677856386, 4223.239816832999, 4257.1698518788535, 4307.203167414038, 4363.80362883671, 4445.955540444619, 5146.202338637517, 5149.639525233272, 5177.870553236199, 5187.403448603459, 5199.142141546602, 4462.085272519126, 4465.598096171295, 4470.687051906335, 4474.955250447454, 4480.910381792346, 2553.4893021395796, 2633.552296109666, 2649.0331029174213, 2657.162993656992, 2662.454196889633, 4627.722336659744, 4658.928446457266, 4666.719996800203, 4677.906441871143, 4678.461976872703, 5035.345405097387, 5064.797408103708, 5149.227615857576, 5163.3545806774155, 5187.740612969412, 4406.317020861771, 4677.859664210079, 4707.8587642370785, 4715.316279139574, 4723.748206725152, 4965.379327584052, 5026.899462010812, 5031.698098114125, 5041.798328066983, 5042.520960508746, 4913.410390753133, 4914.975425122851, 4919.3604831371695, 4928.968684259083, 4932.704037757746, 4436.667249956202, 4594.632429405707, 4647.930281045719, 4653.464851542116, 4685.742284174266, 3465.410880150431, 3476.443768996948, 3487.773294735834, 3507.351140038999, 3511.8595256189465, 4976.178930476431, 4984.551390374883, 4990.925136122888, 5004.724740139327, 5028.491452564289, 4696.835610753607, 4811.591014763644, 4841.540053694894, 4852.466228714909, 4907.68100073494, 4818.8795280118275, 4822.035592881368, 4832.468428472795, 4835.975820120877, 4837.637177211653, 4801.063792560417, 4802.903941921212, 4805.183118869871, 4808.543188397097, 4815.975920120377, 4673.228917228619, 4860.9548946976565, 4865.367502224688, 4898.387701537308, 4919.237518184687, 4707.540662394532, 4713.515242698539, 4721.268203428474, 4732.73969931657, 4780.569748722632], "value": 3759.8433602398122, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [668.665667166417, 695.8434352270739, 700.1982729774405, 743.4349894080498, 767.9270469305416, 12563.120581559295, 13861.722765544682, 13862.029657923937, 13873.445062197512, 13886.097403668755, 13455.192688438701, 13566.0, 13608.523701670443, 13635.590932272024, 13715.519956801514, 672.4049216443458, 675.1729131813514, 800.519688187089, 813.2071230550196, 815.2458975447717, 791.9722809701661, 806.6773290683715, 812.2243257688923, 812.305478815613, 823.7322870067252, 854.692310768123, 855.5465603230298, 855.6534603485599, 858.635080090962, 858.8325276571071, 701.8420855307565, 707.1125737199833, 849.7875531117221, 849.9362547808909, 850.9829803403928, 535.5180337696065, 538.6175815171233, 612.9938700612981, 614.2874265851591, 632.7690393006566, 13156.868431315661, 13194.53819116336, 13210.141340812912, 13210.273434961073, 13225.272610006446, 12862.614121576367, 12965.935170324183, 12996.41516131767, 13162.473501059947, 13251.403686834115, 10004.0, 10209.38743675382, 10326.535305911177, 11062.115030797517, 11189.104871610332, 4102.405151253081, 4138.275801734684, 4139.19285739281, 4178.6657067434535, 4225.302825033866, 61146.0, 62306.68846655783, 63061.36938630601, 63971.680141599456, 64862.838030387036, 10094.293399461967, 10137.69586912379, 10233.334833235816, 10327.431991240404, 10447.373157610416, 18212.0, 18224.908875455538, 18239.27042918295, 18242.270309187483, 18244.179011944496, 6232.719527621268, 6237.189165408558, 6403.615783053032, 6460.644664543402, 6485.156929599122, 3628.1111127773866, 3681.2637472505608, 3702.851885924533, 3729.6083911189476, 3743.082944678571, 2788.8884444622395, 2834.0930902111127, 2852.914412567626, 2911.78161637878, 2919.737223649882, 487.8658368948497, 498.2252597211299, 500.60202139299344, 506.65729878432023, 517.1312195511501, 426.75035104464234, 507.92381142828873, 521.903611236278, 552.3234038303046, 576.0437673462033, 7168.17565979919, 7381.907441776978, 7709.267619576131, 7800.843983120306, 7831.960840195762, 519.2626470412024, 527.4646234072419, 542.3112646938366, 542.3573065916901, 546.0907588864576, 3623.293457775761, 3760.9059773505332, 3796.8101594920145, 3844.6539811417106, 3964.900877478086, 51829.48170518322, 54324.45675543273, 54333.45666543363, 59687.0, 60431.395686043455, 42374.0, 45617.771911140895, 47162.76418617885, 53597.46402536003, 53902.73048634732, 49296.50703492919, 49507.53447258657, 50022.499775002514, 51158.23262650989, 51825.0, 8038.718644847402, 8494.447860889022, 8798.912010879809, 8911.821763564822, 8984.55077246122, 35352.0, 36011.0, 36105.638943610225, 36241.0, 37255.25489490249, 41406.79296603498, 45665.0, 46683.09827468067, 47129.528704713885, 49137.0, 11284.32294062359, 11334.60328888485, 11355.318680879174, 11547.653570392902, 12061.0, 11723.531058757895, 12387.504499819906, 12520.248785072925, 12547.686307842378, 12944.805827912778, 11747.823782643438, 12062.276263424223, 12087.818682719942, 12106.576269830513, 12123.696907577381, 12062.336571488653, 12126.696832579257, 12224.266544007389, 12380.0, 12835.294058826854, 11433.428328583705, 11562.94218528902, 12050.879491205327, 12085.637430877088, 12142.635720928387, 9556.283278753983, 9736.172425343924, 9891.406515609087, 10114.342567733074, 10213.642522511676, 9830.557624906753, 10210.642627508001, 10263.332883362558, 10278.0, 10316.690499285034], "value": 6226.26328396287, "unit": "score"}], "name": "dromaeo_css", "value": 6232.741043779583, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 114.0, 114.0, 114.0, 117.0, 114.0, 122.0, 115.0, 116.0, 116.0], "value": 114.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 129.0, 125.0, 157.0, 127.0, 127.0, 131.0, 125.0, 145.0, 124.0], "value": 134.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 161.0, 161.0, 163.0, 165.0, 163.0, 162.0, 163.0, 162.0, 160.0], "value": 162.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 77.0, 77.0, 105.0, 77.0, 79.0, 84.0, 76.0, 90.0, 76.0], "value": 81.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 110.0, 86.0, 84.0, 85.0, 99.0, 85.0, 86.0, 117.0], "value": 92.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 118.0, 117.0, 117.0, 118.0, 117.0], "value": 117.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 148.0, 148.0, 149.0, 149.0, 150.0, 149.0, 149.0, 149.0, 151.0], "value": 149.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 95.0, 95.0, 95.0, 95.0, 95.0, 96.0, 96.0, 96.0], "value": 95.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 59.0, 58.0, 58.0, 59.0, 72.0, 60.0, 59.0, 61.0, 59.0], "value": 60.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 66.0, 66.0, 67.0, 67.0, 67.0, 67.0, 68.0, 67.0], "value": 67.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 88.0, 85.0, 86.0, 87.0, 86.0, 94.0, 85.0, 85.0], "value": 86.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 143.0, 132.0, 141.0, 143.0, 134.0, 138.0, 131.0, 177.0, 164.0], "value": 145.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 173.0, 176.0, 179.0, 174.0, 175.0, 174.0, 176.0, 174.0, 175.0], "value": 175.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 66.0, 76.0, 67.0, 68.0, 68.0, 65.0, 70.0, 67.0, 66.0], "value": 68.1, "unit": "score"}], "name": "kraken", "value": 1549.7, "alertThreshold": 2.0}]} 23:20:41 INFO - Return code: 0 23:20:41 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 23:20:41 INFO - # TBPL SUCCESS # 23:20:41 INFO - Running post-action listener: _resource_record_post_action 23:20:41 INFO - [mozharness: 2016-12-27 07:20:41.107971Z] Finished run-tests step (success) 23:20:41 INFO - Running post-run listener: _resource_record_post_run 23:20:41 INFO - Total resource usage - Wall time: 1201s; CPU: 13.0%; Read bytes: 12288; Write bytes: 399020032; Read time: 28; Write time: 267752 23:20:41 INFO - TinderboxPrint: CPU usage
13.1% 23:20:41 INFO - TinderboxPrint: I/O read bytes / time
12,288 / 28 23:20:41 INFO - TinderboxPrint: I/O write bytes / time
399,020,032 / 267,752 23:20:41 INFO - TinderboxPrint: CPU idle
8,325.4 (86.8%) 23:20:41 INFO - TinderboxPrint: CPU user
1,215.9 (12.7%) 23:20:41 INFO - TinderboxPrint: Swap in / out
0 / 0 23:20:41 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 115675136; Read time: 0; Write time: 64464 23:20:41 INFO - run-tests - Wall time: 1185s; CPU: 13.0%; Read bytes: 12288; Write bytes: 282927104; Read time: 28; Write time: 203284 23:20:41 INFO - Running post-run listener: _upload_blobber_files 23:20:41 INFO - Blob upload gear active. 23:20:41 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 23:20:41 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 23:20:41 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', 'Graphics', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 23:20:41 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 Graphics -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 23:20:42 INFO - (blobuploader) - INFO - Open directory for files ... 23:20:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 23:20:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:20:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:20:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 23:20:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:20:42 INFO - (blobuploader) - INFO - Done attempting. 23:20:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 23:20:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:20:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:20:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 23:20:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:20:43 INFO - (blobuploader) - INFO - Done attempting. 23:20:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 23:20:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:20:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:20:44 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 23:20:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:20:44 INFO - (blobuploader) - INFO - Done attempting. 23:20:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 23:20:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:20:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:20:48 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 23:20:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:20:48 INFO - (blobuploader) - INFO - Done attempting. 23:20:48 INFO - (blobuploader) - INFO - Iteration through files over. 23:20:48 INFO - Return code: 0 23:20:48 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 23:20:48 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 23:20:48 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/db5772e1edca91f8ecb28bcda144ee62fa646b27a82bdf61fd8875b8e0762d08371ffe06e5d7567c519997fc4f2d61e09c55362b9a8244d9ab111b9f7bdece69", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/2f12d44353f911ff7d0cff06ceeedbaac5f8a0c21dc8fb804fb28f95a83c16368c5a3e84117c4cf7c0d9425d5e95a05e528ffecd7a669ec20a8aa1764231152e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/cb9747ab83c04a2ff19565ef991b1e84ff7846de3ac01b0d828bfffadbd8f81edf8f140319f2e707d75ac2cb4fab57ace29f1e242a9bda10c00e2b4a90d824ec", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/c554be5f7c0f27e8f67e20c8eaa643f9f6028c46dd0d7c442cce7d80e98e343d8a571a5d72c194bdd90a41fa59fb3f87abf78cd08baa9fee5b3784d6a2f1b201"} 23:20:48 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 23:20:48 INFO - Writing to file /builds/slave/test/properties/blobber_files 23:20:48 INFO - Contents: 23:20:48 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/db5772e1edca91f8ecb28bcda144ee62fa646b27a82bdf61fd8875b8e0762d08371ffe06e5d7567c519997fc4f2d61e09c55362b9a8244d9ab111b9f7bdece69", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/2f12d44353f911ff7d0cff06ceeedbaac5f8a0c21dc8fb804fb28f95a83c16368c5a3e84117c4cf7c0d9425d5e95a05e528ffecd7a669ec20a8aa1764231152e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/cb9747ab83c04a2ff19565ef991b1e84ff7846de3ac01b0d828bfffadbd8f81edf8f140319f2e707d75ac2cb4fab57ace29f1e242a9bda10c00e2b4a90d824ec", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/c554be5f7c0f27e8f67e20c8eaa643f9f6028c46dd0d7c442cce7d80e98e343d8a571a5d72c194bdd90a41fa59fb3f87abf78cd08baa9fee5b3784d6a2f1b201"} 23:20:48 INFO - Running post-run listener: copy_logs_to_upload_dir 23:20:48 INFO - Copying logs to upload dir... 23:20:48 INFO - mkdir: /builds/slave/test/build/upload/logs 23:20:48 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1245.766231 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 45 secs) (at 2016-12-26 23:20:48.242494) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-26 23:20:48.246528) ========= 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=0333a256a5e4f052382dd180000001b1-1482820167.797212-42797055 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/db5772e1edca91f8ecb28bcda144ee62fa646b27a82bdf61fd8875b8e0762d08371ffe06e5d7567c519997fc4f2d61e09c55362b9a8244d9ab111b9f7bdece69", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/2f12d44353f911ff7d0cff06ceeedbaac5f8a0c21dc8fb804fb28f95a83c16368c5a3e84117c4cf7c0d9425d5e95a05e528ffecd7a669ec20a8aa1764231152e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/cb9747ab83c04a2ff19565ef991b1e84ff7846de3ac01b0d828bfffadbd8f81edf8f140319f2e707d75ac2cb4fab57ace29f1e242a9bda10c00e2b4a90d824ec", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/c554be5f7c0f27e8f67e20c8eaa643f9f6028c46dd0d7c442cce7d80e98e343d8a571a5d72c194bdd90a41fa59fb3f87abf78cd08baa9fee5b3784d6a2f1b201"} build_url:https://queue.taskcluster.net/v1/task/bbC1Ymu0Sca5raJ3Fbmu7w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011378 build_url: 'https://queue.taskcluster.net/v1/task/bbC1Ymu0Sca5raJ3Fbmu7w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/db5772e1edca91f8ecb28bcda144ee62fa646b27a82bdf61fd8875b8e0762d08371ffe06e5d7567c519997fc4f2d61e09c55362b9a8244d9ab111b9f7bdece69", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/2f12d44353f911ff7d0cff06ceeedbaac5f8a0c21dc8fb804fb28f95a83c16368c5a3e84117c4cf7c0d9425d5e95a05e528ffecd7a669ec20a8aa1764231152e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/cb9747ab83c04a2ff19565ef991b1e84ff7846de3ac01b0d828bfffadbd8f81edf8f140319f2e707d75ac2cb4fab57ace29f1e242a9bda10c00e2b4a90d824ec", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/c554be5f7c0f27e8f67e20c8eaa643f9f6028c46dd0d7c442cce7d80e98e343d8a571a5d72c194bdd90a41fa59fb3f87abf78cd08baa9fee5b3784d6a2f1b201"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-26 23:20:48.279935) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-26 23:20:48.280715) ========= 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=0333a256a5e4f052382dd180000001b1-1482820167.797212-42797055 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004492 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-26 23:20:48.362354) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-26 23:20:48.363141) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-26 23:20:48.363858) ========= ========= Total master_lag: 0.20 =========