builder: mozilla-central_ubuntu64_hw_styloseq_test-dromaeojs-e10s slave: talos-linux64-ix-026 starttime: 1501811263.18 results: success (0) revision: 32083f24a1bb2c33050b4c972783f066432194eb ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-08-03 18:47:43.182664) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-08-03 18:47:43.183125) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-03 18:47:43.183395) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-03 18:47:43.199508) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-03 18:47:43.199900) ========= 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=41bf3e9aed84707956354da8000001c5-1501808513.100895-1942670171 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-08-03 18:47:43-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 109M=0s 2017-08-03 18:47:43 (109 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.146492 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-03 18:47:43.359599) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-03 18:47:43.362852) ========= 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=41bf3e9aed84707956354da8000001c5-1501808513.100895-1942670171 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016003 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-03 18:47:43.412610) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 mins, 36 secs) (at 2017-08-03 18:47:43.414960) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 32083f24a1bb2c33050b4c972783f066432194eb --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev 32083f24a1bb2c33050b4c972783f066432194eb --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=41bf3e9aed84707956354da8000001c5-1501808513.100895-1942670171 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-08-03 18:47:44,018 truncating revision to first 12 chars 2017-08-03 18:47:44,018 Setting DEBUG logging. 2017-08-03 18:47:44,018 attempt 1/10 2017-08-03 18:47:44,018 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/32083f24a1bb?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-08-03 18:47:44,404 attempt 1/10 2017-08-03 18:47:44,922 current task status: no status available at this point. state: PENDING 2017-08-03 18:47:44,922 sleeping for 11.00s (attempt 1/10) 2017-08-03 18:47:55,934 attempt 2/10 2017-08-03 18:47:56,446 current task status: no status available at this point. state: PENDING 2017-08-03 18:47:56,446 sleeping for 17.50s (attempt 2/10) 2017-08-03 18:48:13,964 attempt 3/10 2017-08-03 18:48:14,459 current task status: no status available at this point. state: PENDING 2017-08-03 18:48:14,459 sleeping for 25.25s (attempt 3/10) 2017-08-03 18:48:39,735 attempt 4/10 2017-08-03 18:48:40,239 current task status: no status available at this point. state: PENDING 2017-08-03 18:48:40,239 sleeping for 38.88s (attempt 4/10) 2017-08-03 18:49:19,153 attempt 5/10 2017-08-03 18:49:19,461 current task status: Task completed! Check 's3_urls' for upload locations. state: SUCCESS 2017-08-03 18:49:19,673 unpacking tar archive at: mozilla-central-32083f24a1bb/testing/mozharness/ program finished with exit code 0 elapsedTime=96.020677 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 mins, 36 secs) (at 2017-08-03 18:49:19.457509) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-03 18:49:19.460858) ========= script_repo_revision: 32083f24a1bb2c33050b4c972783f066432194eb ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-03 18:49:19.461239) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-03 18:49:19.461522) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-03 18:49:19.471930) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 57 secs) (at 2017-08-03 18:49:19.472237) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=41bf3e9aed84707956354da8000001c5-1501808513.100895-1942670171 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:49:20 INFO - MultiFileLogger online at 20170803 18:49:20 in /builds/slave/test 18:49:20 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 18:49:20 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:49:20 INFO - {'append_to_log': False, 18:49:20 INFO - 'base_work_dir': '/builds/slave/test', 18:49:20 INFO - 'blob_upload_branch': 'Firefox', 18:49:20 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:49:20 INFO - 'branch': 'Firefox', 18:49:20 INFO - 'buildbot_json_path': 'buildprops.json', 18:49:20 INFO - 'code_coverage': False, 18:49:20 INFO - 'config_files': ('talos/linux_config.py',), 18:49:20 INFO - 'default_actions': ('clobber', 18:49:20 INFO - 'read-buildbot-config', 18:49:20 INFO - 'download-and-extract', 18:49:20 INFO - 'populate-webroot', 18:49:20 INFO - 'create-virtualenv', 18:49:20 INFO - 'install', 18:49:20 INFO - 'setup-mitmproxy', 18:49:20 INFO - 'run-tests'), 18:49:20 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:49:20 INFO - 'disable_ccov_upload': False, 18:49:20 INFO - 'download_minidump_stackwalk': True, 18:49:20 INFO - 'download_symbols': 'ondemand', 18:49:20 INFO - 'enable_stylo': False, 18:49:20 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:49:20 INFO - 'tooltool.py': '/tools/tooltool.py', 18:49:20 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:49:20 INFO - '/tools/misc-python/virtualenv.py')}, 18:49:20 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:49:20 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:49:20 INFO - 'gecko_profile': False, 18:49:20 INFO - 'gecko_profile_interval': 0, 18:49:20 INFO - 'installer_path': 'installer.exe', 18:49:20 INFO - 'log_level': 'info', 18:49:20 INFO - 'log_name': 'talos', 18:49:20 INFO - 'log_to_console': True, 18:49:20 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:49:20 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:49:20 INFO - 'opt_config_files': (), 18:49:20 INFO - 'pip_index': False, 18:49:20 INFO - 'suite': 'dromaeojs-e10s', 18:49:20 INFO - 'system_bits': '32', 18:49:20 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:49:20 INFO - 'title': 'talos-linux64-ix-026', 18:49:20 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:49:20 INFO - 'use_talos_json': True, 18:49:20 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:49:20 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:49:20 INFO - 'work_dir': 'build'} 18:49:20 INFO - [mozharness: 2017-08-04 01:49:20.174902Z] Running clobber step. 18:49:20 INFO - Running pre-action listener: _resource_record_pre_action 18:49:20 INFO - Running main action method: clobber 18:49:20 INFO - rmtree: /builds/slave/test/build 18:49:20 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:49:21 INFO - Running post-action listener: _resource_record_post_action 18:49:21 INFO - [mozharness: 2017-08-04 01:49:21.714444Z] Finished clobber step (success) 18:49:21 INFO - [mozharness: 2017-08-04 01:49:21.714546Z] Running read-buildbot-config step. 18:49:21 INFO - Running pre-action listener: _resource_record_pre_action 18:49:21 INFO - Running main action method: read_buildbot_config 18:49:21 INFO - Using buildbot properties: 18:49:21 INFO - { 18:49:21 INFO - "project": "", 18:49:21 INFO - "product": "firefox", 18:49:21 INFO - "who": "kwierso@gmail.com", 18:49:21 INFO - "installer_path": "public/build/target.tar.bz2", 18:49:21 INFO - "builddir": "mozilla-central_ubuntu64_hw_styloseq_test-dromaeojs-e10s", 18:49:21 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 18:49:21 INFO - "buildername": "Ubuntu HW 12.04 x64 stylo-sequential mozilla-central talos dromaeojs-e10s", 18:49:21 INFO - "stage_platform": "linux64-stylosequential", 18:49:21 INFO - "basedir": "/builds/slave/test", 18:49:21 INFO - "buildnumber": 17, 18:49:21 INFO - "platform": "ubuntu64_hw_styloseq", 18:49:21 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 18:49:21 INFO - "slavebuilddir": "test", 18:49:21 INFO - "taskId": "RHA3NIC-R6ORlB4KtmT7nw", 18:49:21 INFO - "branch": "mozilla-central", 18:49:21 INFO - "script_repo_revision": "production", 18:49:21 INFO - "revision": "32083f24a1bb2c33050b4c972783f066432194eb", 18:49:21 INFO - "slavename": "talos-linux64-ix-026", 18:49:21 INFO - "repo_path": "mozilla-central" 18:49:21 INFO - } 18:49:21 INFO - Finding installer, test and symbols from parent task. 18:49:21 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RHA3NIC-R6ORlB4KtmT7nw'}, attempt #1 18:49:22 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RHA3NIC-R6ORlB4KtmT7nw'}, attempt #1 18:49:22 INFO - Task dependencies: H5B5IEnwSEm76TFIhWTsdQ 18:49:22 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/H5B5IEnwSEm76TFIhWTsdQ'}, attempt #1 18:49:23 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/H5B5IEnwSEm76TFIhWTsdQ/artifacts/public/build/target.tar.bz2 18:49:23 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/H5B5IEnwSEm76TFIhWTsdQ/artifacts/public/build/target.test_packages.json 18:49:23 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/H5B5IEnwSEm76TFIhWTsdQ/artifacts/public/build/target.crashreporter-symbols.zip 18:49:23 INFO - Running post-action listener: _resource_record_post_action 18:49:23 INFO - [mozharness: 2017-08-04 01:49:23.058605Z] Finished read-buildbot-config step (success) 18:49:23 INFO - [mozharness: 2017-08-04 01:49:23.058827Z] Running download-and-extract step. 18:49:23 INFO - Running pre-action listener: _resource_record_pre_action 18:49:23 INFO - Running main action method: download_and_extract 18:49:23 INFO - mkdir: /builds/slave/test/build/tests 18:49:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 18:49:23 INFO - trying https://queue.taskcluster.net/v1/task/H5B5IEnwSEm76TFIhWTsdQ/artifacts/public/build/target.test_packages.json 18:49:23 INFO - Downloading https://queue.taskcluster.net/v1/task/H5B5IEnwSEm76TFIhWTsdQ/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 18:49:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/H5B5IEnwSEm76TFIhWTsdQ/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 18:49:23 INFO - Downloaded 989 bytes. 18:49:23 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 18:49:23 INFO - Using the following test package requirements: 18:49:23 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 18:49:23 INFO - u'common': [u'target.common.tests.zip'], 18:49:23 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 18:49:23 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 18:49:23 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 18:49:23 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 18:49:23 INFO - u'mozbase': [u'target.common.tests.zip'], 18:49:23 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 18:49:23 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 18:49:23 INFO - u'web-platform': [u'target.common.tests.zip', 18:49:23 INFO - u'target.web-platform.tests.tar.gz'], 18:49:23 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 18:49:23 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 18:49:23 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/H5B5IEnwSEm76TFIhWTsdQ/artifacts/public/build/target.common.tests.zip 18:49:23 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/H5B5IEnwSEm76TFIhWTsdQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 18:49:23 INFO - Fetch https://queue.taskcluster.net/v1/task/H5B5IEnwSEm76TFIhWTsdQ/artifacts/public/build/target.common.tests.zip into memory 18:49:24 INFO - Content-Length response header: 54278817 18:49:24 INFO - Bytes received: 54278817 18:49:35 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 18:49:35 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/H5B5IEnwSEm76TFIhWTsdQ/artifacts/public/build/target.common.tests.zip 18:49:35 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/H5B5IEnwSEm76TFIhWTsdQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 18:49:35 INFO - Fetch https://queue.taskcluster.net/v1/task/H5B5IEnwSEm76TFIhWTsdQ/artifacts/public/build/target.common.tests.zip into memory 18:49:36 INFO - Content-Length response header: 54278817 18:49:36 INFO - Bytes received: 54278817 18:49:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/H5B5IEnwSEm76TFIhWTsdQ/artifacts/public/build/target.talos.tests.zip 18:49:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/H5B5IEnwSEm76TFIhWTsdQ/artifacts/public/build/target.talos.tests.zip'}, attempt #1 18:49:48 INFO - Fetch https://queue.taskcluster.net/v1/task/H5B5IEnwSEm76TFIhWTsdQ/artifacts/public/build/target.talos.tests.zip into memory 18:49:49 INFO - Content-Length response header: 14496012 18:49:49 INFO - Bytes received: 14496012 18:49:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 18:49:49 INFO - trying https://queue.taskcluster.net/v1/task/H5B5IEnwSEm76TFIhWTsdQ/artifacts/public/build/target.tar.bz2 18:49:49 INFO - Downloading https://queue.taskcluster.net/v1/task/H5B5IEnwSEm76TFIhWTsdQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 18:49:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/H5B5IEnwSEm76TFIhWTsdQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 18:49:50 INFO - Downloaded 62304218 bytes. 18:49:50 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/H5B5IEnwSEm76TFIhWTsdQ/artifacts/public/build/target.tar.bz2 18:49:50 INFO - mkdir: /builds/slave/test/properties 18:49:50 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:49:50 INFO - Writing to file /builds/slave/test/properties/build_url 18:49:50 INFO - Contents: 18:49:50 INFO - build_url:https://queue.taskcluster.net/v1/task/H5B5IEnwSEm76TFIhWTsdQ/artifacts/public/build/target.tar.bz2 18:49:50 INFO - Running post-action listener: _resource_record_post_action 18:49:50 INFO - Running post-action listener: set_extra_try_arguments 18:49:50 INFO - [mozharness: 2017-08-04 01:49:50.552859Z] Finished download-and-extract step (success) 18:49:50 INFO - [mozharness: 2017-08-04 01:49:50.552957Z] Running populate-webroot step. 18:49:50 INFO - Running pre-action listener: _resource_record_pre_action 18:49:50 INFO - Running main action method: populate_webroot 18:49:50 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:49:50 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:49:50 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 18:49:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:49:50 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 18:49:50 INFO - 'tests': ['damp', 'tps']}, 18:49:50 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:49:50 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 18:49:50 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 18:49:50 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 18:49:50 INFO - 'other-e10s': {'tests': ['a11yr', 18:49:50 INFO - 'ts_paint', 18:49:50 INFO - 'tpaint', 18:49:50 INFO - 'sessionrestore', 18:49:50 INFO - 'sessionrestore_many_windows', 18:49:50 INFO - 'sessionrestore_no_auto_restore', 18:49:50 INFO - 'tabpaint']}, 18:49:50 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 18:49:50 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 18:49:50 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:49:50 INFO - 'tsvgr_opacity', 18:49:50 INFO - 'tart', 18:49:50 INFO - 'tscrollx', 18:49:50 INFO - 'cart', 18:49:50 INFO - 'tsvg_static']}, 18:49:50 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 18:49:50 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:49:50 INFO - 'talos_options': ['--mitmproxy', 18:49:50 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:49:50 INFO - '--firstNonBlankPaint'], 18:49:50 INFO - 'tests': ['tp6_google', 18:49:50 INFO - 'tp6_youtube', 18:49:50 INFO - 'tp6_amazon', 18:49:50 INFO - 'tp6_facebook']}, 18:49:50 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:49:50 INFO - 'talos_options': ['--stylo', 18:49:50 INFO - '--mitmproxy', 18:49:50 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:49:50 INFO - '--firstNonBlankPaint'], 18:49:50 INFO - 'tests': ['tp6_google', 18:49:50 INFO - 'tp6_youtube', 18:49:50 INFO - 'tp6_amazon', 18:49:50 INFO - 'tp6_facebook']}, 18:49:50 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:49:50 INFO - 'talos_options': ['--stylo', 18:49:50 INFO - '--stylo-threads=1', 18:49:50 INFO - '--mitmproxy', 18:49:50 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:49:50 INFO - '--firstNonBlankPaint'], 18:49:50 INFO - 'tests': ['tp6_google', 18:49:50 INFO - 'tp6_youtube', 18:49:50 INFO - 'tp6_amazon', 18:49:50 INFO - 'tp6_facebook']}, 18:49:50 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 18:49:50 INFO - 'talos_options': ['--xperf_path', 18:49:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:49:50 INFO - 'tests': ['tp5n']}}} 18:49:50 INFO - Running post-action listener: _resource_record_post_action 18:49:50 INFO - [mozharness: 2017-08-04 01:49:50.561500Z] Finished populate-webroot step (success) 18:49:50 INFO - [mozharness: 2017-08-04 01:49:50.561590Z] Running create-virtualenv step. 18:49:50 INFO - Running pre-action listener: _resource_record_pre_action 18:49:50 INFO - Running main action method: create_virtualenv 18:49:50 INFO - Creating virtualenv /builds/slave/test/build/venv 18:49:50 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 18:49:50 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:49:50 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 18:49:50 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:49:50 INFO - Using real prefix '/usr' 18:49:50 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:49:51 INFO - Installing distribute.............................................................................................................................................................................................done. 18:49:53 INFO - Installing pip.................done. 18:49:53 INFO - Return code: 0 18:49:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:49:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:49:53 INFO - Reading from file tmpfile_stdout 18:49:53 INFO - Output received: 18:49:53 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) 18:49:53 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:49:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 18:49:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:49:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 18:49:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:49:53 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/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 0x19d81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ba4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d47d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d44eb0>, '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 0x1d45db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d46240>, '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': '41bf3e9aed84707956354da8000001c5-1501808513.100895-1942670171', '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 18:49:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 18:49:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 18:49:53 INFO - Using env: {'DISPLAY': ':0', 18:49:53 INFO - 'HOME': '/home/cltbld', 18:49:53 INFO - 'LANG': 'en_US.UTF-8', 18:49:53 INFO - 'LANGUAGE': 'en_US:en', 18:49:53 INFO - 'LOGNAME': 'cltbld', 18:49:53 INFO - 'MAIL': '/var/mail/cltbld', 18:49:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:49:53 INFO - 'MOZ_NO_REMOTE': '1', 18:49:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:49:53 INFO - 'NO_EM_RESTART': '1', 18:49:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:49:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:49:53 INFO - 'PWD': '/builds/slave/test', 18:49:53 INFO - 'SHELL': '/bin/bash', 18:49:53 INFO - 'SHLVL': '1', 18:49:53 INFO - 'TERM': 'linux', 18:49:53 INFO - 'TMOUT': '86400', 18:49:53 INFO - 'USER': 'cltbld', 18:49:53 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1501808513.100895-1942670171', 18:49:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:49:53 INFO - '_': '/tools/buildbot/bin/python'} 18:49:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:49:53 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 18:49:53 INFO - Cleaning up... 18:49:53 INFO - Return code: 0 18:49:53 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:49:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 18:49:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:49:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 18:49:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:49:53 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/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 0x19d81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ba4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d47d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d44eb0>, '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 0x1d45db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d46240>, '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': '41bf3e9aed84707956354da8000001c5-1501808513.100895-1942670171', '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 18:49:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 18:49:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 18:49:53 INFO - Using env: {'DISPLAY': ':0', 18:49:53 INFO - 'HOME': '/home/cltbld', 18:49:53 INFO - 'LANG': 'en_US.UTF-8', 18:49:53 INFO - 'LANGUAGE': 'en_US:en', 18:49:53 INFO - 'LOGNAME': 'cltbld', 18:49:53 INFO - 'MAIL': '/var/mail/cltbld', 18:49:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:49:53 INFO - 'MOZ_NO_REMOTE': '1', 18:49:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:49:53 INFO - 'NO_EM_RESTART': '1', 18:49:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:49:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:49:53 INFO - 'PWD': '/builds/slave/test', 18:49:53 INFO - 'SHELL': '/bin/bash', 18:49:53 INFO - 'SHLVL': '1', 18:49:53 INFO - 'TERM': 'linux', 18:49:53 INFO - 'TMOUT': '86400', 18:49:53 INFO - 'USER': 'cltbld', 18:49:53 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1501808513.100895-1942670171', 18:49:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:49:53 INFO - '_': '/tools/buildbot/bin/python'} 18:49:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:49:53 INFO - Downloading/unpacking psutil>=3.1.1 18:49:53 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 18:49:53 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 18:49:53 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 18:49:53 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 18:49:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:49:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:49:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:49:54 INFO - Installing collected packages: psutil 18:49:54 INFO - Running setup.py install for psutil 18:49:55 INFO - building 'psutil._psutil_linux' extension 18:49:55 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 18:49:55 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 18:49:55 INFO - building 'psutil._psutil_posix' extension 18:49:55 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 18:49:55 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 18:49:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:49:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:49:55 INFO - Successfully installed psutil 18:49:55 INFO - Cleaning up... 18:49:55 INFO - Return code: 0 18:49:55 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:49:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 18:49:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:49:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 18:49:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:49:55 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/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 0x19d81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ba4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d47d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d44eb0>, '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 0x1d45db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d46240>, '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': '41bf3e9aed84707956354da8000001c5-1501808513.100895-1942670171', '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 18:49:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 18:49:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 18:49:55 INFO - Using env: {'DISPLAY': ':0', 18:49:55 INFO - 'HOME': '/home/cltbld', 18:49:55 INFO - 'LANG': 'en_US.UTF-8', 18:49:55 INFO - 'LANGUAGE': 'en_US:en', 18:49:55 INFO - 'LOGNAME': 'cltbld', 18:49:55 INFO - 'MAIL': '/var/mail/cltbld', 18:49:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:49:55 INFO - 'MOZ_NO_REMOTE': '1', 18:49:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:49:55 INFO - 'NO_EM_RESTART': '1', 18:49:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:49:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:49:55 INFO - 'PWD': '/builds/slave/test', 18:49:55 INFO - 'SHELL': '/bin/bash', 18:49:55 INFO - 'SHLVL': '1', 18:49:55 INFO - 'TERM': 'linux', 18:49:55 INFO - 'TMOUT': '86400', 18:49:55 INFO - 'USER': 'cltbld', 18:49:55 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1501808513.100895-1942670171', 18:49:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:49:55 INFO - '_': '/tools/buildbot/bin/python'} 18:49:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:49:55 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:49:55 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 18:49:55 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 18:49:55 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 18:49:55 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 18:49:56 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:49:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:49:56 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:49:56 INFO - Installing collected packages: mozsystemmonitor 18:49:56 INFO - Running setup.py install for mozsystemmonitor 18:49:56 INFO - Successfully installed mozsystemmonitor 18:49:56 INFO - Cleaning up... 18:49:57 INFO - Return code: 0 18:49:57 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:49:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 18:49:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:49:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 18:49:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:49:57 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/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 0x19d81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ba4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d47d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d44eb0>, '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 0x1d45db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d46240>, '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': '41bf3e9aed84707956354da8000001c5-1501808513.100895-1942670171', '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 18:49:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 18:49:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 18:49:57 INFO - Using env: {'DISPLAY': ':0', 18:49:57 INFO - 'HOME': '/home/cltbld', 18:49:57 INFO - 'LANG': 'en_US.UTF-8', 18:49:57 INFO - 'LANGUAGE': 'en_US:en', 18:49:57 INFO - 'LOGNAME': 'cltbld', 18:49:57 INFO - 'MAIL': '/var/mail/cltbld', 18:49:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:49:57 INFO - 'MOZ_NO_REMOTE': '1', 18:49:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:49:57 INFO - 'NO_EM_RESTART': '1', 18:49:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:49:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:49:57 INFO - 'PWD': '/builds/slave/test', 18:49:57 INFO - 'SHELL': '/bin/bash', 18:49:57 INFO - 'SHLVL': '1', 18:49:57 INFO - 'TERM': 'linux', 18:49:57 INFO - 'TMOUT': '86400', 18:49:57 INFO - 'USER': 'cltbld', 18:49:57 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1501808513.100895-1942670171', 18:49:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:49:57 INFO - '_': '/tools/buildbot/bin/python'} 18:49:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:49:57 INFO - Downloading/unpacking jsonschema==2.5.1 18:49:57 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 18:49:57 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 18:49:57 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 18:49:57 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 18:49:58 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:49:58 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:49:58 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 18:49:58 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 18:49:58 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 18:49:58 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 18:49:58 INFO - Downloading functools32-3.2.3-2.tar.gz 18:49:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:49:58 INFO - warning: no files found matching '*.txt' 18:49:58 INFO - no previously-included directories found matching 'build' 18:49:58 INFO - no previously-included directories found matching 'dist' 18:49:58 INFO - no previously-included directories found matching '.git*' 18:49:58 INFO - Installing collected packages: jsonschema, functools32 18:49:58 INFO - Running setup.py install for functools32 18:49:58 INFO - warning: no files found matching '*.txt' 18:49:58 INFO - no previously-included directories found matching 'build' 18:49:58 INFO - no previously-included directories found matching 'dist' 18:49:58 INFO - no previously-included directories found matching '.git*' 18:49:58 INFO - Successfully installed jsonschema functools32 18:49:58 INFO - Cleaning up... 18:49:58 INFO - Return code: 0 18:49:58 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:49:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 18:49:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:49:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 18:49:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:49:58 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/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 0x19d81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ba4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d47d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d44eb0>, '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 0x1d45db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d46240>, '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': '41bf3e9aed84707956354da8000001c5-1501808513.100895-1942670171', '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 18:49:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 18:49:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 18:49:58 INFO - Using env: {'DISPLAY': ':0', 18:49:58 INFO - 'HOME': '/home/cltbld', 18:49:58 INFO - 'LANG': 'en_US.UTF-8', 18:49:58 INFO - 'LANGUAGE': 'en_US:en', 18:49:58 INFO - 'LOGNAME': 'cltbld', 18:49:58 INFO - 'MAIL': '/var/mail/cltbld', 18:49:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:49:58 INFO - 'MOZ_NO_REMOTE': '1', 18:49:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:49:58 INFO - 'NO_EM_RESTART': '1', 18:49:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:49:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:49:58 INFO - 'PWD': '/builds/slave/test', 18:49:58 INFO - 'SHELL': '/bin/bash', 18:49:58 INFO - 'SHLVL': '1', 18:49:58 INFO - 'TERM': 'linux', 18:49:58 INFO - 'TMOUT': '86400', 18:49:58 INFO - 'USER': 'cltbld', 18:49:58 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1501808513.100895-1942670171', 18:49:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:49:58 INFO - '_': '/tools/buildbot/bin/python'} 18:49:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:49:58 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:49:58 INFO - Cleaning up... 18:49:58 INFO - Return code: 0 18:49:58 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:49:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 18:49:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:49:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 18:49:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:49:58 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/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 0x19d81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ba4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d47d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d44eb0>, '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 0x1d45db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d46240>, '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': '41bf3e9aed84707956354da8000001c5-1501808513.100895-1942670171', '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 18:49:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 18:49:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 18:49:58 INFO - Using env: {'DISPLAY': ':0', 18:49:58 INFO - 'HOME': '/home/cltbld', 18:49:58 INFO - 'LANG': 'en_US.UTF-8', 18:49:58 INFO - 'LANGUAGE': 'en_US:en', 18:49:58 INFO - 'LOGNAME': 'cltbld', 18:49:58 INFO - 'MAIL': '/var/mail/cltbld', 18:49:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:49:58 INFO - 'MOZ_NO_REMOTE': '1', 18:49:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:49:58 INFO - 'NO_EM_RESTART': '1', 18:49:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:49:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:49:58 INFO - 'PWD': '/builds/slave/test', 18:49:58 INFO - 'SHELL': '/bin/bash', 18:49:58 INFO - 'SHLVL': '1', 18:49:58 INFO - 'TERM': 'linux', 18:49:58 INFO - 'TMOUT': '86400', 18:49:58 INFO - 'USER': 'cltbld', 18:49:58 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1501808513.100895-1942670171', 18:49:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:49:58 INFO - '_': '/tools/buildbot/bin/python'} 18:49:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:49:59 INFO - Downloading/unpacking blobuploader==1.2.4 18:49:59 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 18:49:59 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 18:49:59 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 18:49:59 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 18:50:00 INFO - Downloading blobuploader-1.2.4.tar.gz 18:50:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:50:00 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:50:00 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 18:50:00 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 18:50:00 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 18:50:00 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 18:50:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:50:00 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:50:00 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 18:50:00 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 18:50:00 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 18:50:00 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 18:50:00 INFO - Downloading docopt-0.6.1.tar.gz 18:50:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:50:00 INFO - Installing collected packages: blobuploader, requests, docopt 18:50:00 INFO - Running setup.py install for blobuploader 18:50:00 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:50:00 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:50:01 INFO - Running setup.py install for requests 18:50:01 INFO - Running setup.py install for docopt 18:50:01 INFO - Successfully installed blobuploader requests docopt 18:50:01 INFO - Cleaning up... 18:50:01 INFO - Return code: 0 18:50:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:50:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 18:50:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:50:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 18:50:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:50:01 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/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 0x19d81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ba4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d47d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d44eb0>, '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 0x1d45db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d46240>, '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': '41bf3e9aed84707956354da8000001c5-1501808513.100895-1942670171', '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 18:50:01 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/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 18:50:01 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/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:50:01 INFO - Using env: {'DISPLAY': ':0', 18:50:01 INFO - 'HOME': '/home/cltbld', 18:50:01 INFO - 'LANG': 'en_US.UTF-8', 18:50:01 INFO - 'LANGUAGE': 'en_US:en', 18:50:01 INFO - 'LOGNAME': 'cltbld', 18:50:01 INFO - 'MAIL': '/var/mail/cltbld', 18:50:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:50:01 INFO - 'MOZ_NO_REMOTE': '1', 18:50:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:50:01 INFO - 'NO_EM_RESTART': '1', 18:50:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:50:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:50:01 INFO - 'PWD': '/builds/slave/test', 18:50:01 INFO - 'SHELL': '/bin/bash', 18:50:01 INFO - 'SHLVL': '1', 18:50:01 INFO - 'TERM': 'linux', 18:50:01 INFO - 'TMOUT': '86400', 18:50:01 INFO - 'USER': 'cltbld', 18:50:01 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1501808513.100895-1942670171', 18:50:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:50:01 INFO - '_': '/tools/buildbot/bin/python'} 18:50:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:50:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:50:01 INFO - Running setup.py (path:/tmp/pip-EiuHs8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:50:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:50:01 INFO - Running setup.py (path:/tmp/pip-bpWQT9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:50:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:50:01 INFO - Running setup.py (path:/tmp/pip-2XqFqO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:50:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:50:01 INFO - Running setup.py (path:/tmp/pip-jzRRmp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:50:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:50:02 INFO - Running setup.py (path:/tmp/pip-YGNi1G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:50:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:50:02 INFO - Running setup.py (path:/tmp/pip-VPf4SB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:50:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:50:02 INFO - Running setup.py (path:/tmp/pip-vQeJEm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:50:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:50:02 INFO - Running setup.py (path:/tmp/pip-ylBdKx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:50:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:50:02 INFO - Running setup.py (path:/tmp/pip-S9L2Ox-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:50:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:50:02 INFO - Running setup.py (path:/tmp/pip-pkuUQi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:50:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:50:02 INFO - Running setup.py (path:/tmp/pip-u0T74t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:50:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:50:02 INFO - Running setup.py (path:/tmp/pip-EheUDY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:50:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:50:02 INFO - Running setup.py (path:/tmp/pip-ne030L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:50:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:50:02 INFO - Running setup.py (path:/tmp/pip-OaE1dN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:50:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:50:02 INFO - Running setup.py (path:/tmp/pip-jZNLmf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:50:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:50:02 INFO - Running setup.py (path:/tmp/pip-VyLWaE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:50:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:50:02 INFO - Running setup.py (path:/tmp/pip-kLtU8x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:50:02 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:50:02 INFO - Running setup.py install for manifestparser 18:50:03 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:50:03 INFO - Running setup.py install for mozcrash 18:50:03 INFO - Running setup.py install for mozdebug 18:50:03 INFO - Running setup.py install for mozdevice 18:50:03 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:50:03 INFO - Running setup.py install for mozfile 18:50:03 INFO - Running setup.py install for mozhttpd 18:50:03 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:50:03 INFO - Running setup.py install for mozinfo 18:50:03 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:50:03 INFO - Running setup.py install for mozInstall 18:50:03 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:50:03 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:50:03 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:50:03 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:50:03 INFO - Running setup.py install for mozleak 18:50:03 INFO - Running setup.py install for mozlog 18:50:03 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:50:03 INFO - Running setup.py install for moznetwork 18:50:04 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:50:04 INFO - Running setup.py install for mozprocess 18:50:04 INFO - Running setup.py install for mozprofile 18:50:04 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:50:04 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:50:04 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:50:04 INFO - Running setup.py install for mozrunner 18:50:04 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:50:04 INFO - Running setup.py install for mozscreenshot 18:50:04 INFO - Running setup.py install for moztest 18:50:04 INFO - Running setup.py install for mozversion 18:50:04 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:50:04 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:50:04 INFO - Cleaning up... 18:50:04 INFO - Return code: 0 18:50:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:50:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 18:50:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:50:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 18:50:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:50:04 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/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 0x19d81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ba4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d47d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d44eb0>, '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 0x1d45db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d46240>, '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': '41bf3e9aed84707956354da8000001c5-1501808513.100895-1942670171', '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 18:50:04 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/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 18:50:04 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/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:50:04 INFO - Using env: {'DISPLAY': ':0', 18:50:04 INFO - 'HOME': '/home/cltbld', 18:50:04 INFO - 'LANG': 'en_US.UTF-8', 18:50:04 INFO - 'LANGUAGE': 'en_US:en', 18:50:04 INFO - 'LOGNAME': 'cltbld', 18:50:04 INFO - 'MAIL': '/var/mail/cltbld', 18:50:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:50:04 INFO - 'MOZ_NO_REMOTE': '1', 18:50:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:50:04 INFO - 'NO_EM_RESTART': '1', 18:50:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:50:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:50:04 INFO - 'PWD': '/builds/slave/test', 18:50:04 INFO - 'SHELL': '/bin/bash', 18:50:04 INFO - 'SHLVL': '1', 18:50:04 INFO - 'TERM': 'linux', 18:50:04 INFO - 'TMOUT': '86400', 18:50:04 INFO - 'USER': 'cltbld', 18:50:04 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1501808513.100895-1942670171', 18:50:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:50:04 INFO - '_': '/tools/buildbot/bin/python'} 18:50:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:50:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:50:04 INFO - Running setup.py (path:/tmp/pip-6OZs6Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:50:05 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)) 18:50:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:50:05 INFO - Running setup.py (path:/tmp/pip-zoqolt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:50:05 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)) 18:50:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:50:05 INFO - Running setup.py (path:/tmp/pip-fM4YpX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:50:05 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)) 18:50:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:50:05 INFO - Running setup.py (path:/tmp/pip-d9Y8Q7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:50:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 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)) 18:50:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:50:05 INFO - Running setup.py (path:/tmp/pip-wtDBXw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:50:05 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)) 18:50:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:50:05 INFO - Running setup.py (path:/tmp/pip-ZRI1VU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:50:05 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)) 18:50:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:50:05 INFO - Running setup.py (path:/tmp/pip-i0_0Z6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:50:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 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)) 18:50:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:50:05 INFO - Running setup.py (path:/tmp/pip-OqfRYG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:50:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 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)) 18:50:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:50:05 INFO - Running setup.py (path:/tmp/pip-6Spu3S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:50:05 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)) 18:50:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:50:05 INFO - Running setup.py (path:/tmp/pip-MPyGPm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:50:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 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)) 18:50:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:50:05 INFO - Running setup.py (path:/tmp/pip-sjWJvC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:50:05 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)) 18:50:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:50:05 INFO - Running setup.py (path:/tmp/pip-jg0qzj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:50:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 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)) 18:50:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:50:05 INFO - Running setup.py (path:/tmp/pip-b41hH3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:50:05 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)) 18:50:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:50:05 INFO - Running setup.py (path:/tmp/pip-PYNrmz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:50:05 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)) 18:50:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:50:05 INFO - Running setup.py (path:/tmp/pip-tmDAZT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:50:06 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)) 18:50:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:50:06 INFO - Running setup.py (path:/tmp/pip-FmTX_z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:50:06 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 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)) 18:50:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:50:06 INFO - Running setup.py (path:/tmp/pip-v7ODJp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:50:06 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)) 18:50:06 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)) 18:50:06 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)) 18:50:06 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)) 18:50:06 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.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:50:06 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.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:50:06 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 18:50:06 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:50:06 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 18:50:06 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 18:50:06 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 18:50:06 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 18:50:07 INFO - Downloading blessings-1.6.tar.gz 18:50:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:50:07 INFO - Installing collected packages: blessings 18:50:07 INFO - Running setup.py install for blessings 18:50:07 INFO - Successfully installed blessings 18:50:07 INFO - Cleaning up... 18:50:07 INFO - Return code: 0 18:50:07 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:50:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:50:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:50:07 INFO - Reading from file tmpfile_stdout 18:50:07 INFO - Current package versions: 18:50:07 INFO - argparse == 1.2.1 18:50:07 INFO - blessings == 1.6 18:50:07 INFO - blobuploader == 1.2.4 18:50:07 INFO - docopt == 0.6.1 18:50:07 INFO - functools32 == 3.2.3-2 18:50:07 INFO - jsonschema == 2.5.1 18:50:07 INFO - manifestparser == 1.1 18:50:07 INFO - mozInstall == 1.13 18:50:07 INFO - mozcrash == 1.0 18:50:07 INFO - mozdebug == 0.1 18:50:07 INFO - mozdevice == 0.51 18:50:07 INFO - mozfile == 1.2 18:50:07 INFO - mozhttpd == 0.7 18:50:07 INFO - mozinfo == 0.10 18:50:07 INFO - mozleak == 0.1 18:50:07 INFO - mozlog == 3.5 18:50:07 INFO - moznetwork == 0.27 18:50:07 INFO - mozprocess == 0.25 18:50:07 INFO - mozprofile == 0.28 18:50:07 INFO - mozrunner == 6.13 18:50:07 INFO - mozscreenshot == 0.1 18:50:07 INFO - mozsystemmonitor == 0.3 18:50:07 INFO - moztest == 0.8 18:50:07 INFO - mozversion == 1.4 18:50:07 INFO - psutil == 3.1.1 18:50:07 INFO - requests == 1.2.3 18:50:07 INFO - wsgiref == 0.1.2 18:50:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:50:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 18:50:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:50:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 18:50:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:50:07 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/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 0x19d81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ba4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d47d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d44eb0>, '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 0x1d45db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d46240>, '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': '41bf3e9aed84707956354da8000001c5-1501808513.100895-1942670171', '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 18:50:07 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/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 18:50:07 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/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:50:07 INFO - Using env: {'DISPLAY': ':0', 18:50:07 INFO - 'HOME': '/home/cltbld', 18:50:07 INFO - 'LANG': 'en_US.UTF-8', 18:50:07 INFO - 'LANGUAGE': 'en_US:en', 18:50:07 INFO - 'LOGNAME': 'cltbld', 18:50:07 INFO - 'MAIL': '/var/mail/cltbld', 18:50:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:50:07 INFO - 'MOZ_NO_REMOTE': '1', 18:50:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:50:07 INFO - 'NO_EM_RESTART': '1', 18:50:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:50:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:50:07 INFO - 'PWD': '/builds/slave/test', 18:50:07 INFO - 'SHELL': '/bin/bash', 18:50:07 INFO - 'SHLVL': '1', 18:50:07 INFO - 'TERM': 'linux', 18:50:07 INFO - 'TMOUT': '86400', 18:50:07 INFO - 'USER': 'cltbld', 18:50:07 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1501808513.100895-1942670171', 18:50:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:50:07 INFO - '_': '/tools/buildbot/bin/python'} 18:50:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:50:07 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)) 18:50:07 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)) 18:50:07 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)) 18:50:07 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)) 18:50:07 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)) 18:50:07 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)) 18:50:07 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)) 18:50:07 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)) 18:50:07 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)) 18:50:07 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 18:50:07 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 18:50:07 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 18:50:07 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 18:50:07 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 18:50:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 18:50:09 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)) 18:50:09 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)) 18:50:09 INFO - Installing collected packages: simplejson 18:50:09 INFO - Running setup.py install for simplejson 18:50:09 INFO - building 'simplejson._speedups' extension 18:50:09 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c simplejson/_speedups.c -o build/temp.linux-x86_64-2.7/simplejson/_speedups.o 18:50:10 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/simplejson/_speedups.o -o build/lib.linux-x86_64-2.7/simplejson/_speedups.so 18:50:10 INFO - Successfully installed simplejson 18:50:10 INFO - Cleaning up... 18:50:10 INFO - Return code: 0 18:50:10 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:50:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 18:50:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:50:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 18:50:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:50:10 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/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 0x19d81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ba4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d47d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d44eb0>, '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 0x1d45db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d46240>, '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': '41bf3e9aed84707956354da8000001c5-1501808513.100895-1942670171', '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 18:50:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 18:50:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 18:50:10 INFO - Using env: {'DISPLAY': ':0', 18:50:10 INFO - 'HOME': '/home/cltbld', 18:50:10 INFO - 'LANG': 'en_US.UTF-8', 18:50:10 INFO - 'LANGUAGE': 'en_US:en', 18:50:10 INFO - 'LOGNAME': 'cltbld', 18:50:10 INFO - 'MAIL': '/var/mail/cltbld', 18:50:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:50:10 INFO - 'MOZ_NO_REMOTE': '1', 18:50:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:50:10 INFO - 'NO_EM_RESTART': '1', 18:50:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:50:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:50:10 INFO - 'PWD': '/builds/slave/test', 18:50:10 INFO - 'SHELL': '/bin/bash', 18:50:10 INFO - 'SHLVL': '1', 18:50:10 INFO - 'TERM': 'linux', 18:50:10 INFO - 'TMOUT': '86400', 18:50:10 INFO - 'USER': 'cltbld', 18:50:10 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1501808513.100895-1942670171', 18:50:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:50:10 INFO - '_': '/tools/buildbot/bin/python'} 18:50:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:50:10 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:50:10 INFO - Cleaning up... 18:50:10 INFO - Return code: 0 18:50:10 INFO - Running post-action listener: _resource_record_post_action 18:50:10 INFO - Running post-action listener: _start_resource_monitoring 18:50:10 INFO - Starting resource monitoring. 18:50:10 INFO - [mozharness: 2017-08-04 01:50:10.410557Z] Finished create-virtualenv step (success) 18:50:10 INFO - [mozharness: 2017-08-04 01:50:10.411029Z] Running install step. 18:50:10 INFO - Running pre-action listener: _resource_record_pre_action 18:50:10 INFO - Running main action method: install 18:50:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:50:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:50:10 INFO - Reading from file tmpfile_stdout 18:50:10 INFO - Detecting whether we're running mozinstall >=1.0... 18:50:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:50:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:50:10 INFO - Reading from file tmpfile_stdout 18:50:10 INFO - Output received: 18:50:10 INFO - Usage: mozinstall [options] installer 18:50:10 INFO - Options: 18:50:10 INFO - -h, --help show this help message and exit 18:50:10 INFO - -d DEST, --destination=DEST 18:50:10 INFO - Directory to install application into. [default: 18:50:10 INFO - "/builds/slave/test"] 18:50:10 INFO - --app=APP Application being installed. [default: firefox] 18:50:10 INFO - mkdir: /builds/slave/test/build/application 18:50:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 18:50:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 18:50:29 INFO - Reading from file tmpfile_stdout 18:50:29 INFO - Output received: 18:50:29 INFO - /builds/slave/test/build/application/firefox/firefox 18:50:29 INFO - Running post-action listener: _resource_record_post_action 18:50:29 INFO - [mozharness: 2017-08-04 01:50:29.463998Z] Finished install step (success) 18:50:29 INFO - [mozharness: 2017-08-04 01:50:29.464251Z] Running setup-mitmproxy step. 18:50:29 INFO - Running pre-action listener: _resource_record_pre_action 18:50:29 INFO - Running main action method: setup_mitmproxy 18:50:29 INFO - Skipping: mitmproxy is not required 18:50:29 INFO - Running post-action listener: _resource_record_post_action 18:50:29 INFO - [mozharness: 2017-08-04 01:50:29.465556Z] Finished setup-mitmproxy step (success) 18:50:29 INFO - [mozharness: 2017-08-04 01:50:29.465778Z] Running run-tests step. 18:50:29 INFO - Running pre-action listener: _resource_record_pre_action 18:50:29 INFO - Running pre-action listener: _set_gcov_prefix 18:50:29 INFO - Running main action method: run_tests 18:50:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:50:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:50:29 INFO - Python 2.7.3 18:50:29 INFO - Return code: 0 18:50:29 INFO - grabbing minidump binary from tooltool 18:50:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 18:50:29 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 0x1d44eb0>, '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 0x1d45db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d46240>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:50:29 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 18:50:29 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 18:50:29 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 18:50:29 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:50:29 INFO - Return code: 0 18:50:29 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 18:50:29 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:50:29 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:50:29 INFO - ENV: RUST_BACKTRACE is now 1 18:50:29 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 18:50:29 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:50:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/H5B5IEnwSEm76TFIhWTsdQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-026', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 18:50:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/H5B5IEnwSEm76TFIhWTsdQ/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-026 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 18:50:29 INFO - Using env: {'DISPLAY': ':0', 18:50:29 INFO - 'HOME': '/home/cltbld', 18:50:29 INFO - 'LANG': 'en_US.UTF-8', 18:50:29 INFO - 'LANGUAGE': 'en_US:en', 18:50:29 INFO - 'LOGNAME': 'cltbld', 18:50:29 INFO - 'MAIL': '/var/mail/cltbld', 18:50:29 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:50:29 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:50:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:50:29 INFO - 'MOZ_NO_REMOTE': '1', 18:50:29 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:50:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:50:29 INFO - 'NO_EM_RESTART': '1', 18:50:29 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:50:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:50:29 INFO - 'PWD': '/builds/slave/test', 18:50:29 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:50:29 INFO - 'RUST_BACKTRACE': '1', 18:50:29 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 18:50:29 INFO - 'SHELL': '/bin/bash', 18:50:29 INFO - 'SHLVL': '1', 18:50:29 INFO - 'STYLO_FORCE_ENABLED': '1', 18:50:29 INFO - 'TERM': 'linux', 18:50:29 INFO - 'TMOUT': '86400', 18:50:29 INFO - 'USER': 'cltbld', 18:50:29 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1501808513.100895-1942670171', 18:50:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:50:29 INFO - '_': '/tools/buildbot/bin/python'} 18:50:29 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/H5B5IEnwSEm76TFIhWTsdQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-026', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 18:50:29 INFO - mozversion application_buildid: 20170804010714 18:50:29 INFO - mozversion application_changeset: 32083f24a1bb2c33050b4c972783f066432194eb 18:50:29 INFO - mozversion application_display_name: Nightly 18:50:29 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:50:29 INFO - mozversion application_name: Firefox 18:50:29 INFO - mozversion application_remotingname: firefox 18:50:29 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 18:50:29 INFO - mozversion application_vendor: Mozilla 18:50:29 INFO - mozversion application_version: 57.0a1 18:50:29 INFO - mozversion platform_buildid: 20170804010714 18:50:29 INFO - mozversion platform_changeset: 32083f24a1bb2c33050b4c972783f066432194eb 18:50:29 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 18:50:29 INFO - mozversion platform_version: 57.0a1 18:50:29 INFO - using testdate: 1501811429 18:50:29 INFO - actual date: 1501811429 18:50:29 INFO - starting webserver on 'localhost:35974' 18:50:29 INFO - SUITE-START | Running 2 tests 18:50:29 INFO - TEST-START | dromaeo_css 18:50:29 INFO - Initialising browser for dromaeo_css test... 18:50:29 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:35974/getInfo.html -profile /tmp/tmpMR4N9V/profile 18:50:29 INFO - TEST-INFO | started process 17227 (/builds/slave/test/build/application/firefox/firefox http://localhost:35974/getInfo.html) 18:50:36 INFO - TEST-INFO | 17227: exit 0 18:50:36 INFO - Browser initialized. 18:50:36 INFO - Running cycle 1/1 for dromaeo_css test... 18:50:36 INFO - TEST-INFO | started process 17454 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpMR4N9V/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 18:50:37 INFO - PID 17454 | 18:50:37 INFO - PID 17454 | (/builds/slave/test/build/application/firefox/firefox:17508): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 18:50:37 INFO - PID 17454 | 18:50:47 INFO - PID 17454 | RSS: Main: 170598400 18:50:47 INFO - PID 17454 | 18:53:47 INFO - PID 17454 | Cycle 1(1): loaded http://localhost:35974/tests/dromaeo/cssquery-dojo.html (next: http://localhost:35974/tests/dromaeo/cssquery-ext.html) 18:53:48 INFO - PID 17454 | RSS: Main: 173367296 18:53:48 INFO - PID 17454 | 18:56:50 INFO - PID 17454 | Cycle 1(1): loaded http://localhost:35974/tests/dromaeo/cssquery-ext.html (next: http://localhost:35974/tests/dromaeo/cssquery-jquery.html) 18:56:51 INFO - PID 17454 | RSS: Main: 173342720 18:56:51 INFO - PID 17454 | 18:59:52 INFO - PID 17454 | Cycle 1(1): loaded http://localhost:35974/tests/dromaeo/cssquery-jquery.html (next: http://localhost:35974/tests/dromaeo/cssquery-mootools.html) 18:59:53 INFO - PID 17454 | RSS: Main: 175624192 18:59:53 INFO - PID 17454 | 19:02:55 INFO - PID 17454 | Cycle 1(1): loaded http://localhost:35974/tests/dromaeo/cssquery-mootools.html (next: http://localhost:35974/tests/dromaeo/cssquery-prototype.html) 19:02:56 INFO - PID 17454 | RSS: Main: 171896832 19:02:56 INFO - PID 17454 | 19:05:59 INFO - PID 17454 | Cycle 1(1): loaded http://localhost:35974/tests/dromaeo/cssquery-prototype.html (next: http://localhost:35974/tests/dromaeo/cssquery-yui.html) 19:06:00 INFO - PID 17454 | RSS: Main: 176934912 19:06:00 INFO - PID 17454 | 19:09:02 INFO - PID 17454 | Cycle 1(1): loaded http://localhost:35974/tests/dromaeo/cssquery-yui.html (next: http://localhost:35974/tests/dromaeo/cssquery-dojo.html) 19:09:02 INFO - PID 17454 | RSS: Main: 178081792 19:09:02 INFO - PID 17454 | 19:09:02 INFO - PID 17454 | __start_tp_report 19:09:02 INFO - PID 17454 | _x_x_mozilla_page_load 19:09:02 INFO - PID 17454 | _x_x_mozilla_page_load_details 19:09:02 INFO - PID 17454 | |i|pagename|runs| 19:09:02 INFO - PID 17454 | |0;dojo.html;3146.905592832216;3169.857356418961;3172.2386627209476;3186.888458903939;3190.5214217867315;6066.636001839893;6087.11736798164;6107.969460152704;6111.511079113671;6130.0498422744495;6025.246844144482;6101.237345331833;6105.908411373833;6112.174856394386;6128.387161283869;5610.438956104398;5614.803481878135;5626.043572592659;5644.971775141119;5660.9716951415185;5599.832005039835;5618.353889302721;5624.3531993820625;5627.437256274381;5692.345380281279;5635.943640563603;5684.630499017571;5714.085746280596;5720.399358067405;5727.828165155052;5481.451854814526;5626.381098079208;5638.464345887134;5704.2299289595785;5725.828225153252;5542.224088627595;5563.833085007415;5588.608797384185;5605.103183490663;5613.46672066153;5991.850203744898;6026.578139530235;6051.304100028509;6055.878882422327;6071.696415179224;6483.935160648381;6487.740490380379;6509.3490650935;6594.637294948776;6605.70274337656;6445.742170313182;6573.079768832367;6599;6614.0740296358545;6648.434883034948;6179.474744646755;6198.597091189104;6202.689865506797;6219.844503887393;6229.31477537466;6770.984352347089;6773.762918297836;6841.897371539431;6872.518923675395;6884.345987131214;6409.038644203407;6438.742450301936;6476.093346931433;6478.578892371981;6558.950567909207;6577.3422657733845;6581.440577550962;6581.736730530821;6583.407493325623;6612.966935165294;6488.059231411419;6500.739970401226;6583.407493325623;6588.703508342136;6605.70274337656;6468.417842394207;6485.383888530582;6509.2839787623325;6627.768028118992;6630.171228596426;5213.582913366921;5234.528892399626;5245.318108645928;5250.766069973553;5261.500157485033;4528.049109686936;4577.198990176706;4585.72485650862;4588.357629931812;4604.7927843247135;5606.719664016783;5668.659880407189;5706.600537962386;5719.428057194331;5789.623674461147;6594.571352862049;6624.370684784938;6633.834154146185;6638.468922486286;6645.335466453316;5715.1713001614535;5720.1991721159075;5746.454086861741;5746.712664366765;5749.396313387034;5768.913466298009;5776.451237132465;5790.623609465456;5835.620684655484;5888.175655408247;6353.36466353361;6399.488040956712;6411.519136064814;6417.197850268716;6469.4177524022125;6526.4452521536205;6537.8692426152165;6593.736250550116;6610.537262391779;6640.83397915056;6293.748250070129;6340.048992651139;6363.140975968185;6409.359064093554;6418.19772528434;6486.805395838132;6489.610623362613;6494.6103233806125;6499.025146227915;6501.674916254263;6636.070950066898;6641.302663220292;6646.734130634721;6649;6654.833629159116;6391.7123729433215;6394.328595497405;6424.710888009958;6428.421442070049;6430.710618022106;6368.681565921778;6449.838754031187;6467.579607325509;6539.673016349258;6550.8362290943105;6342.619442833445;6361.109444677842;6445.2587952386075;6460.773872914426;6461.2892581816905;6305.527085468517;6418.19772528434;6521.804345869631;6558.278589355263;6584.045313429621;6232.906506402317;6254.781082662267;6259.405356491126;6321.778737744157;6349.7142628580905 19:09:02 INFO - PID 17454 | |1;ext.html;800.1718221640599;815.9061707903593;837.3887062444416;851.0893344121788;868.6916144768607;38989;39765;39784.40323395151;39901.00247493814;40037.399439008404;35812.46281305783;35906;35958.46062309061;36123;36139.63860361396;6693.096431981681;6747.527673062863;6772.8984065239065;6825.897611535831;6834.316568343151;6705.631190284533;6778.762743303985;6824.590524568542;6827.146606674166;6827.761028364008;7537.472376933617;7735.071791385041;7758.340541053989;7767.844643107134;7777.144514103444;7649.235076492362;7712.57580833054;7738.845223095563;7739.651715672752;7744.341730952876;7500.549967001997;7638.274363935473;7693.153753087156;7712.190220026872;7725.343345815612;35841.10397240064;35870.8206458966;36226.63773362259;36260.63739362625;36261.45607815885;3554.36021516127;3566.965579981802;3586.3544561978833;3592.550931133608;3609.4766258892314;29862.85068574643;29965.400691986037;30040.8497957511;30075.849620751756;30177;5013.24801279811;5045.091883460976;5067.442581316052;5071.746412679388;5111.207762796773;189264;190489;191388;191492;191602.04198979057;47490.525094749304;47529.762351188714;47555;47591.52408475871;47801;50016.49983500118;50080.49436539407;50217.497825022016;50382.7404314895;50720.49279507158;16116.113613751357;16247.756283655755;16248.187590620422;16386.180690965404;16392.590185245463;13462.326883655778;13520.661983450296;13547.661308467366;13554.186748795104;13554.525591604444;14270.85729142716;14300.570982870531;14450.421983120768;14474.638134046732;14520.636984075483;5655;5681.829545113571;5708.771649133988;5799.159121927381;5808.680522571299;1004.6082028009082;1010.6058637131524;1015.1675625986768;1024.687470321559;1037.8080055189814;22386.888065559895;22486.66270005951;22592.66111008336;22594.435139121324;22822;1107.4739498738036;1112.732944093428;1127.177160672697;1131.8358837968449;1138.3397629374942;11953.40232988347;12030.879691202976;12031.699207519707;12036.037117030617;12083.154179207371;214248;215674;216561;216921;217293;51090.489095108576;51493;52636.47363526392;56562.434375655714;57142.7142864283;1511.2821409830242;1522.1628104542458;1533.9463118790788;1551.43372668975;1552.2781906413572;26626.201213963614;27249.31876703098;27477.72522274752;27533.449331012867;27674.584881226394;37291.81354093212;37846.24307513779;38010.809945950095;38012.04969875775;38050;205962;206901;207474;207896;209090;34839;35107;35279;35439.468407973385;35512;40778.79610601928;41510;42121.36817947672;42145.36781948212;42379.7881010593;31656.942298750506;31978.84010579932;33252.50121248232;34097.48853767146;35058.47412288767;33348.16629584183;33584.32831343408;33658.158546036546;33827.492587610715;33965.15087122842;35795.82102089473;36338.63661363353;36471.45292820663;36916.63083369132;36951.44572831463;31771.841140795073;31979.200519987186;32052.35895282128;32097.679023209468;32314.83842580772;23636.164099289483;24221;24504.264872053867;24835.379115522257;25204.873975630006 19:09:02 INFO - PID 17454 | |2;jquery.html;573.8465683975209;575.6344721102099;581.8690794571224;587.744331215921;602.6986506746626;22101.557968840632;22314.21900233492;22947.311580652586;22952.88523557382;23112.42218944523;27212;27864.721352786466;27901.302467438323;27960;28069.719302806967;6805.489588280886;6991.615461149635;7003.684834182449;7007;7010.929890701104;6361.109444677749;6772.966135169318;6871.175458944908;6953.37419632233;6957.930420695804;6805.489588280874;6805.693743781517;6806.251312355637;6808.353206445405;6809.829754256134;6778.05107284978;6781.796546103625;6784.796456106275;6789.3889549940495;6791.185057793047;6600.339966003361;6667.93332066678;6704.631245281507;6706.798796036127;6707.1616047994;31623.683763162306;32081.51877721836;32154.839225803957;32462.675373246202;32725.836370818233;1966.852486063551;2013.9295124670566;2014.0533949043927;2014.7884472130509;2019.81821636052;19906.303279385298;20397.694034589495;20427.387178384673;20458.181672733073;20513.4871628209;2411.9397015074587;2478.4051827561257;2541.491701659657;2543.8473691578565;2546.261584140597;139256.30371848075;141834;142385.28807356002;142797;146471;19163.64833717472;20479.28322508705;20747.58504829925;21373.076423121995;22299.331020069425;42740.14519709588;42913.570864291585;43014;43279.56720432818;43302.56697432986;11650.825237621442;12002.63992080239;12096.516139354504;12144.785521447784;12167.695807604881;10219.642312519027;10228.897711022943;10255.846162307571;10278.537465814057;10350.53422595985;9176.44941303524;11478.311301321948;11536.942315288537;11638.35989020612;11703.23928944616;3653.647406549418;3719.8796685889324;3750.5181308336823;4130.442390277274;4208.011117387411;951.9095685909828;979.5787811241235;985.1084768284713;1001.8998100190068;1008.742770593494;19322.966877625528;20132.597348052957;20560.38318850437;23776.286711398687;23946.161884333964;1027.1166796554958;1064.5422468338688;1065.8294672852462;1068.8450174724776;1070.796548655753;10872.45637718111;11297.152713546353;11466.31202127875;11529.365884876412;13391.866081339256;458308;458407;482476;505008;505209;115421.42289288499;115575;116928;119552;119942;1264.3070260345405;1287.6072797796762;1292;1323.2920387592592;1325.085690873275;21471.78528214698;21531.784682153604;21871.343859684235;21873.453163670398;21936.780632194113;58003;59302.40697592969;59461.40538594559;59805;60353.39646603478;70048.64975675089;70849;71365.64317178588;71682;71803.28196717966;12063.776335099232;14257.287135643384;14522.83817294635;14918.850811491746;14959;22630.547389051797;23030.769692302863;23064.308070757903;24297.514049718553;24470.38824029342;18621.534461638566;18773.249070037047;18839.24643014319;18879.150438230587;19040.952747599014;16486.422975195812;16591.834081659028;16730.330786768394;16895.07077110771;17026.65946681084;17166.570835729206;17946.641067178323;18078.728819067986;18159.727604085685;19124.617507650048;14753.704925901207;15131.848681513044;15186.316615752097;15189.468368607046;15299.158546280061;18445.723314150568;18501.53746156357;18799.590030748022;18828.6234275311;18997.62004759925 19:09:02 INFO - PID 17454 | |3;mootools.html;1357.2942070123536;1403.277312184225;1409.0981771666136;1426.7146570685866;1433.2117335465498;13364.465421383145;13970.511032113878;13970.720585588282;13974.371153298112;14204.92897535511;14210.78683819741;14425.855741442583;14459;14471.131732096082;14484.04465397171;2540.098265115885;2560.9615855762177;2597.6882774067044;2607.895684172631;2608.491344187884;2720.9319767005886;2744.1493137127454;2749.5463248563965;2760.4755096531694;2770.8060435769403;2664.080892092225;2680.8123431359813;2687.072959828856;2689.314224872655;2709.796765242605;2560.807939404552;2592.1705054382605;2602.8958841646313;2612.255507180454;2625.2911713837348;2612.542805009116;2628.7634112929927;2639.577667573198;2643.2863126955613;2647.682278126618;13426.328683565782;13706.93146534271;13730.93134534321;13738;13746.106503077368;7525.096988361432;7727.111382191008;7755.108162561265;7797.883031754528;7844.882326765103;4878.048780487793;4958.181899986498;5085.644004919657;5088.1350170471105;5202.739863006835;1822.5443639090226;1834.110456428631;1841.8618603604784;1852.148011914521;1873.5409824592925;22389.44026399337;22685.886570567043;22772.772272277398;22776.77223227763;22927.197548085904;6805.557638753465;7121.501494895411;7153.749618763319;7170.211276759553;7179.066721326299;15504;15650.45223417175;15663.37346506127;15666.843331566766;15684.137372444626;7080.150381954199;7571.46999710026;7607.65765540552;7608.695652173851;7640.656170472342;5677.517411020027;5891.145783861316;6113.32753397126;6980.441564674814;7024.719011239596;5053.84838454847;5196.45437229093;5258.631895767336;5265.684058956398;5294.7352632369;1493.357856121878;1534.608674787922;1546.2423412528005;1552.604085958096;1557.1591340676134;876.4565969099071;903.6927444668844;915.3638221436171;925.9629614815467;929.0941332201049;7319.926800731924;7331.560106393634;7353.558786472829;7380.114386273574;7474.289942455458;713.2974020589714;729.3472342253654;733.9926600734032;735.8491509240592;738.3281214095148;4392.494863090722;4434.84478043267;4880.877978050577;5003.54968052877;5259.368875734936;17531.211095500734;18130.18414171339;18462.81537184638;18495.53761155955;18588.349407770664;18041.7293740594;18246.908765456086;18513.259469621335;18607.534811629546;18623.81376186248;10525.473726313807;10688;10688.839667404995;10723.249372543847;10812;7846.843063138591;7890.210978902064;7923.286904178652;8048.315893148913;8050.758477245692;16333.693304535846;16435.83564164343;16482.83517164861;16793;16900.154992250093;16900.830991690418;17098.487045388658;17222.138893055548;17255.56861078483;17350.305987760854;11508.59719909799;11705.531778728755;11712.824307635563;11758.70603234926;11773.352465614473;12578.433970471533;12990.805137923127;13026.934865325613;13194.868051319749;13206.207627542379;10776.784464310827;11137.109031277316;11137.944310278397;11224.887751122384;11367.033802126913;10433.478326083514;10567;10597.09924656382;10780.407077610493;10791.730206744895;9403.247740180906;9565.473898935346;9575.616975320909;9576.616935322509;9595.520223988913;6997.8250543734775;7021.332973367522;7102.609356485443;7198.064251647253;7233.855322893618;7131.60675478986;7219.314165154302;7253.492255542062;7270.781876543712;7370.889436658562 19:09:02 INFO - PID 17454 | |4;prototype.html;495.8051096856576;499.36603920803657;511.5166167971264;519.2807960974052;525.3118414876511;6131.509479241661;6132.4480796728285;6141.539384546155;6165.907511387321;6208.361446385291;5843.386444423338;6011.969940150293;6013.308469526006;6017.608855424394;6060.666663333526;4825.396825396825;4830.758462076882;4849.127157111719;4906.337644418011;4933.33399991002;4831.212451392972;4881.4630390657;4888.266759985995;4913.877153071184;4925.38432695913;4629.213033784248;4853.490383509734;4873.512648735116;4889.633277504184;4901.436334821487;4827.372441582572;4843.370361852954;4856.660033797635;4870.902581948359;4880.365552478191;4373.387725718402;4383.802728877176;4396.318570621559;4422.535633758441;4423.0711550574415;5871.768727883377;5969.791057312973;5970.253718285215;5970.701464926737;5974.581779275452;5638.859028524279;5650.773969041234;5676.489115979582;5690.516306113986;5696.715164241771;6409.070684750685;6419.903701444482;6427.196600424947;6471.546991710582;6537.529055962418;4808.927866082012;4817.108834865561;4823.469418363977;4849.248366503198;4861.805527778884;6684.064231007663;6828.146481689789;6869.175698916062;6876.243613202544;6889.99999999995;4939.975300123477;4951.579115775199;4961.627877909171;4970.105381031449;4994.275830004629;4991.825286115041;5033.345665063518;5034.471380504994;5054.267131265946;5062.493750624908;5626.465485778845;5627.943720562824;5649.152627105967;5653.434656534314;5680.176374425685;6348.508100596357;6386;6503.414692677682;6546;6555.3772391622715;1665.7418100194488;1699.2608215426328;1715.5968347438343;1735.496705955259;1745.3803899615707;1148.5118824499555;1422.8332767130853;1423.686788906439;1429.3425024488645;1449.5144126717669;5612.410696876851;5641.887162256732;5645;5674.886502269931;5678.630888992202;6508.869822603521;6547.279799222082;6548.410643042053;6564;6614;5512.944870551323;5638.520725738276;5736.340320863154;5738.885222295531;5758.913616295759;3514.929701406009;3525.383057964846;3534.770239934396;3546.3261980224192;3565.3760591896316;6456.7740129095255;6470.870582588322;6509.739610415625;6515.087887695727;6529.640869752209;6789.626570538668;6868.519203655696;6883.728369638007;6924.515283930177;6932.584044957218;6479.8056058318325;6502.187226596676;6509;6550.279469258282;6561.573497722633;6529.67351632426;6621.768238111643;6652.871081253952;6666.166729158856;6716.899246511404;6513.381228783447;6549;6561.278259391559;6593.505487088583;6652.634105124264;6421.7110230038825;6457.257415397289;6549.24683661385;6559.573627714183;6604.867902641824;4882.707037577757;4928.728919909439;4937.666829955963;4961.08219979305;4979.203327467732;6525.3148419417175;6577.473802095917;6659.4006539411685;6667.399934005963;6710.563813352116;6371.872562548816;6402.807915762534;6414.326495717883;6457.773977910751;6479.967600161969;6421;6433.388828061326;6450.612963222222;6468;6469.644169570719;6129.387061293835;6381.074744162163;6394.744210231539;6399.232092148972;6407.935920640734;6031.712716569568;6345.432477385883;6418.743250270124;6433.5496515243485;6443.065755465337;6145.815625531062;6179.783707570394;6192.59748116371;6209.192804935329;6282 19:09:02 INFO - PID 17454 | |5;yui.html;909.386164339071;914.3279689428269;934.7195841247628;937.4328531238605;941.670415354626;16880.746788798177;17809.376671816488;19008.809911900877;19054.237830486763;19059.61880762384;14743.410263589443;16229.594260143502;16352.591185220375;16391.01653900767;16420.096894670787;1039.6517166749147;1055.0662663542744;1056.1181413519682;1058.0424715632364;1058.8994045565698;1045.2213101239588;1046.3355769086609;1046.6650671785033;1046.8168070587656;1063.2450959818539;783.4750717019582;802.542550746075;1071.1216802222182;1112.3770688414513;1124.083871644611;1090.3675867996542;1091.7980173667897;1091.8307662312316;1093.3112139352236;1107.3023994883213;797.9760607181736;799.4923223752901;799.6161842315663;802.1417083720421;817.3012074676185;18727.906360468245;18975.335863244858;19108.713369299472;19158.71261931072;19679.31122410723;21245.575088498143;21295.893520532452;21589.352319430443;21632.026558804893;21863.453413664625;15770.448034318744;16347.509574712778;16424.42514511998;16540.338386464526;16571.83428165715;3444.9311013779834;3505.193805424741;3505.8422370993367;3508.6666766657127;3508.701760350373;115665;116198;116535;116957.41521292424;120529.39735301267;13493.932530337286;13932.651683707785;13953.999999999796;13968.161910285415;13979.231142287068;23392.415189620395;29428.264293392407;30663.540046899314;30702.232444189074;30859.84570077135;8571.871421928676;8593.269572086318;8705.303575713928;8827.293816494664;8829.16122968317;4108.4864391951005;4158.7296825706835;4184.539700632928;4200.138971510833;4207.705460617727;5186.81846135391;5188.092083885305;5236.162214045735;5310.9734451327495;5324.893502129936;1081.7403823082404;1089.5696200000955;1095.611058074388;1107.7839821234943;1109.1237922041657;675.9594424334457;680.5270337115757;702.4169938950658;717.3938022371088;718.4288490649946;10776.407297598707;11686.88313116875;11702.94148529269;11718.589849355232;11812.940935295268;692.2039654397483;693.2166651683666;701.1446035836271;702.0557350363753;707.8973548835493;5315.734213289397;5364.30264065669;5384.192371144281;5483.396826349099;5536.944630553723;95408;96108.51945740226;96657;97328;101014.49492752636;83271;86636;86790.56604716936;86802;87207;74117;75303.62348188333;79336.20663793404;80164.59917700374;80448.19551804524;11868.228565143238;12193.634190974284;12422.503099876265;12617.747645046864;12789.360531973178;34915.4762678555;35113.473297901066;35667.82166089153;36991.445128323634;37342;46049.76975115237;46889;65397;68045;68961;19026.619467610846;19167.52081197981;19252.326168584033;19319.710204347226;19364.225430982602;18738.344157954405;18930.148143333307;19701;21416;21810.672839907733;18057.458276251735;18163.545911351794;18219.45341639753;18378.081095945417;18723.719144212577;16275.51173464798;16313.184340782676;16490;17772.111394430485;18526.722099168794;15538;15685.764713529077;15729.213539323217;15791.526254212393;15878.60303492422;13501;13624.591262262147;13638.93180534091;13798.792193249434;14288.785668214778;13045.739085218052;13766.24285664258;14596.927015364856;14679;14701.485448009269 19:09:02 INFO - PID 17454 | __end_tp_report 19:09:02 INFO - PID 17454 | __start_cc_report 19:09:02 INFO - PID 17454 | _x_x_mozilla_cycle_collect,388 19:09:02 INFO - PID 17454 | __end_cc_report 19:09:02 INFO - PID 17454 | __startTimestamp1501812542756__endTimestamp 19:09:02 INFO - PID 17454 | 19:09:02 INFO - PID 17454 | ------- Summary: start ------- 19:09:02 INFO - PID 17454 | Number of tests: 6 19:09:02 INFO - PID 17454 | 19:09:02 INFO - PID 17454 | [#0] dojo.html Cycles:165 Average:6064.95 Median:6342.62 stddev:709.19 (11.2%) stddev-sans-first:673.41 19:09:02 INFO - PID 17454 | Values: 3146.9 3169.9 3172.2 3186.9 3190.5 6066.6 6087.1 6108.0 6111.5 6130.0 6025.2 6101.2 6105.9 6112.2 6128.4 5610.4 5614.8 5626.0 5645.0 5661.0 5599.8 5618.4 5624.4 5627.4 5692.3 5635.9 5684.6 5714.1 5720.4 5727.8 5481.5 5626.4 5638.5 5704.2 5725.8 5542.2 5563.8 5588.6 5605.1 5613.5 5991.9 6026.6 6051.3 6055.9 6071.7 6483.9 6487.7 6509.3 6594.6 6605.7 6445.7 6573.1 6599.0 6614.1 6648.4 6179.5 6198.6 6202.7 6219.8 6229.3 6771.0 6773.8 6841.9 6872.5 6884.3 6409.0 6438.7 6476.1 6478.6 6559.0 6577.3 6581.4 6581.7 6583.4 6613.0 6488.1 6500.7 6583.4 6588.7 6605.7 6468.4 6485.4 6509.3 6627.8 6630.2 5213.6 5234.5 5245.3 5250.8 5261.5 4528.0 4577.2 4585.7 4588.4 4604.8 5606.7 5668.7 5706.6 5719.4 5789.6 6594.6 6624.4 6633.8 6638.5 6645.3 5715.2 5720.2 5746.5 5746.7 5749.4 5768.9 5776.5 5790.6 5835.6 5888.2 6353.4 6399.5 6411.5 6417.2 6469.4 6526.4 6537.9 6593.7 6610.5 6640.8 6293.7 6340.0 6363.1 6409.4 6418.2 6486.8 6489.6 6494.6 6499.0 6501.7 6636.1 6641.3 6646.7 6649.0 6654.8 6391.7 6394.3 6424.7 6428.4 6430.7 6368.7 6449.8 6467.6 6539.7 6550.8 6342.6 6361.1 6445.3 6460.8 6461.3 6305.5 6418.2 6521.8 6558.3 6584.0 6232.9 6254.8 6259.4 6321.8 6349.7 19:09:02 INFO - PID 17454 | 19:09:02 INFO - PID 17454 | [#1] ext.html Cycles:180 Average:37342.93 Median:3560.66 stddev:53054.56 (1490.0%) stddev-sans-first:53132.44 19:09:02 INFO - PID 17454 | Values: 800.2 815.9 837.4 851.1 868.7 38989.0 39765.0 39784.4 39901.0 40037.4 35812.5 35906.0 35958.5 36123.0 36139.6 6693.1 6747.5 6772.9 6825.9 6834.3 6705.6 6778.8 6824.6 6827.1 6827.8 7537.5 7735.1 7758.3 7767.8 7777.1 7649.2 7712.6 7738.8 7739.7 7744.3 7500.5 7638.3 7693.2 7712.2 7725.3 35841.1 35870.8 36226.6 36260.6 36261.5 3554.4 3567.0 3586.4 3592.6 3609.5 29862.9 29965.4 30040.8 30075.8 30177.0 5013.2 5045.1 5067.4 5071.7 5111.2 189264.0 190489.0 191388.0 191492.0 191602.0 47490.5 47529.8 47555.0 47591.5 47801.0 50016.5 50080.5 50217.5 50382.7 50720.5 16116.1 16247.8 16248.2 16386.2 16392.6 13462.3 13520.7 13547.7 13554.2 13554.5 14270.9 14300.6 14450.4 14474.6 14520.6 5655.0 5681.8 5708.8 5799.2 5808.7 1004.6 1010.6 1015.2 1024.7 1037.8 22386.9 22486.7 22592.7 22594.4 22822.0 1107.5 1112.7 1127.2 1131.8 1138.3 11953.4 12030.9 12031.7 12036.0 12083.2 214248.0 215674.0 216561.0 216921.0 217293.0 51090.5 51493.0 52636.5 56562.4 57142.7 1511.3 1522.2 1533.9 1551.4 1552.3 26626.2 27249.3 27477.7 27533.4 27674.6 37291.8 37846.2 38010.8 38012.0 38050.0 205962.0 206901.0 207474.0 207896.0 209090.0 34839.0 35107.0 35279.0 35439.5 35512.0 40778.8 41510.0 42121.4 42145.4 42379.8 31656.9 31978.8 33252.5 34097.5 35058.5 33348.2 33584.3 33658.2 33827.5 33965.2 35795.8 36338.6 36471.5 36916.6 36951.4 31771.8 31979.2 32052.4 32097.7 32314.8 23636.2 24221.0 24504.3 24835.4 25204.9 19:09:02 INFO - PID 17454 | 19:09:02 INFO - PID 17454 | [#2] jquery.html Cycles:180 Average:36089.44 Median:21872.40 stddev:81452.68 (372.4%) stddev-sans-first:81637.53 19:09:02 INFO - PID 17454 | Values: 573.8 575.6 581.9 587.7 602.7 22101.6 22314.2 22947.3 22952.9 23112.4 27212.0 27864.7 27901.3 27960.0 28069.7 6805.5 6991.6 7003.7 7007.0 7010.9 6361.1 6773.0 6871.2 6953.4 6957.9 6805.5 6805.7 6806.3 6808.4 6809.8 6778.1 6781.8 6784.8 6789.4 6791.2 6600.3 6667.9 6704.6 6706.8 6707.2 31623.7 32081.5 32154.8 32462.7 32725.8 1966.9 2013.9 2014.1 2014.8 2019.8 19906.3 20397.7 20427.4 20458.2 20513.5 2411.9 2478.4 2541.5 2543.8 2546.3 139256.3 141834.0 142385.3 142797.0 146471.0 19163.6 20479.3 20747.6 21373.1 22299.3 42740.1 42913.6 43014.0 43279.6 43302.6 11650.8 12002.6 12096.5 12144.8 12167.7 10219.6 10228.9 10255.8 10278.5 10350.5 9176.4 11478.3 11536.9 11638.4 11703.2 3653.6 3719.9 3750.5 4130.4 4208.0 951.9 979.6 985.1 1001.9 1008.7 19323.0 20132.6 20560.4 23776.3 23946.2 1027.1 1064.5 1065.8 1068.8 1070.8 10872.5 11297.2 11466.3 11529.4 13391.9 458308.0 458407.0 482476.0 505008.0 505209.0 115421.4 115575.0 116928.0 119552.0 119942.0 1264.3 1287.6 1292.0 1323.3 1325.1 21471.8 21531.8 21871.3 21873.5 21936.8 58003.0 59302.4 59461.4 59805.0 60353.4 70048.6 70849.0 71365.6 71682.0 71803.3 12063.8 14257.3 14522.8 14918.9 14959.0 22630.5 23030.8 23064.3 24297.5 24470.4 18621.5 18773.2 18839.2 18879.2 19041.0 16486.4 16591.8 16730.3 16895.1 17026.7 17166.6 17946.6 18078.7 18159.7 19124.6 14753.7 15131.8 15186.3 15189.5 15299.2 18445.7 18501.5 18799.6 18828.6 18997.6 19:09:02 INFO - PID 17454 | 19:09:02 INFO - PID 17454 | [#3] mootools.html Cycles:180 Average:8624.37 Median:2605.40 stddev:5807.66 (222.9%) stddev-sans-first:5798.28 19:09:02 INFO - PID 17454 | Values: 1357.3 1403.3 1409.1 1426.7 1433.2 13364.5 13970.5 13970.7 13974.4 14204.9 14210.8 14425.9 14459.0 14471.1 14484.0 2540.1 2561.0 2597.7 2607.9 2608.5 2720.9 2744.1 2749.5 2760.5 2770.8 2664.1 2680.8 2687.1 2689.3 2709.8 2560.8 2592.2 2602.9 2612.3 2625.3 2612.5 2628.8 2639.6 2643.3 2647.7 13426.3 13706.9 13730.9 13738.0 13746.1 7525.1 7727.1 7755.1 7797.9 7844.9 4878.0 4958.2 5085.6 5088.1 5202.7 1822.5 1834.1 1841.9 1852.1 1873.5 22389.4 22685.9 22772.8 22776.8 22927.2 6805.6 7121.5 7153.7 7170.2 7179.1 15504.0 15650.5 15663.4 15666.8 15684.1 7080.2 7571.5 7607.7 7608.7 7640.7 5677.5 5891.1 6113.3 6980.4 7024.7 5053.8 5196.5 5258.6 5265.7 5294.7 1493.4 1534.6 1546.2 1552.6 1557.2 876.5 903.7 915.4 926.0 929.1 7319.9 7331.6 7353.6 7380.1 7474.3 713.3 729.3 734.0 735.8 738.3 4392.5 4434.8 4880.9 5003.5 5259.4 17531.2 18130.2 18462.8 18495.5 18588.3 18041.7 18246.9 18513.3 18607.5 18623.8 10525.5 10688.0 10688.8 10723.2 10812.0 7846.8 7890.2 7923.3 8048.3 8050.8 16333.7 16435.8 16482.8 16793.0 16900.2 16900.8 17098.5 17222.1 17255.6 17350.3 11508.6 11705.5 11712.8 11758.7 11773.4 12578.4 12990.8 13026.9 13194.9 13206.2 10776.8 11137.1 11137.9 11224.9 11367.0 10433.5 10567.0 10597.1 10780.4 10791.7 9403.2 9565.5 9575.6 9576.6 9595.5 6997.8 7021.3 7102.6 7198.1 7233.9 7131.6 7219.3 7253.5 7270.8 7370.9 19:09:02 INFO - PID 17454 | 19:09:02 INFO - PID 17454 | [#4] prototype.html Cycles:180 Average:5438.96 Median:5993.28 stddev:1515.97 (25.3%) stddev-sans-first:1474.12 19:09:02 INFO - PID 17454 | Values: 495.8 499.4 511.5 519.3 525.3 6131.5 6132.4 6141.5 6165.9 6208.4 5843.4 6012.0 6013.3 6017.6 6060.7 4825.4 4830.8 4849.1 4906.3 4933.3 4831.2 4881.5 4888.3 4913.9 4925.4 4629.2 4853.5 4873.5 4889.6 4901.4 4827.4 4843.4 4856.7 4870.9 4880.4 4373.4 4383.8 4396.3 4422.5 4423.1 5871.8 5969.8 5970.3 5970.7 5974.6 5638.9 5650.8 5676.5 5690.5 5696.7 6409.1 6419.9 6427.2 6471.5 6537.5 4808.9 4817.1 4823.5 4849.2 4861.8 6684.1 6828.1 6869.2 6876.2 6890.0 4940.0 4951.6 4961.6 4970.1 4994.3 4991.8 5033.3 5034.5 5054.3 5062.5 5626.5 5627.9 5649.2 5653.4 5680.2 6348.5 6386.0 6503.4 6546.0 6555.4 1665.7 1699.3 1715.6 1735.5 1745.4 1148.5 1422.8 1423.7 1429.3 1449.5 5612.4 5641.9 5645.0 5674.9 5678.6 6508.9 6547.3 6548.4 6564.0 6614.0 5512.9 5638.5 5736.3 5738.9 5758.9 3514.9 3525.4 3534.8 3546.3 3565.4 6456.8 6470.9 6509.7 6515.1 6529.6 6789.6 6868.5 6883.7 6924.5 6932.6 6479.8 6502.2 6509.0 6550.3 6561.6 6529.7 6621.8 6652.9 6666.2 6716.9 6513.4 6549.0 6561.3 6593.5 6652.6 6421.7 6457.3 6549.2 6559.6 6604.9 4882.7 4928.7 4937.7 4961.1 4979.2 6525.3 6577.5 6659.4 6667.4 6710.6 6371.9 6402.8 6414.3 6457.8 6480.0 6421.0 6433.4 6450.6 6468.0 6469.6 6129.4 6381.1 6394.7 6399.2 6407.9 6031.7 6345.4 6418.7 6433.5 6443.1 6145.8 6179.8 6192.6 6209.2 6282.0 19:09:02 INFO - PID 17454 | 19:09:02 INFO - PID 17454 | [#5] yui.html Cycles:180 Average:21821.16 Median:19163.12 stddev:28794.60 (150.3%) stddev-sans-first:28832.56 19:09:02 INFO - PID 17454 | Values: 909.4 914.3 934.7 937.4 941.7 16880.7 17809.4 19008.8 19054.2 19059.6 14743.4 16229.6 16352.6 16391.0 16420.1 1039.7 1055.1 1056.1 1058.0 1058.9 1045.2 1046.3 1046.7 1046.8 1063.2 783.5 802.5 1071.1 1112.4 1124.1 1090.4 1091.8 1091.8 1093.3 1107.3 798.0 799.5 799.6 802.1 817.3 18727.9 18975.3 19108.7 19158.7 19679.3 21245.6 21295.9 21589.4 21632.0 21863.5 15770.4 16347.5 16424.4 16540.3 16571.8 3444.9 3505.2 3505.8 3508.7 3508.7 115665.0 116198.0 116535.0 116957.4 120529.4 13493.9 13932.7 13954.0 13968.2 13979.2 23392.4 29428.3 30663.5 30702.2 30859.8 8571.9 8593.3 8705.3 8827.3 8829.2 4108.5 4158.7 4184.5 4200.1 4207.7 5186.8 5188.1 5236.2 5311.0 5324.9 1081.7 1089.6 1095.6 1107.8 1109.1 676.0 680.5 702.4 717.4 718.4 10776.4 11686.9 11702.9 11718.6 11812.9 692.2 693.2 701.1 702.1 707.9 5315.7 5364.3 5384.2 5483.4 5536.9 95408.0 96108.5 96657.0 97328.0 101014.5 83271.0 86636.0 86790.6 86802.0 87207.0 74117.0 75303.6 79336.2 80164.6 80448.2 11868.2 12193.6 12422.5 12617.7 12789.4 34915.5 35113.5 35667.8 36991.4 37342.0 46049.8 46889.0 65397.0 68045.0 68961.0 19026.6 19167.5 19252.3 19319.7 19364.2 18738.3 18930.1 19701.0 21416.0 21810.7 18057.5 18163.5 18219.5 18378.1 18723.7 16275.5 16313.2 16490.0 17772.1 18526.7 15538.0 15685.8 15729.2 15791.5 15878.6 13501.0 13624.6 13638.9 13798.8 14288.8 13045.7 13766.2 14596.9 14679.0 14701.5 19:09:02 INFO - PID 17454 | -------- Summary: end -------- 19:09:02 INFO - PID 17454 | 19:09:03 INFO - TEST-INFO | 17454: exit 0 19:09:03 INFO - TEST-OK | dromaeo_css | took 1113583ms 19:09:03 INFO - TEST-START | kraken 19:09:03 INFO - Initialising browser for kraken test... 19:09:03 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:35974/getInfo.html -profile /tmp/tmpJMOMLf/profile 19:09:03 INFO - TEST-INFO | started process 17835 (/builds/slave/test/build/application/firefox/firefox http://localhost:35974/getInfo.html) 19:09:09 INFO - TEST-INFO | 17835: exit 0 19:09:09 INFO - Browser initialized. 19:09:09 INFO - Running cycle 1/1 for kraken test... 19:09:09 INFO - TEST-INFO | started process 18061 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpJMOMLf/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 19:09:10 INFO - PID 18061 | 19:09:10 INFO - PID 18061 | (/builds/slave/test/build/application/firefox/firefox:18114): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 19:09:10 INFO - PID 18061 | 19:09:20 INFO - PID 18061 | RSS: Main: 174321664 19:09:20 INFO - PID 18061 | 19:09:23 INFO - PID 18061 | Cycle 1(1): loaded http://localhost:35974/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:35974/tests/kraken/driver.html?testName=audio-beat-detection) 19:09:24 INFO - PID 18061 | RSS: Main: 179564544 19:09:24 INFO - PID 18061 | 19:09:27 INFO - PID 18061 | Cycle 1(1): loaded http://localhost:35974/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:35974/tests/kraken/driver.html?testName=audio-dft) 19:09:27 INFO - PID 18061 | RSS: Main: 179499008 19:09:27 INFO - PID 18061 | 19:09:30 INFO - PID 18061 | Cycle 1(1): loaded http://localhost:35974/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:35974/tests/kraken/driver.html?testName=audio-fft) 19:09:31 INFO - PID 18061 | RSS: Main: 174710784 19:09:31 INFO - PID 18061 | 19:09:33 INFO - PID 18061 | Cycle 1(1): loaded http://localhost:35974/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:35974/tests/kraken/driver.html?testName=audio-oscillator) 19:09:34 INFO - PID 18061 | RSS: Main: 174505984 19:09:34 INFO - PID 18061 | 19:09:36 INFO - PID 18061 | Cycle 1(1): loaded http://localhost:35974/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:35974/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:09:37 INFO - PID 18061 | RSS: Main: 174829568 19:09:37 INFO - PID 18061 | 19:09:40 INFO - PID 18061 | Cycle 1(1): loaded http://localhost:35974/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:35974/tests/kraken/driver.html?testName=imaging-darkroom) 19:09:40 INFO - PID 18061 | RSS: Main: 177369088 19:09:40 INFO - PID 18061 | 19:09:44 INFO - PID 18061 | Cycle 1(1): loaded http://localhost:35974/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:35974/tests/kraken/driver.html?testName=imaging-desaturate) 19:09:45 INFO - PID 18061 | RSS: Main: 176984064 19:09:45 INFO - PID 18061 | 19:09:47 INFO - PID 18061 | Cycle 1(1): loaded http://localhost:35974/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:35974/tests/kraken/driver.html?testName=json-parse-financial) 19:09:48 INFO - PID 18061 | RSS: Main: 177975296 19:09:48 INFO - PID 18061 | 19:09:50 INFO - PID 18061 | Cycle 1(1): loaded http://localhost:35974/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:35974/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:09:50 INFO - PID 18061 | RSS: Main: 178413568 19:09:50 INFO - PID 18061 | 19:09:52 INFO - PID 18061 | Cycle 1(1): loaded http://localhost:35974/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:35974/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:09:53 INFO - PID 18061 | RSS: Main: 175640576 19:09:53 INFO - PID 18061 | 19:09:55 INFO - PID 18061 | Cycle 1(1): loaded http://localhost:35974/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:35974/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:09:56 INFO - PID 18061 | RSS: Main: 175599616 19:09:56 INFO - PID 18061 | 19:09:59 INFO - PID 18061 | Cycle 1(1): loaded http://localhost:35974/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:35974/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:09:59 INFO - PID 18061 | RSS: Main: 176017408 19:09:59 INFO - PID 18061 | 19:10:02 INFO - PID 18061 | Cycle 1(1): loaded http://localhost:35974/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:35974/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:10:03 INFO - PID 18061 | RSS: Main: 175915008 19:10:03 INFO - PID 18061 | 19:10:05 INFO - PID 18061 | Cycle 1(1): loaded http://localhost:35974/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:35974/tests/kraken/driver.html?testName=ai-astar) 19:10:05 INFO - PID 18061 | RSS: Main: 177033216 19:10:05 INFO - PID 18061 | 19:10:05 INFO - PID 18061 | __start_tp_report 19:10:05 INFO - PID 18061 | _x_x_mozilla_page_load 19:10:05 INFO - PID 18061 | _x_x_mozilla_page_load_details 19:10:05 INFO - PID 18061 | |i|pagename|runs| 19:10:05 INFO - PID 18061 | |0;ai-astar;98;108;102;114;110;110;115;100;107;113 19:10:05 INFO - PID 18061 | |1;audio-beat-detection;151;117;142;175;123;150;151;122;176;127 19:10:05 INFO - PID 18061 | |2;audio-dft;164;164;163;163;168;166;175;166;163;168 19:10:05 INFO - PID 18061 | |3;audio-fft;76;76;80;172;76;75;80;74;169;75 19:10:05 INFO - PID 18061 | |4;audio-oscillator;79;81;136;79;85;79;130;80;78;129 19:10:05 INFO - PID 18061 | |5;imaging-gaussian-blur;117;117;121;117;115;114;114;114;115;114 19:10:05 INFO - PID 18061 | |6;imaging-darkroom;150;151;150;159;153;154;152;151;152;149 19:10:05 INFO - PID 18061 | |7;imaging-desaturate;94;95;94;94;95;95;94;95;94;95 19:10:05 INFO - PID 18061 | |8;json-parse-financial;54;56;55;54;56;63;55;53;54;54 19:10:05 INFO - PID 18061 | |9;json-stringify-tinderbox;54;54;54;54;58;53;60;54;57;54 19:10:05 INFO - PID 18061 | |10;stanford-crypto-aes;78;77;80;87;79;78;77;80;90;78 19:10:05 INFO - PID 18061 | |11;stanford-crypto-ccm;126;139;133;134;132;146;125;140;135;177 19:10:05 INFO - PID 18061 | |12;stanford-crypto-pbkdf2;200;179;189;178;190;177;179;182;178;175 19:10:05 INFO - PID 18061 | |13;stanford-crypto-sha256-iterative;66;65;78;65;67;74;67;67;70;68 19:10:05 INFO - PID 18061 | __end_tp_report 19:10:05 INFO - PID 18061 | __start_cc_report 19:10:05 INFO - PID 18061 | _x_x_mozilla_cycle_collect,963 19:10:05 INFO - PID 18061 | __end_cc_report 19:10:05 INFO - PID 18061 | __startTimestamp1501812605729__endTimestamp 19:10:05 INFO - PID 18061 | 19:10:05 INFO - PID 18061 | ------- Summary: start ------- 19:10:05 INFO - PID 18061 | Number of tests: 14 19:10:05 INFO - PID 18061 | 19:10:05 INFO - PID 18061 | [#0] ai-astar Cycles:10 Average:107.70 Median:111.50 stddev:5.95 (5.3%) stddev-sans-first:5.17 19:10:05 INFO - PID 18061 | Values: 98.0 108.0 102.0 114.0 110.0 110.0 115.0 100.0 107.0 113.0 19:10:05 INFO - PID 18061 | 19:10:05 INFO - PID 18061 | [#1] audio-beat-detection Cycles:10 Average:143.40 Median:150.50 stddev:21.25 (14.1%) stddev-sans-first:22.36 19:10:05 INFO - PID 18061 | Values: 151.0 117.0 142.0 175.0 123.0 150.0 151.0 122.0 176.0 127.0 19:10:05 INFO - PID 18061 | 19:10:05 INFO - PID 18061 | [#2] audio-dft Cycles:10 Average:166.00 Median:166.00 stddev:3.71 (2.2%) stddev-sans-first:3.87 19:10:05 INFO - PID 18061 | Values: 164.0 164.0 163.0 163.0 168.0 166.0 175.0 166.0 163.0 168.0 19:10:05 INFO - PID 18061 | 19:10:05 INFO - PID 18061 | [#3] audio-fft Cycles:10 Average:95.30 Median:76.00 stddev:39.69 (52.2%) stddev-sans-first:41.48 19:10:05 INFO - PID 18061 | Values: 76.0 76.0 80.0 172.0 76.0 75.0 80.0 74.0 169.0 75.0 19:10:05 INFO - PID 18061 | 19:10:05 INFO - PID 18061 | [#4] audio-oscillator Cycles:10 Average:95.60 Median:79.00 stddev:25.03 (31.7%) stddev-sans-first:25.81 19:10:05 INFO - PID 18061 | Values: 79.0 81.0 136.0 79.0 85.0 79.0 130.0 80.0 78.0 129.0 19:10:05 INFO - PID 18061 | 19:10:05 INFO - PID 18061 | [#5] imaging-gaussian-blur Cycles:10 Average:115.80 Median:116.00 stddev:2.25 (1.9%) stddev-sans-first:2.35 19:10:05 INFO - PID 18061 | Values: 117.0 117.0 121.0 117.0 115.0 114.0 114.0 114.0 115.0 114.0 19:10:05 INFO - PID 18061 | 19:10:05 INFO - PID 18061 | [#6] imaging-darkroom Cycles:10 Average:152.10 Median:152.00 stddev:2.85 (1.9%) stddev-sans-first:2.92 19:10:05 INFO - PID 18061 | Values: 150.0 151.0 150.0 159.0 153.0 154.0 152.0 151.0 152.0 149.0 19:10:05 INFO - PID 18061 | 19:10:05 INFO - PID 18061 | [#7] imaging-desaturate Cycles:10 Average:94.50 Median:95.00 stddev:0.53 (0.6%) stddev-sans-first:0.53 19:10:05 INFO - PID 18061 | Values: 94.0 95.0 94.0 94.0 95.0 95.0 94.0 95.0 94.0 95.0 19:10:05 INFO - PID 18061 | 19:10:05 INFO - PID 18061 | [#8] json-parse-financial Cycles:10 Average:55.40 Median:55.00 stddev:2.84 (5.2%) stddev-sans-first:2.96 19:10:05 INFO - PID 18061 | Values: 54.0 56.0 55.0 54.0 56.0 63.0 55.0 53.0 54.0 54.0 19:10:05 INFO - PID 18061 | 19:10:05 INFO - PID 18061 | [#9] json-stringify-tinderbox Cycles:10 Average:55.20 Median:54.00 stddev:2.30 (4.3%) stddev-sans-first:2.40 19:10:05 INFO - PID 18061 | Values: 54.0 54.0 54.0 54.0 58.0 53.0 60.0 54.0 57.0 54.0 19:10:05 INFO - PID 18061 | 19:10:05 INFO - PID 18061 | [#10] stanford-crypto-aes Cycles:10 Average:80.40 Median:79.50 stddev:4.45 (5.6%) stddev-sans-first:4.64 19:10:05 INFO - PID 18061 | Values: 78.0 77.0 80.0 87.0 79.0 78.0 77.0 80.0 90.0 78.0 19:10:05 INFO - PID 18061 | 19:10:05 INFO - PID 18061 | [#11] stanford-crypto-ccm Cycles:10 Average:138.70 Median:137.00 stddev:14.85 (10.8%) stddev-sans-first:15.02 19:10:05 INFO - PID 18061 | Values: 126.0 139.0 133.0 134.0 132.0 146.0 125.0 140.0 135.0 177.0 19:10:05 INFO - PID 18061 | 19:10:05 INFO - PID 18061 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:182.70 Median:180.50 stddev:7.86 (4.4%) stddev-sans-first:5.29 19:10:05 INFO - PID 18061 | Values: 200.0 179.0 189.0 178.0 190.0 177.0 179.0 182.0 178.0 175.0 19:10:05 INFO - PID 18061 | 19:10:05 INFO - PID 18061 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.70 Median:67.50 stddev:4.22 (6.2%) stddev-sans-first:4.36 19:10:05 INFO - PID 18061 | Values: 66.0 65.0 78.0 65.0 67.0 74.0 67.0 67.0 70.0 68.0 19:10:05 INFO - PID 18061 | -------- Summary: end -------- 19:10:05 INFO - PID 18061 | 19:10:06 INFO - PID 18061 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 19:10:06 INFO - TEST-INFO | 18061: exit 0 19:10:06 INFO - TEST-OK | kraken | took 62993ms 19:10:06 INFO - SUITE-END | took 1176s 19:10:06 INFO - Completed test suite (00:19:37) 19:10:06 INFO - javascript benchmark 19:10:06 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8475.504071546286, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3146.905592832216, 3169.857356418961, 3172.2386627209476, 3186.888458903939, 3190.5214217867315, 6066.636001839893, 6087.11736798164, 6107.969460152704, 6111.511079113671, 6130.0498422744495, 6025.246844144482, 6101.237345331833, 6105.908411373833, 6112.174856394386, 6128.387161283869, 5610.438956104398, 5614.803481878135, 5626.043572592659, 5644.971775141119, 5660.9716951415185, 5599.832005039835, 5618.353889302721, 5624.3531993820625, 5627.437256274381, 5692.345380281279, 5635.943640563603, 5684.630499017571, 5714.085746280596, 5720.399358067405, 5727.828165155052, 5481.451854814526, 5626.381098079208, 5638.464345887134, 5704.2299289595785, 5725.828225153252, 5542.224088627595, 5563.833085007415, 5588.608797384185, 5605.103183490663, 5613.46672066153, 5991.850203744898, 6026.578139530235, 6051.304100028509, 6055.878882422327, 6071.696415179224, 6483.935160648381, 6487.740490380379, 6509.3490650935, 6594.637294948776, 6605.70274337656, 6445.742170313182, 6573.079768832367, 6599.0, 6614.0740296358545, 6648.434883034948, 6179.474744646755, 6198.597091189104, 6202.689865506797, 6219.844503887393, 6229.31477537466, 6770.984352347089, 6773.762918297836, 6841.897371539431, 6872.518923675395, 6884.345987131214, 6409.038644203407, 6438.742450301936, 6476.093346931433, 6478.578892371981, 6558.950567909207, 6577.3422657733845, 6581.440577550962, 6581.736730530821, 6583.407493325623, 6612.966935165294, 6488.059231411419, 6500.739970401226, 6583.407493325623, 6588.703508342136, 6605.70274337656, 6468.417842394207, 6485.383888530582, 6509.2839787623325, 6627.768028118992, 6630.171228596426, 5213.582913366921, 5234.528892399626, 5245.318108645928, 5250.766069973553, 5261.500157485033, 4528.049109686936, 4577.198990176706, 4585.72485650862, 4588.357629931812, 4604.7927843247135, 5606.719664016783, 5668.659880407189, 5706.600537962386, 5719.428057194331, 5789.623674461147, 6594.571352862049, 6624.370684784938, 6633.834154146185, 6638.468922486286, 6645.335466453316, 5715.1713001614535, 5720.1991721159075, 5746.454086861741, 5746.712664366765, 5749.396313387034, 5768.913466298009, 5776.451237132465, 5790.623609465456, 5835.620684655484, 5888.175655408247, 6353.36466353361, 6399.488040956712, 6411.519136064814, 6417.197850268716, 6469.4177524022125, 6526.4452521536205, 6537.8692426152165, 6593.736250550116, 6610.537262391779, 6640.83397915056, 6293.748250070129, 6340.048992651139, 6363.140975968185, 6409.359064093554, 6418.19772528434, 6486.805395838132, 6489.610623362613, 6494.6103233806125, 6499.025146227915, 6501.674916254263, 6636.070950066898, 6641.302663220292, 6646.734130634721, 6649.0, 6654.833629159116, 6391.7123729433215, 6394.328595497405, 6424.710888009958, 6428.421442070049, 6430.710618022106, 6368.681565921778, 6449.838754031187, 6467.579607325509, 6539.673016349258, 6550.8362290943105, 6342.619442833445, 6361.109444677842, 6445.2587952386075, 6460.773872914426, 6461.2892581816905, 6305.527085468517, 6418.19772528434, 6521.804345869631, 6558.278589355263, 6584.045313429621, 6232.906506402317, 6254.781082662267, 6259.405356491126, 6321.778737744157, 6349.7142628580905], "value": 6012.696060411984, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [800.1718221640599, 815.9061707903593, 837.3887062444416, 851.0893344121788, 868.6916144768607, 38989.0, 39765.0, 39784.40323395151, 39901.00247493814, 40037.399439008404, 35812.46281305783, 35906.0, 35958.46062309061, 36123.0, 36139.63860361396, 6693.096431981681, 6747.527673062863, 6772.8984065239065, 6825.897611535831, 6834.316568343151, 6705.631190284533, 6778.762743303985, 6824.590524568542, 6827.146606674166, 6827.761028364008, 7537.472376933617, 7735.071791385041, 7758.340541053989, 7767.844643107134, 7777.144514103444, 7649.235076492362, 7712.57580833054, 7738.845223095563, 7739.651715672752, 7744.341730952876, 7500.549967001997, 7638.274363935473, 7693.153753087156, 7712.190220026872, 7725.343345815612, 35841.10397240064, 35870.8206458966, 36226.63773362259, 36260.63739362625, 36261.45607815885, 3554.36021516127, 3566.965579981802, 3586.3544561978833, 3592.550931133608, 3609.4766258892314, 29862.85068574643, 29965.400691986037, 30040.8497957511, 30075.849620751756, 30177.0, 5013.24801279811, 5045.091883460976, 5067.442581316052, 5071.746412679388, 5111.207762796773, 189264.0, 190489.0, 191388.0, 191492.0, 191602.04198979057, 47490.525094749304, 47529.762351188714, 47555.0, 47591.52408475871, 47801.0, 50016.49983500118, 50080.49436539407, 50217.497825022016, 50382.7404314895, 50720.49279507158, 16116.113613751357, 16247.756283655755, 16248.187590620422, 16386.180690965404, 16392.590185245463, 13462.326883655778, 13520.661983450296, 13547.661308467366, 13554.186748795104, 13554.525591604444, 14270.85729142716, 14300.570982870531, 14450.421983120768, 14474.638134046732, 14520.636984075483, 5655.0, 5681.829545113571, 5708.771649133988, 5799.159121927381, 5808.680522571299, 1004.6082028009082, 1010.6058637131524, 1015.1675625986768, 1024.687470321559, 1037.8080055189814, 22386.888065559895, 22486.66270005951, 22592.66111008336, 22594.435139121324, 22822.0, 1107.4739498738036, 1112.732944093428, 1127.177160672697, 1131.8358837968449, 1138.3397629374942, 11953.40232988347, 12030.879691202976, 12031.699207519707, 12036.037117030617, 12083.154179207371, 214248.0, 215674.0, 216561.0, 216921.0, 217293.0, 51090.489095108576, 51493.0, 52636.47363526392, 56562.434375655714, 57142.7142864283, 1511.2821409830242, 1522.1628104542458, 1533.9463118790788, 1551.43372668975, 1552.2781906413572, 26626.201213963614, 27249.31876703098, 27477.72522274752, 27533.449331012867, 27674.584881226394, 37291.81354093212, 37846.24307513779, 38010.809945950095, 38012.04969875775, 38050.0, 205962.0, 206901.0, 207474.0, 207896.0, 209090.0, 34839.0, 35107.0, 35279.0, 35439.468407973385, 35512.0, 40778.79610601928, 41510.0, 42121.36817947672, 42145.36781948212, 42379.7881010593, 31656.942298750506, 31978.84010579932, 33252.50121248232, 34097.48853767146, 35058.47412288767, 33348.16629584183, 33584.32831343408, 33658.158546036546, 33827.492587610715, 33965.15087122842, 35795.82102089473, 36338.63661363353, 36471.45292820663, 36916.63083369132, 36951.44572831463, 31771.841140795073, 31979.200519987186, 32052.35895282128, 32097.679023209468, 32314.83842580772, 23636.164099289483, 24221.0, 24504.264872053867, 24835.379115522257, 25204.873975630006], "value": 17070.04299529175, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [573.8465683975209, 575.6344721102099, 581.8690794571224, 587.744331215921, 602.6986506746626, 22101.557968840632, 22314.21900233492, 22947.311580652586, 22952.88523557382, 23112.42218944523, 27212.0, 27864.721352786466, 27901.302467438323, 27960.0, 28069.719302806967, 6805.489588280886, 6991.615461149635, 7003.684834182449, 7007.0, 7010.929890701104, 6361.109444677749, 6772.966135169318, 6871.175458944908, 6953.37419632233, 6957.930420695804, 6805.489588280874, 6805.693743781517, 6806.251312355637, 6808.353206445405, 6809.829754256134, 6778.05107284978, 6781.796546103625, 6784.796456106275, 6789.3889549940495, 6791.185057793047, 6600.339966003361, 6667.93332066678, 6704.631245281507, 6706.798796036127, 6707.1616047994, 31623.683763162306, 32081.51877721836, 32154.839225803957, 32462.675373246202, 32725.836370818233, 1966.852486063551, 2013.9295124670566, 2014.0533949043927, 2014.7884472130509, 2019.81821636052, 19906.303279385298, 20397.694034589495, 20427.387178384673, 20458.181672733073, 20513.4871628209, 2411.9397015074587, 2478.4051827561257, 2541.491701659657, 2543.8473691578565, 2546.261584140597, 139256.30371848075, 141834.0, 142385.28807356002, 142797.0, 146471.0, 19163.64833717472, 20479.28322508705, 20747.58504829925, 21373.076423121995, 22299.331020069425, 42740.14519709588, 42913.570864291585, 43014.0, 43279.56720432818, 43302.56697432986, 11650.825237621442, 12002.63992080239, 12096.516139354504, 12144.785521447784, 12167.695807604881, 10219.642312519027, 10228.897711022943, 10255.846162307571, 10278.537465814057, 10350.53422595985, 9176.44941303524, 11478.311301321948, 11536.942315288537, 11638.35989020612, 11703.23928944616, 3653.647406549418, 3719.8796685889324, 3750.5181308336823, 4130.442390277274, 4208.011117387411, 951.9095685909828, 979.5787811241235, 985.1084768284713, 1001.8998100190068, 1008.742770593494, 19322.966877625528, 20132.597348052957, 20560.38318850437, 23776.286711398687, 23946.161884333964, 1027.1166796554958, 1064.5422468338688, 1065.8294672852462, 1068.8450174724776, 1070.796548655753, 10872.45637718111, 11297.152713546353, 11466.31202127875, 11529.365884876412, 13391.866081339256, 458308.0, 458407.0, 482476.0, 505008.0, 505209.0, 115421.42289288499, 115575.0, 116928.0, 119552.0, 119942.0, 1264.3070260345405, 1287.6072797796762, 1292.0, 1323.2920387592592, 1325.085690873275, 21471.78528214698, 21531.784682153604, 21871.343859684235, 21873.453163670398, 21936.780632194113, 58003.0, 59302.40697592969, 59461.40538594559, 59805.0, 60353.39646603478, 70048.64975675089, 70849.0, 71365.64317178588, 71682.0, 71803.28196717966, 12063.776335099232, 14257.287135643384, 14522.83817294635, 14918.850811491746, 14959.0, 22630.547389051797, 23030.769692302863, 23064.308070757903, 24297.514049718553, 24470.38824029342, 18621.534461638566, 18773.249070037047, 18839.24643014319, 18879.150438230587, 19040.952747599014, 16486.422975195812, 16591.834081659028, 16730.330786768394, 16895.07077110771, 17026.65946681084, 17166.570835729206, 17946.641067178323, 18078.728819067986, 18159.727604085685, 19124.617507650048, 14753.704925901207, 15131.848681513044, 15186.316615752097, 15189.468368607046, 15299.158546280061, 18445.723314150568, 18501.53746156357, 18799.590030748022, 18828.6234275311, 18997.62004759925], "value": 12966.838159152556, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1357.2942070123536, 1403.277312184225, 1409.0981771666136, 1426.7146570685866, 1433.2117335465498, 13364.465421383145, 13970.511032113878, 13970.720585588282, 13974.371153298112, 14204.92897535511, 14210.78683819741, 14425.855741442583, 14459.0, 14471.131732096082, 14484.04465397171, 2540.098265115885, 2560.9615855762177, 2597.6882774067044, 2607.895684172631, 2608.491344187884, 2720.9319767005886, 2744.1493137127454, 2749.5463248563965, 2760.4755096531694, 2770.8060435769403, 2664.080892092225, 2680.8123431359813, 2687.072959828856, 2689.314224872655, 2709.796765242605, 2560.807939404552, 2592.1705054382605, 2602.8958841646313, 2612.255507180454, 2625.2911713837348, 2612.542805009116, 2628.7634112929927, 2639.577667573198, 2643.2863126955613, 2647.682278126618, 13426.328683565782, 13706.93146534271, 13730.93134534321, 13738.0, 13746.106503077368, 7525.096988361432, 7727.111382191008, 7755.108162561265, 7797.883031754528, 7844.882326765103, 4878.048780487793, 4958.181899986498, 5085.644004919657, 5088.1350170471105, 5202.739863006835, 1822.5443639090226, 1834.110456428631, 1841.8618603604784, 1852.148011914521, 1873.5409824592925, 22389.44026399337, 22685.886570567043, 22772.772272277398, 22776.77223227763, 22927.197548085904, 6805.557638753465, 7121.501494895411, 7153.749618763319, 7170.211276759553, 7179.066721326299, 15504.0, 15650.45223417175, 15663.37346506127, 15666.843331566766, 15684.137372444626, 7080.150381954199, 7571.46999710026, 7607.65765540552, 7608.695652173851, 7640.656170472342, 5677.517411020027, 5891.145783861316, 6113.32753397126, 6980.441564674814, 7024.719011239596, 5053.84838454847, 5196.45437229093, 5258.631895767336, 5265.684058956398, 5294.7352632369, 1493.357856121878, 1534.608674787922, 1546.2423412528005, 1552.604085958096, 1557.1591340676134, 876.4565969099071, 903.6927444668844, 915.3638221436171, 925.9629614815467, 929.0941332201049, 7319.926800731924, 7331.560106393634, 7353.558786472829, 7380.114386273574, 7474.289942455458, 713.2974020589714, 729.3472342253654, 733.9926600734032, 735.8491509240592, 738.3281214095148, 4392.494863090722, 4434.84478043267, 4880.877978050577, 5003.54968052877, 5259.368875734936, 17531.211095500734, 18130.18414171339, 18462.81537184638, 18495.53761155955, 18588.349407770664, 18041.7293740594, 18246.908765456086, 18513.259469621335, 18607.534811629546, 18623.81376186248, 10525.473726313807, 10688.0, 10688.839667404995, 10723.249372543847, 10812.0, 7846.843063138591, 7890.210978902064, 7923.286904178652, 8048.315893148913, 8050.758477245692, 16333.693304535846, 16435.83564164343, 16482.83517164861, 16793.0, 16900.154992250093, 16900.830991690418, 17098.487045388658, 17222.138893055548, 17255.56861078483, 17350.305987760854, 11508.59719909799, 11705.531778728755, 11712.824307635563, 11758.70603234926, 11773.352465614473, 12578.433970471533, 12990.805137923127, 13026.934865325613, 13194.868051319749, 13206.207627542379, 10776.784464310827, 11137.109031277316, 11137.944310278397, 11224.887751122384, 11367.033802126913, 10433.478326083514, 10567.0, 10597.09924656382, 10780.407077610493, 10791.730206744895, 9403.247740180906, 9565.473898935346, 9575.616975320909, 9576.616935322509, 9595.520223988913, 6997.8250543734775, 7021.332973367522, 7102.609356485443, 7198.064251647253, 7233.855322893618, 7131.60675478986, 7219.314165154302, 7253.492255542062, 7270.781876543712, 7370.889436658562], "value": 6296.881500968717, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [495.8051096856576, 499.36603920803657, 511.5166167971264, 519.2807960974052, 525.3118414876511, 6131.509479241661, 6132.4480796728285, 6141.539384546155, 6165.907511387321, 6208.361446385291, 5843.386444423338, 6011.969940150293, 6013.308469526006, 6017.608855424394, 6060.666663333526, 4825.396825396825, 4830.758462076882, 4849.127157111719, 4906.337644418011, 4933.33399991002, 4831.212451392972, 4881.4630390657, 4888.266759985995, 4913.877153071184, 4925.38432695913, 4629.213033784248, 4853.490383509734, 4873.512648735116, 4889.633277504184, 4901.436334821487, 4827.372441582572, 4843.370361852954, 4856.660033797635, 4870.902581948359, 4880.365552478191, 4373.387725718402, 4383.802728877176, 4396.318570621559, 4422.535633758441, 4423.0711550574415, 5871.768727883377, 5969.791057312973, 5970.253718285215, 5970.701464926737, 5974.581779275452, 5638.859028524279, 5650.773969041234, 5676.489115979582, 5690.516306113986, 5696.715164241771, 6409.070684750685, 6419.903701444482, 6427.196600424947, 6471.546991710582, 6537.529055962418, 4808.927866082012, 4817.108834865561, 4823.469418363977, 4849.248366503198, 4861.805527778884, 6684.064231007663, 6828.146481689789, 6869.175698916062, 6876.243613202544, 6889.99999999995, 4939.975300123477, 4951.579115775199, 4961.627877909171, 4970.105381031449, 4994.275830004629, 4991.825286115041, 5033.345665063518, 5034.471380504994, 5054.267131265946, 5062.493750624908, 5626.465485778845, 5627.943720562824, 5649.152627105967, 5653.434656534314, 5680.176374425685, 6348.508100596357, 6386.0, 6503.414692677682, 6546.0, 6555.3772391622715, 1665.7418100194488, 1699.2608215426328, 1715.5968347438343, 1735.496705955259, 1745.3803899615707, 1148.5118824499555, 1422.8332767130853, 1423.686788906439, 1429.3425024488645, 1449.5144126717669, 5612.410696876851, 5641.887162256732, 5645.0, 5674.886502269931, 5678.630888992202, 6508.869822603521, 6547.279799222082, 6548.410643042053, 6564.0, 6614.0, 5512.944870551323, 5638.520725738276, 5736.340320863154, 5738.885222295531, 5758.913616295759, 3514.929701406009, 3525.383057964846, 3534.770239934396, 3546.3261980224192, 3565.3760591896316, 6456.7740129095255, 6470.870582588322, 6509.739610415625, 6515.087887695727, 6529.640869752209, 6789.626570538668, 6868.519203655696, 6883.728369638007, 6924.515283930177, 6932.584044957218, 6479.8056058318325, 6502.187226596676, 6509.0, 6550.279469258282, 6561.573497722633, 6529.67351632426, 6621.768238111643, 6652.871081253952, 6666.166729158856, 6716.899246511404, 6513.381228783447, 6549.0, 6561.278259391559, 6593.505487088583, 6652.634105124264, 6421.7110230038825, 6457.257415397289, 6549.24683661385, 6559.573627714183, 6604.867902641824, 4882.707037577757, 4928.728919909439, 4937.666829955963, 4961.08219979305, 4979.203327467732, 6525.3148419417175, 6577.473802095917, 6659.4006539411685, 6667.399934005963, 6710.563813352116, 6371.872562548816, 6402.807915762534, 6414.326495717883, 6457.773977910751, 6479.967600161969, 6421.0, 6433.388828061326, 6450.612963222222, 6468.0, 6469.644169570719, 6129.387061293835, 6381.074744162163, 6394.744210231539, 6399.232092148972, 6407.935920640734, 6031.712716569568, 6345.432477385883, 6418.743250270124, 6433.5496515243485, 6443.065755465337, 6145.815625531062, 6179.783707570394, 6192.59748116371, 6209.192804935329, 6282.0], "value": 5003.253234674508, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [909.386164339071, 914.3279689428269, 934.7195841247628, 937.4328531238605, 941.670415354626, 16880.746788798177, 17809.376671816488, 19008.809911900877, 19054.237830486763, 19059.61880762384, 14743.410263589443, 16229.594260143502, 16352.591185220375, 16391.01653900767, 16420.096894670787, 1039.6517166749147, 1055.0662663542744, 1056.1181413519682, 1058.0424715632364, 1058.8994045565698, 1045.2213101239588, 1046.3355769086609, 1046.6650671785033, 1046.8168070587656, 1063.2450959818539, 783.4750717019582, 802.542550746075, 1071.1216802222182, 1112.3770688414513, 1124.083871644611, 1090.3675867996542, 1091.7980173667897, 1091.8307662312316, 1093.3112139352236, 1107.3023994883213, 797.9760607181736, 799.4923223752901, 799.6161842315663, 802.1417083720421, 817.3012074676185, 18727.906360468245, 18975.335863244858, 19108.713369299472, 19158.71261931072, 19679.31122410723, 21245.575088498143, 21295.893520532452, 21589.352319430443, 21632.026558804893, 21863.453413664625, 15770.448034318744, 16347.509574712778, 16424.42514511998, 16540.338386464526, 16571.83428165715, 3444.9311013779834, 3505.193805424741, 3505.8422370993367, 3508.6666766657127, 3508.701760350373, 115665.0, 116198.0, 116535.0, 116957.41521292424, 120529.39735301267, 13493.932530337286, 13932.651683707785, 13953.999999999796, 13968.161910285415, 13979.231142287068, 23392.415189620395, 29428.264293392407, 30663.540046899314, 30702.232444189074, 30859.84570077135, 8571.871421928676, 8593.269572086318, 8705.303575713928, 8827.293816494664, 8829.16122968317, 4108.4864391951005, 4158.7296825706835, 4184.539700632928, 4200.138971510833, 4207.705460617727, 5186.81846135391, 5188.092083885305, 5236.162214045735, 5310.9734451327495, 5324.893502129936, 1081.7403823082404, 1089.5696200000955, 1095.611058074388, 1107.7839821234943, 1109.1237922041657, 675.9594424334457, 680.5270337115757, 702.4169938950658, 717.3938022371088, 718.4288490649946, 10776.407297598707, 11686.88313116875, 11702.94148529269, 11718.589849355232, 11812.940935295268, 692.2039654397483, 693.2166651683666, 701.1446035836271, 702.0557350363753, 707.8973548835493, 5315.734213289397, 5364.30264065669, 5384.192371144281, 5483.396826349099, 5536.944630553723, 95408.0, 96108.51945740226, 96657.0, 97328.0, 101014.49492752636, 83271.0, 86636.0, 86790.56604716936, 86802.0, 87207.0, 74117.0, 75303.62348188333, 79336.20663793404, 80164.59917700374, 80448.19551804524, 11868.228565143238, 12193.634190974284, 12422.503099876265, 12617.747645046864, 12789.360531973178, 34915.4762678555, 35113.473297901066, 35667.82166089153, 36991.445128323634, 37342.0, 46049.76975115237, 46889.0, 65397.0, 68045.0, 68961.0, 19026.619467610846, 19167.52081197981, 19252.326168584033, 19319.710204347226, 19364.225430982602, 18738.344157954405, 18930.148143333307, 19701.0, 21416.0, 21810.672839907733, 18057.458276251735, 18163.545911351794, 18219.45341639753, 18378.081095945417, 18723.719144212577, 16275.51173464798, 16313.184340782676, 16490.0, 17772.111394430485, 18526.722099168794, 15538.0, 15685.764713529077, 15729.213539323217, 15791.526254212393, 15878.60303492422, 13501.0, 13624.591262262147, 13638.93180534091, 13798.792193249434, 14288.785668214778, 13045.739085218052, 13766.24285664258, 14596.927015364856, 14679.0, 14701.485448009269], "value": 8839.92637434353, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1551.5000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 108.0, 102.0, 114.0, 110.0, 110.0, 115.0, 100.0, 107.0, 113.0], "value": 107.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 117.0, 142.0, 175.0, 123.0, 150.0, 151.0, 122.0, 176.0, 127.0], "value": 143.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 164.0, 163.0, 163.0, 168.0, 166.0, 175.0, 166.0, 163.0, 168.0], "value": 166.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 80.0, 172.0, 76.0, 75.0, 80.0, 74.0, 169.0, 75.0], "value": 95.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 81.0, 136.0, 79.0, 85.0, 79.0, 130.0, 80.0, 78.0, 129.0], "value": 95.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 121.0, 117.0, 115.0, 114.0, 114.0, 114.0, 115.0, 114.0], "value": 115.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 151.0, 150.0, 159.0, 153.0, 154.0, 152.0, 151.0, 152.0, 149.0], "value": 152.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 94.0, 94.0, 95.0, 95.0, 94.0, 95.0, 94.0, 95.0], "value": 94.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 56.0, 55.0, 54.0, 56.0, 63.0, 55.0, 53.0, 54.0, 54.0], "value": 55.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 54.0, 54.0, 54.0, 58.0, 53.0, 60.0, 54.0, 57.0, 54.0], "value": 55.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 77.0, 80.0, 87.0, 79.0, 78.0, 77.0, 80.0, 90.0, 78.0], "value": 80.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [126.0, 139.0, 133.0, 134.0, 132.0, 146.0, 125.0, 140.0, 135.0, 177.0], "value": 138.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [200.0, 179.0, 189.0, 178.0, 190.0, 177.0, 179.0, 182.0, 178.0, 175.0], "value": 182.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 78.0, 65.0, 67.0, 74.0, 67.0, 67.0, 70.0, 68.0], "value": 68.7, "unit": "score"}]}]} 19:10:06 INFO - Return code: 0 19:10:06 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:10:07 INFO - # TBPL SUCCESS # 19:10:07 INFO - Running post-action listener: _package_coverage_data 19:10:07 INFO - Running post-action listener: _resource_record_post_action 19:10:07 INFO - [mozharness: 2017-08-04 02:10:07.003884Z] Finished run-tests step (success) 19:10:07 INFO - Running post-run listener: _resource_record_post_run 19:10:07 INFO - Total resource usage - Wall time: 1196s; CPU: 13.0%; Read bytes: 0; Write bytes: 434122752; Read time: 0; Write time: 296612 19:10:07 INFO - TinderboxPrint: CPU usage
13.2% 19:10:07 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 19:10:07 INFO - TinderboxPrint: I/O write bytes / time
434,122,752 / 296,612 19:10:07 INFO - TinderboxPrint: CPU idle
8,278.1 (86.8%) 19:10:07 INFO - TinderboxPrint: CPU user
1,207.3 (12.7%) 19:10:07 INFO - TinderboxPrint: Swap in / out
0 / 0 19:10:07 INFO - install - Wall time: 19s; CPU: 13.0%; Read bytes: 0; Write bytes: 106840064; Read time: 0; Write time: 69748 19:10:07 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 19:10:07 INFO - run-tests - Wall time: 1178s; CPU: 13.0%; Read bytes: 0; Write bytes: 325431296; Read time: 0; Write time: 226796 19:10:07 INFO - Running post-run listener: _upload_blobber_files 19:10:07 INFO - Blob upload gear active. 19:10:07 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:10:07 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 19:10:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 19:10:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 19:10:08 INFO - (blobuploader) - INFO - Open directory for files ... 19:10:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 19:10:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:10:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:10:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 19:10:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:10:08 INFO - (blobuploader) - INFO - Done attempting. 19:10:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 19:10:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:10:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:10:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 19:10:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:10:09 INFO - (blobuploader) - INFO - Done attempting. 19:10:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:10:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:10:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:10:16 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:10:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:10:16 INFO - (blobuploader) - INFO - Done attempting. 19:10:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 19:10:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:10:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:10:17 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 19:10:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:10:17 INFO - (blobuploader) - INFO - Done attempting. 19:10:17 INFO - (blobuploader) - INFO - Iteration through files over. 19:10:17 INFO - Return code: 0 19:10:17 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:10:17 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:10:17 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/98b06d912fc1c4d365a846b4466c6378ff730c713dce5a033758555814f5eaeeea35813c5b1abaa7a432aae14fe5c8d902c97c5ca5922e49a684559b3b247634", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/60ec60d6308a8cadeb9e5e78ecb1686b1ab8d0d0254bb014276da87989c57b9b604beffc10fe78096fc0e232ec6933773acb7f4e7cf36c004a9565cef4e07181", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e5a53edc3eeb8b14c4491e3e22e3cf5f5e4c3d38aa710b530f519f9e30382c7ecd774d99d5a2160d789974cecd108b94363e366b7979bfc1a7a82d2506f21792", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cf1ebab71a4f0f4850727f335bfde86acfbc12cd0f33a5d42a6a365f7e021eb947875d5d47b3d2f0f9c4529fdfecd634f7bc7f608246b1a055aeffcaa5616802"} 19:10:17 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:10:17 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:10:17 INFO - Contents: 19:10:17 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/98b06d912fc1c4d365a846b4466c6378ff730c713dce5a033758555814f5eaeeea35813c5b1abaa7a432aae14fe5c8d902c97c5ca5922e49a684559b3b247634", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/60ec60d6308a8cadeb9e5e78ecb1686b1ab8d0d0254bb014276da87989c57b9b604beffc10fe78096fc0e232ec6933773acb7f4e7cf36c004a9565cef4e07181", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e5a53edc3eeb8b14c4491e3e22e3cf5f5e4c3d38aa710b530f519f9e30382c7ecd774d99d5a2160d789974cecd108b94363e366b7979bfc1a7a82d2506f21792", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cf1ebab71a4f0f4850727f335bfde86acfbc12cd0f33a5d42a6a365f7e021eb947875d5d47b3d2f0f9c4529fdfecd634f7bc7f608246b1a055aeffcaa5616802"} 19:10:17 INFO - Running post-run listener: copy_logs_to_upload_dir 19:10:17 INFO - Copying logs to upload dir... 19:10:17 INFO - mkdir: /builds/slave/test/build/upload/logs 19:10:17 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1257.153487 ========= master_lag: 0.11 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 57 secs) (at 2017-08-03 19:10:16.734054) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-03 19:10:16.738155) ========= 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=41bf3e9aed84707956354da8000001c5-1501808513.100895-1942670171 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/98b06d912fc1c4d365a846b4466c6378ff730c713dce5a033758555814f5eaeeea35813c5b1abaa7a432aae14fe5c8d902c97c5ca5922e49a684559b3b247634", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/60ec60d6308a8cadeb9e5e78ecb1686b1ab8d0d0254bb014276da87989c57b9b604beffc10fe78096fc0e232ec6933773acb7f4e7cf36c004a9565cef4e07181", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e5a53edc3eeb8b14c4491e3e22e3cf5f5e4c3d38aa710b530f519f9e30382c7ecd774d99d5a2160d789974cecd108b94363e366b7979bfc1a7a82d2506f21792", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cf1ebab71a4f0f4850727f335bfde86acfbc12cd0f33a5d42a6a365f7e021eb947875d5d47b3d2f0f9c4529fdfecd634f7bc7f608246b1a055aeffcaa5616802"} build_url:https://queue.taskcluster.net/v1/task/H5B5IEnwSEm76TFIhWTsdQ/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011358 build_url: 'https://queue.taskcluster.net/v1/task/H5B5IEnwSEm76TFIhWTsdQ/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/98b06d912fc1c4d365a846b4466c6378ff730c713dce5a033758555814f5eaeeea35813c5b1abaa7a432aae14fe5c8d902c97c5ca5922e49a684559b3b247634", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/60ec60d6308a8cadeb9e5e78ecb1686b1ab8d0d0254bb014276da87989c57b9b604beffc10fe78096fc0e232ec6933773acb7f4e7cf36c004a9565cef4e07181", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e5a53edc3eeb8b14c4491e3e22e3cf5f5e4c3d38aa710b530f519f9e30382c7ecd774d99d5a2160d789974cecd108b94363e366b7979bfc1a7a82d2506f21792", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cf1ebab71a4f0f4850727f335bfde86acfbc12cd0f33a5d42a6a365f7e021eb947875d5d47b3d2f0f9c4529fdfecd634f7bc7f608246b1a055aeffcaa5616802"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-03 19:10:16.781385) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-03 19:10:16.781830) ========= 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=41bf3e9aed84707956354da8000001c5-1501808513.100895-1942670171 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004357 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-03 19:10:16.862772) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-03 19:10:16.863107) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-03 19:10:16.863440) ========= ========= Total master_lag: 0.29 =========