builder: mozilla-central_ubuntu64_hw_styloseq_test-dromaeojs-e10s slave: talos-linux64-ix-007 starttime: 1502414360.41 results: success (0) revision: d0068f9051be88eb4c97e28ec0a4f101ca4ff147 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-08-10 18:19:20.413884) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-08-10 18:19:20.415172) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-10 18:19:20.415572) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-10 18:19:20.433942) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-10 18:19:20.434679) ========= 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=923d835db2ba3686ebe126b700000192-1502413822.384271-334355300 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-08-10 18:19:20-- 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% 26.5M=0s 2017-08-10 18:19:21 (26.5 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.127793 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-10 18:19:20.579283) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-10 18:19:20.579625) ========= 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=923d835db2ba3686ebe126b700000192-1502413822.384271-334355300 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016836 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-10 18:19:20.631682) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 11 secs) (at 2017-08-10 18:19:20.632096) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev d0068f9051be88eb4c97e28ec0a4f101ca4ff147 --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 d0068f9051be88eb4c97e28ec0a4f101ca4ff147 --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=923d835db2ba3686ebe126b700000192-1502413822.384271-334355300 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-08-10 18:19:21,123 truncating revision to first 12 chars 2017-08-10 18:19:21,123 Setting DEBUG logging. 2017-08-10 18:19:21,124 attempt 1/10 2017-08-10 18:19:21,124 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/d0068f9051be?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-08-10 18:19:21,755 sleeping for 10.00s (attempt 1/10) 2017-08-10 18:19:31,765 attempt 2/10 2017-08-10 18:19:31,765 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/d0068f9051be?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-08-10 18:19:32,222 unpacking tar archive at: mozilla-central-d0068f9051be/testing/mozharness/ program finished with exit code 0 elapsedTime=11.416295 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 11 secs) (at 2017-08-10 18:19:32.061803) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-10 18:19:32.062334) ========= script_repo_revision: d0068f9051be88eb4c97e28ec0a4f101ca4ff147 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-10 18:19:32.062876) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-10 18:19:32.063211) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-10 18:19:32.072559) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2017-08-10 18:19:32.072879) ========= /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=923d835db2ba3686ebe126b700000192-1502413822.384271-334355300 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:19:32 INFO - MultiFileLogger online at 20170810 18:19:32 in /builds/slave/test 18:19:32 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:19:32 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:19:32 INFO - {'append_to_log': False, 18:19:32 INFO - 'base_work_dir': '/builds/slave/test', 18:19:32 INFO - 'blob_upload_branch': 'Firefox', 18:19:32 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:19:32 INFO - 'branch': 'Firefox', 18:19:32 INFO - 'buildbot_json_path': 'buildprops.json', 18:19:32 INFO - 'code_coverage': False, 18:19:32 INFO - 'config_files': ('talos/linux_config.py',), 18:19:32 INFO - 'default_actions': ('clobber', 18:19:32 INFO - 'read-buildbot-config', 18:19:32 INFO - 'download-and-extract', 18:19:32 INFO - 'populate-webroot', 18:19:32 INFO - 'create-virtualenv', 18:19:32 INFO - 'install', 18:19:32 INFO - 'setup-mitmproxy', 18:19:32 INFO - 'run-tests'), 18:19:32 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:19:32 INFO - 'disable_ccov_upload': False, 18:19:32 INFO - 'download_minidump_stackwalk': True, 18:19:32 INFO - 'download_symbols': 'ondemand', 18:19:32 INFO - 'enable_stylo': False, 18:19:32 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:19:32 INFO - 'tooltool.py': '/tools/tooltool.py', 18:19:32 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:19:32 INFO - '/tools/misc-python/virtualenv.py')}, 18:19:32 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:19:32 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:19:32 INFO - 'gecko_profile': False, 18:19:32 INFO - 'gecko_profile_interval': 0, 18:19:32 INFO - 'installer_path': 'installer.exe', 18:19:32 INFO - 'log_level': 'info', 18:19:32 INFO - 'log_name': 'talos', 18:19:32 INFO - 'log_to_console': True, 18:19:32 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:19:32 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:19:32 INFO - 'opt_config_files': (), 18:19:32 INFO - 'pip_index': False, 18:19:32 INFO - 'suite': 'dromaeojs-e10s', 18:19:32 INFO - 'system_bits': '32', 18:19:32 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:19:32 INFO - 'title': 'talos-linux64-ix-007', 18:19:32 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:19:32 INFO - 'use_talos_json': True, 18:19:32 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:19:32 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:19:32 INFO - 'work_dir': 'build'} 18:19:32 INFO - [mozharness: 2017-08-11 01:19:32.671563Z] Running clobber step. 18:19:32 INFO - Running pre-action listener: _resource_record_pre_action 18:19:32 INFO - Running main action method: clobber 18:19:32 INFO - rmtree: /builds/slave/test/build 18:19:32 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:19:34 INFO - Running post-action listener: _resource_record_post_action 18:19:34 INFO - [mozharness: 2017-08-11 01:19:34.211481Z] Finished clobber step (success) 18:19:34 INFO - [mozharness: 2017-08-11 01:19:34.211582Z] Running read-buildbot-config step. 18:19:34 INFO - Running pre-action listener: _resource_record_pre_action 18:19:34 INFO - Running main action method: read_buildbot_config 18:19:34 INFO - Using buildbot properties: 18:19:34 INFO - { 18:19:34 INFO - "project": "", 18:19:34 INFO - "product": "firefox", 18:19:34 INFO - "who": "kwierso@gmail.com", 18:19:34 INFO - "installer_path": "public/build/target.tar.bz2", 18:19:34 INFO - "builddir": "mozilla-central_ubuntu64_hw_styloseq_test-dromaeojs-e10s", 18:19:34 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 18:19:34 INFO - "buildername": "Ubuntu HW 12.04 x64 stylo-sequential mozilla-central talos dromaeojs-e10s", 18:19:34 INFO - "stage_platform": "linux64-stylosequential", 18:19:34 INFO - "basedir": "/builds/slave/test", 18:19:34 INFO - "buildnumber": 178, 18:19:34 INFO - "platform": "ubuntu64_hw_styloseq", 18:19:34 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 18:19:34 INFO - "slavebuilddir": "test", 18:19:34 INFO - "taskId": "FcewwJT0R_S1piOM30GwOw", 18:19:34 INFO - "branch": "mozilla-central", 18:19:34 INFO - "script_repo_revision": "production", 18:19:34 INFO - "revision": "d0068f9051be88eb4c97e28ec0a4f101ca4ff147", 18:19:34 INFO - "slavename": "talos-linux64-ix-007", 18:19:34 INFO - "repo_path": "mozilla-central" 18:19:34 INFO - } 18:19:34 INFO - Finding installer, test and symbols from parent task. 18:19:34 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FcewwJT0R_S1piOM30GwOw'}, attempt #1 18:19:34 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FcewwJT0R_S1piOM30GwOw'}, attempt #1 18:19:35 INFO - Task dependencies: dTnd9BLdQ-u7ChXvVPHXNw 18:19:35 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dTnd9BLdQ-u7ChXvVPHXNw'}, attempt #1 18:19:35 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/dTnd9BLdQ-u7ChXvVPHXNw/artifacts/public/build/target.tar.bz2 18:19:35 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/dTnd9BLdQ-u7ChXvVPHXNw/artifacts/public/build/target.test_packages.json 18:19:35 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/dTnd9BLdQ-u7ChXvVPHXNw/artifacts/public/build/target.crashreporter-symbols.zip 18:19:35 INFO - Running post-action listener: _resource_record_post_action 18:19:35 INFO - [mozharness: 2017-08-11 01:19:35.574004Z] Finished read-buildbot-config step (success) 18:19:35 INFO - [mozharness: 2017-08-11 01:19:35.574226Z] Running download-and-extract step. 18:19:35 INFO - Running pre-action listener: _resource_record_pre_action 18:19:35 INFO - Running main action method: download_and_extract 18:19:35 INFO - mkdir: /builds/slave/test/build/tests 18:19:35 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:19:35 INFO - trying https://queue.taskcluster.net/v1/task/dTnd9BLdQ-u7ChXvVPHXNw/artifacts/public/build/target.test_packages.json 18:19:35 INFO - Downloading https://queue.taskcluster.net/v1/task/dTnd9BLdQ-u7ChXvVPHXNw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 18:19:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dTnd9BLdQ-u7ChXvVPHXNw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 18:19:36 INFO - Downloaded 989 bytes. 18:19:36 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 18:19:36 INFO - Using the following test package requirements: 18:19:36 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 18:19:36 INFO - u'common': [u'target.common.tests.zip'], 18:19:36 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 18:19:36 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 18:19:36 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 18:19:36 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 18:19:36 INFO - u'mozbase': [u'target.common.tests.zip'], 18:19:36 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 18:19:36 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 18:19:36 INFO - u'web-platform': [u'target.common.tests.zip', 18:19:36 INFO - u'target.web-platform.tests.tar.gz'], 18:19:36 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 18:19:36 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 18:19:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dTnd9BLdQ-u7ChXvVPHXNw/artifacts/public/build/target.common.tests.zip 18:19:36 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dTnd9BLdQ-u7ChXvVPHXNw/artifacts/public/build/target.common.tests.zip'}, attempt #1 18:19:36 INFO - Fetch https://queue.taskcluster.net/v1/task/dTnd9BLdQ-u7ChXvVPHXNw/artifacts/public/build/target.common.tests.zip into memory 18:19:38 INFO - Content-Length response header: 54343543 18:19:38 INFO - Bytes received: 54343543 18:19:49 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 18:19:49 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dTnd9BLdQ-u7ChXvVPHXNw/artifacts/public/build/target.common.tests.zip 18:19:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dTnd9BLdQ-u7ChXvVPHXNw/artifacts/public/build/target.common.tests.zip'}, attempt #1 18:19:49 INFO - Fetch https://queue.taskcluster.net/v1/task/dTnd9BLdQ-u7ChXvVPHXNw/artifacts/public/build/target.common.tests.zip into memory 18:19:50 INFO - Content-Length response header: 54343543 18:19:50 INFO - Bytes received: 54343543 18:20:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dTnd9BLdQ-u7ChXvVPHXNw/artifacts/public/build/target.talos.tests.zip 18:20:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dTnd9BLdQ-u7ChXvVPHXNw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 18:20:04 INFO - Fetch https://queue.taskcluster.net/v1/task/dTnd9BLdQ-u7ChXvVPHXNw/artifacts/public/build/target.talos.tests.zip into memory 18:20:04 INFO - Content-Length response header: 14496008 18:20:04 INFO - Bytes received: 14496008 18:20:05 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:20:05 INFO - trying https://queue.taskcluster.net/v1/task/dTnd9BLdQ-u7ChXvVPHXNw/artifacts/public/build/target.tar.bz2 18:20:05 INFO - Downloading https://queue.taskcluster.net/v1/task/dTnd9BLdQ-u7ChXvVPHXNw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 18:20:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dTnd9BLdQ-u7ChXvVPHXNw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 18:20:08 INFO - Downloaded 61958000 bytes. 18:20:08 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/dTnd9BLdQ-u7ChXvVPHXNw/artifacts/public/build/target.tar.bz2 18:20:08 INFO - mkdir: /builds/slave/test/properties 18:20:08 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:20:08 INFO - Writing to file /builds/slave/test/properties/build_url 18:20:08 INFO - Contents: 18:20:08 INFO - build_url:https://queue.taskcluster.net/v1/task/dTnd9BLdQ-u7ChXvVPHXNw/artifacts/public/build/target.tar.bz2 18:20:08 INFO - Running post-action listener: _resource_record_post_action 18:20:08 INFO - Running post-action listener: set_extra_try_arguments 18:20:08 INFO - [mozharness: 2017-08-11 01:20:08.360802Z] Finished download-and-extract step (success) 18:20:08 INFO - [mozharness: 2017-08-11 01:20:08.360902Z] Running populate-webroot step. 18:20:08 INFO - Running pre-action listener: _resource_record_pre_action 18:20:08 INFO - Running main action method: populate_webroot 18:20:08 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:20:08 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:20:08 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 18:20:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:20:08 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 18:20:08 INFO - 'tests': ['damp', 'tps']}, 18:20:08 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:20:08 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 18:20:08 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 18:20:08 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 18:20:08 INFO - 'other-e10s': {'tests': ['a11yr', 18:20:08 INFO - 'ts_paint', 18:20:08 INFO - 'tpaint', 18:20:08 INFO - 'sessionrestore', 18:20:08 INFO - 'sessionrestore_many_windows', 18:20:08 INFO - 'sessionrestore_no_auto_restore', 18:20:08 INFO - 'tabpaint']}, 18:20:08 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 18:20:08 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 18:20:08 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:20:08 INFO - 'tsvgr_opacity', 18:20:08 INFO - 'tart', 18:20:08 INFO - 'tscrollx', 18:20:08 INFO - 'tsvg_static']}, 18:20:08 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 18:20:08 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:20:08 INFO - 'talos_options': ['--mitmproxy', 18:20:08 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:20:08 INFO - '--firstNonBlankPaint'], 18:20:08 INFO - 'tests': ['tp6_google', 18:20:08 INFO - 'tp6_youtube', 18:20:08 INFO - 'tp6_amazon', 18:20:08 INFO - 'tp6_facebook']}, 18:20:08 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:20:08 INFO - 'talos_options': ['--stylo', 18:20:08 INFO - '--mitmproxy', 18:20:08 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:20:08 INFO - '--firstNonBlankPaint'], 18:20:08 INFO - 'tests': ['tp6_google', 18:20:08 INFO - 'tp6_youtube', 18:20:08 INFO - 'tp6_amazon', 18:20:08 INFO - 'tp6_facebook']}, 18:20:08 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:20:08 INFO - 'talos_options': ['--stylo', 18:20:08 INFO - '--stylo-threads=1', 18:20:08 INFO - '--mitmproxy', 18:20:08 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:20:08 INFO - '--firstNonBlankPaint'], 18:20:08 INFO - 'tests': ['tp6_google', 18:20:08 INFO - 'tp6_youtube', 18:20:08 INFO - 'tp6_amazon', 18:20:08 INFO - 'tp6_facebook']}, 18:20:08 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 18:20:08 INFO - 'talos_options': ['--xperf_path', 18:20:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:20:08 INFO - 'tests': ['tp5n']}}} 18:20:08 INFO - Running post-action listener: _resource_record_post_action 18:20:08 INFO - [mozharness: 2017-08-11 01:20:08.369458Z] Finished populate-webroot step (success) 18:20:08 INFO - [mozharness: 2017-08-11 01:20:08.369549Z] Running create-virtualenv step. 18:20:08 INFO - Running pre-action listener: _resource_record_pre_action 18:20:08 INFO - Running main action method: create_virtualenv 18:20:08 INFO - Creating virtualenv /builds/slave/test/build/venv 18:20:08 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:20:08 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:20:08 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 18:20:08 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:20:08 INFO - Using real prefix '/usr' 18:20:08 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:20:08 INFO - Installing distribute.............................................................................................................................................................................................done. 18:20:10 INFO - Installing pip.................done. 18:20:10 INFO - Return code: 0 18:20:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:20:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:20:11 INFO - Reading from file tmpfile_stdout 18:20:11 INFO - Output received: 18:20:11 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:20:11 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:20:11 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:20:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:20:11 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:20:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:20:11 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 0x10861f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1252a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13f3b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13f6f00>, '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 0x13f1ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13f2050>, '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': '923d835db2ba3686ebe126b700000192-1502413822.384271-334355300', '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:20:11 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:20:11 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:20:11 INFO - Using env: {'DISPLAY': ':0', 18:20:11 INFO - 'HOME': '/home/cltbld', 18:20:11 INFO - 'LANG': 'en_US.UTF-8', 18:20:11 INFO - 'LANGUAGE': 'en_US:en', 18:20:11 INFO - 'LOGNAME': 'cltbld', 18:20:11 INFO - 'MAIL': '/var/mail/cltbld', 18:20:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:20:11 INFO - 'MOZ_NO_REMOTE': '1', 18:20:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:20:11 INFO - 'NO_EM_RESTART': '1', 18:20:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:20:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:20:11 INFO - 'PWD': '/builds/slave/test', 18:20:11 INFO - 'SHELL': '/bin/bash', 18:20:11 INFO - 'SHLVL': '1', 18:20:11 INFO - 'TERM': 'linux', 18:20:11 INFO - 'TMOUT': '86400', 18:20:11 INFO - 'USER': 'cltbld', 18:20:11 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1502413822.384271-334355300', 18:20:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:20:11 INFO - '_': '/tools/buildbot/bin/python'} 18:20:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:20:11 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:20:11 INFO - Cleaning up... 18:20:11 INFO - Return code: 0 18:20:11 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:20:11 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:20:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:20:11 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:20:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:20:11 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 0x10861f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1252a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13f3b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13f6f00>, '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 0x13f1ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13f2050>, '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': '923d835db2ba3686ebe126b700000192-1502413822.384271-334355300', '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:20:11 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:20:11 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:20:11 INFO - Using env: {'DISPLAY': ':0', 18:20:11 INFO - 'HOME': '/home/cltbld', 18:20:11 INFO - 'LANG': 'en_US.UTF-8', 18:20:11 INFO - 'LANGUAGE': 'en_US:en', 18:20:11 INFO - 'LOGNAME': 'cltbld', 18:20:11 INFO - 'MAIL': '/var/mail/cltbld', 18:20:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:20:11 INFO - 'MOZ_NO_REMOTE': '1', 18:20:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:20:11 INFO - 'NO_EM_RESTART': '1', 18:20:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:20:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:20:11 INFO - 'PWD': '/builds/slave/test', 18:20:11 INFO - 'SHELL': '/bin/bash', 18:20:11 INFO - 'SHLVL': '1', 18:20:11 INFO - 'TERM': 'linux', 18:20:11 INFO - 'TMOUT': '86400', 18:20:11 INFO - 'USER': 'cltbld', 18:20:11 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1502413822.384271-334355300', 18:20:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:20:11 INFO - '_': '/tools/buildbot/bin/python'} 18:20:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:20:11 INFO - Downloading/unpacking psutil>=3.1.1 18:20:11 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:20:11 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:20:11 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:20:11 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:20:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:20:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:20:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:20:12 INFO - Installing collected packages: psutil 18:20:12 INFO - Running setup.py install for psutil 18:20:12 INFO - building 'psutil._psutil_linux' extension 18:20:12 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:20:13 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:20:13 INFO - building 'psutil._psutil_posix' extension 18:20:13 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:20:13 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:20:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:20:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:20:13 INFO - Successfully installed psutil 18:20:13 INFO - Cleaning up... 18:20:13 INFO - Return code: 0 18:20:13 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:20:13 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:20:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:20:13 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:20:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:20:13 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 0x10861f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1252a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13f3b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13f6f00>, '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 0x13f1ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13f2050>, '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': '923d835db2ba3686ebe126b700000192-1502413822.384271-334355300', '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:20:13 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:20:13 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:20:13 INFO - Using env: {'DISPLAY': ':0', 18:20:13 INFO - 'HOME': '/home/cltbld', 18:20:13 INFO - 'LANG': 'en_US.UTF-8', 18:20:13 INFO - 'LANGUAGE': 'en_US:en', 18:20:13 INFO - 'LOGNAME': 'cltbld', 18:20:13 INFO - 'MAIL': '/var/mail/cltbld', 18:20:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:20:13 INFO - 'MOZ_NO_REMOTE': '1', 18:20:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:20:13 INFO - 'NO_EM_RESTART': '1', 18:20:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:20:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:20:13 INFO - 'PWD': '/builds/slave/test', 18:20:13 INFO - 'SHELL': '/bin/bash', 18:20:13 INFO - 'SHLVL': '1', 18:20:13 INFO - 'TERM': 'linux', 18:20:13 INFO - 'TMOUT': '86400', 18:20:13 INFO - 'USER': 'cltbld', 18:20:13 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1502413822.384271-334355300', 18:20:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:20:13 INFO - '_': '/tools/buildbot/bin/python'} 18:20:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:20:13 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:20:13 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:20:13 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:20:13 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:20:13 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:20:14 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:20:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:20:14 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:20:14 INFO - Installing collected packages: mozsystemmonitor 18:20:14 INFO - Running setup.py install for mozsystemmonitor 18:20:14 INFO - Successfully installed mozsystemmonitor 18:20:14 INFO - Cleaning up... 18:20:14 INFO - Return code: 0 18:20:14 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:20:14 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:20:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:20:14 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:20:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:20:14 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 0x10861f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1252a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13f3b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13f6f00>, '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 0x13f1ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13f2050>, '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': '923d835db2ba3686ebe126b700000192-1502413822.384271-334355300', '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:20:14 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:20:14 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:20:14 INFO - Using env: {'DISPLAY': ':0', 18:20:14 INFO - 'HOME': '/home/cltbld', 18:20:14 INFO - 'LANG': 'en_US.UTF-8', 18:20:14 INFO - 'LANGUAGE': 'en_US:en', 18:20:14 INFO - 'LOGNAME': 'cltbld', 18:20:14 INFO - 'MAIL': '/var/mail/cltbld', 18:20:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:20:14 INFO - 'MOZ_NO_REMOTE': '1', 18:20:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:20:14 INFO - 'NO_EM_RESTART': '1', 18:20:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:20:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:20:14 INFO - 'PWD': '/builds/slave/test', 18:20:14 INFO - 'SHELL': '/bin/bash', 18:20:14 INFO - 'SHLVL': '1', 18:20:14 INFO - 'TERM': 'linux', 18:20:14 INFO - 'TMOUT': '86400', 18:20:14 INFO - 'USER': 'cltbld', 18:20:14 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1502413822.384271-334355300', 18:20:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:20:14 INFO - '_': '/tools/buildbot/bin/python'} 18:20:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:20:15 INFO - Downloading/unpacking jsonschema==2.5.1 18:20:15 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:20:15 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:20:15 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:20:15 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:20:16 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:20:16 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:20:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:20:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:20:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:20:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:20:16 INFO - Downloading functools32-3.2.3-2.tar.gz 18:20:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:20:16 INFO - warning: no files found matching '*.txt' 18:20:16 INFO - no previously-included directories found matching 'build' 18:20:16 INFO - no previously-included directories found matching 'dist' 18:20:16 INFO - no previously-included directories found matching '.git*' 18:20:16 INFO - Installing collected packages: jsonschema, functools32 18:20:16 INFO - Running setup.py install for functools32 18:20:16 INFO - warning: no files found matching '*.txt' 18:20:16 INFO - no previously-included directories found matching 'build' 18:20:16 INFO - no previously-included directories found matching 'dist' 18:20:16 INFO - no previously-included directories found matching '.git*' 18:20:16 INFO - Successfully installed jsonschema functools32 18:20:16 INFO - Cleaning up... 18:20:16 INFO - Return code: 0 18:20:16 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:20:16 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:20:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:20:16 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:20:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:20:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x10861f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1252a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13f3b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13f6f00>, '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 0x13f1ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13f2050>, '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': '923d835db2ba3686ebe126b700000192-1502413822.384271-334355300', '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:20:16 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:20:16 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:20:16 INFO - Using env: {'DISPLAY': ':0', 18:20:16 INFO - 'HOME': '/home/cltbld', 18:20:16 INFO - 'LANG': 'en_US.UTF-8', 18:20:16 INFO - 'LANGUAGE': 'en_US:en', 18:20:16 INFO - 'LOGNAME': 'cltbld', 18:20:16 INFO - 'MAIL': '/var/mail/cltbld', 18:20:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:20:16 INFO - 'MOZ_NO_REMOTE': '1', 18:20:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:20:16 INFO - 'NO_EM_RESTART': '1', 18:20:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:20:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:20:16 INFO - 'PWD': '/builds/slave/test', 18:20:16 INFO - 'SHELL': '/bin/bash', 18:20:16 INFO - 'SHLVL': '1', 18:20:16 INFO - 'TERM': 'linux', 18:20:16 INFO - 'TMOUT': '86400', 18:20:16 INFO - 'USER': 'cltbld', 18:20:16 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1502413822.384271-334355300', 18:20:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:20:16 INFO - '_': '/tools/buildbot/bin/python'} 18:20:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:20:16 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:20:16 INFO - Cleaning up... 18:20:16 INFO - Return code: 0 18:20:16 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:20:16 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:20:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:20:16 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:20:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:20:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x10861f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1252a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13f3b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13f6f00>, '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 0x13f1ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13f2050>, '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': '923d835db2ba3686ebe126b700000192-1502413822.384271-334355300', '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:20:16 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:20:16 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:20:16 INFO - Using env: {'DISPLAY': ':0', 18:20:16 INFO - 'HOME': '/home/cltbld', 18:20:16 INFO - 'LANG': 'en_US.UTF-8', 18:20:16 INFO - 'LANGUAGE': 'en_US:en', 18:20:16 INFO - 'LOGNAME': 'cltbld', 18:20:16 INFO - 'MAIL': '/var/mail/cltbld', 18:20:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:20:16 INFO - 'MOZ_NO_REMOTE': '1', 18:20:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:20:16 INFO - 'NO_EM_RESTART': '1', 18:20:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:20:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:20:16 INFO - 'PWD': '/builds/slave/test', 18:20:16 INFO - 'SHELL': '/bin/bash', 18:20:16 INFO - 'SHLVL': '1', 18:20:16 INFO - 'TERM': 'linux', 18:20:16 INFO - 'TMOUT': '86400', 18:20:16 INFO - 'USER': 'cltbld', 18:20:16 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1502413822.384271-334355300', 18:20:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:20:16 INFO - '_': '/tools/buildbot/bin/python'} 18:20:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:20:16 INFO - Downloading/unpacking blobuploader==1.2.4 18:20:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:20:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:20:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:20:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:20:18 INFO - Downloading blobuploader-1.2.4.tar.gz 18:20:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:20:18 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:20:18 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:20:18 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:20:18 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:20:18 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:20:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:20:18 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:20:18 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:20:18 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:20:18 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:20:18 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:20:18 INFO - Downloading docopt-0.6.1.tar.gz 18:20:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:20:18 INFO - Installing collected packages: blobuploader, requests, docopt 18:20:18 INFO - Running setup.py install for blobuploader 18:20:18 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:20:18 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:20:18 INFO - Running setup.py install for requests 18:20:19 INFO - Running setup.py install for docopt 18:20:19 INFO - Successfully installed blobuploader requests docopt 18:20:19 INFO - Cleaning up... 18:20:19 INFO - Return code: 0 18:20:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:20:19 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:20:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:20:19 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:20:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:20:19 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 0x10861f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1252a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13f3b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13f6f00>, '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 0x13f1ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13f2050>, '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': '923d835db2ba3686ebe126b700000192-1502413822.384271-334355300', '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:20:19 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:20:19 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:20:19 INFO - Using env: {'DISPLAY': ':0', 18:20:19 INFO - 'HOME': '/home/cltbld', 18:20:19 INFO - 'LANG': 'en_US.UTF-8', 18:20:19 INFO - 'LANGUAGE': 'en_US:en', 18:20:19 INFO - 'LOGNAME': 'cltbld', 18:20:19 INFO - 'MAIL': '/var/mail/cltbld', 18:20:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:20:19 INFO - 'MOZ_NO_REMOTE': '1', 18:20:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:20:19 INFO - 'NO_EM_RESTART': '1', 18:20:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:20:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:20:19 INFO - 'PWD': '/builds/slave/test', 18:20:19 INFO - 'SHELL': '/bin/bash', 18:20:19 INFO - 'SHLVL': '1', 18:20:19 INFO - 'TERM': 'linux', 18:20:19 INFO - 'TMOUT': '86400', 18:20:19 INFO - 'USER': 'cltbld', 18:20:19 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1502413822.384271-334355300', 18:20:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:20:19 INFO - '_': '/tools/buildbot/bin/python'} 18:20:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:20:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:20:19 INFO - Running setup.py (path:/tmp/pip-nsIAOd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:20:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:20:19 INFO - Running setup.py (path:/tmp/pip-EGR2T8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:20:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:20:19 INFO - Running setup.py (path:/tmp/pip-BEQqeJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:20:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:20:19 INFO - Running setup.py (path:/tmp/pip-O7_mcy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:20:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:20:19 INFO - Running setup.py (path:/tmp/pip-hwtS59-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:20:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:20:19 INFO - Running setup.py (path:/tmp/pip-tNy5Tk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:20:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:20:19 INFO - Running setup.py (path:/tmp/pip-H54DFt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:20:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:20:19 INFO - Running setup.py (path:/tmp/pip-Gn23VA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:20:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:20:20 INFO - Running setup.py (path:/tmp/pip-lRg0pI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:20:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:20:20 INFO - Running setup.py (path:/tmp/pip-yV24aa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:20:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:20:20 INFO - Running setup.py (path:/tmp/pip-77tSn5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:20:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:20:20 INFO - Running setup.py (path:/tmp/pip-5M2pD_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:20:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:20:20 INFO - Running setup.py (path:/tmp/pip-EUIiYU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:20:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:20:20 INFO - Running setup.py (path:/tmp/pip-sMmHUF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:20:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:20:20 INFO - Running setup.py (path:/tmp/pip-U5jUuq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:20:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:20:20 INFO - Running setup.py (path:/tmp/pip-wEtxXm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:20:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:20:20 INFO - Running setup.py (path:/tmp/pip-wwo8ML-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:20:20 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:20:20 INFO - Running setup.py install for manifestparser 18:20:20 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:20:20 INFO - Running setup.py install for mozcrash 18:20:20 INFO - Running setup.py install for mozdebug 18:20:21 INFO - Running setup.py install for mozdevice 18:20:21 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:20:21 INFO - Running setup.py install for mozfile 18:20:21 INFO - Running setup.py install for mozhttpd 18:20:21 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:20:21 INFO - Running setup.py install for mozinfo 18:20:21 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:20:21 INFO - Running setup.py install for mozInstall 18:20:21 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:20:21 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:20:21 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:20:21 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:20:21 INFO - Running setup.py install for mozleak 18:20:21 INFO - Running setup.py install for mozlog 18:20:21 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:20:21 INFO - Running setup.py install for moznetwork 18:20:21 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:20:21 INFO - Running setup.py install for mozprocess 18:20:21 INFO - Running setup.py install for mozprofile 18:20:22 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:20:22 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:20:22 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:20:22 INFO - Running setup.py install for mozrunner 18:20:22 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:20:22 INFO - Running setup.py install for mozscreenshot 18:20:22 INFO - Running setup.py install for moztest 18:20:22 INFO - Running setup.py install for mozversion 18:20:22 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:20:22 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:20:22 INFO - Cleaning up... 18:20:22 INFO - Return code: 0 18:20:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:20:22 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:20:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:20:22 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:20:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:20:22 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 0x10861f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1252a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13f3b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13f6f00>, '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 0x13f1ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13f2050>, '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': '923d835db2ba3686ebe126b700000192-1502413822.384271-334355300', '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:20:22 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:20:22 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:20:22 INFO - Using env: {'DISPLAY': ':0', 18:20:22 INFO - 'HOME': '/home/cltbld', 18:20:22 INFO - 'LANG': 'en_US.UTF-8', 18:20:22 INFO - 'LANGUAGE': 'en_US:en', 18:20:22 INFO - 'LOGNAME': 'cltbld', 18:20:22 INFO - 'MAIL': '/var/mail/cltbld', 18:20:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:20:22 INFO - 'MOZ_NO_REMOTE': '1', 18:20:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:20:22 INFO - 'NO_EM_RESTART': '1', 18:20:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:20:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:20:22 INFO - 'PWD': '/builds/slave/test', 18:20:22 INFO - 'SHELL': '/bin/bash', 18:20:22 INFO - 'SHLVL': '1', 18:20:22 INFO - 'TERM': 'linux', 18:20:22 INFO - 'TMOUT': '86400', 18:20:22 INFO - 'USER': 'cltbld', 18:20:22 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1502413822.384271-334355300', 18:20:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:20:22 INFO - '_': '/tools/buildbot/bin/python'} 18:20:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:20:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:20:22 INFO - Running setup.py (path:/tmp/pip-oosRum-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:20:22 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:20:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:20:22 INFO - Running setup.py (path:/tmp/pip-A9B0Hr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:20:22 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:20:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:20:22 INFO - Running setup.py (path:/tmp/pip-7G3vO5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:20:22 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:20:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:20:22 INFO - Running setup.py (path:/tmp/pip-UAL_X9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:20:23 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:20:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:20:23 INFO - Running setup.py (path:/tmp/pip-pvyqdv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:20:23 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:20:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:20:23 INFO - Running setup.py (path:/tmp/pip-hLSgSm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:20:23 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:20:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:20:23 INFO - Running setup.py (path:/tmp/pip-Fk4B42-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:20:23 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:20:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:20:23 INFO - Running setup.py (path:/tmp/pip-1unba4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:20:23 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:20:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:20:23 INFO - Running setup.py (path:/tmp/pip-Bze94j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:20:23 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:20:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:20:23 INFO - Running setup.py (path:/tmp/pip-uYDVUk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:20:23 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:20:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:20:23 INFO - Running setup.py (path:/tmp/pip-ZC_bBT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:20:23 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:20:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:20:23 INFO - Running setup.py (path:/tmp/pip-DPS6CR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:20:23 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:20:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:20:23 INFO - Running setup.py (path:/tmp/pip-mckT6D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:20:23 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:20:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:20:23 INFO - Running setup.py (path:/tmp/pip-qM1KNN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:20:23 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:20:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:20:23 INFO - Running setup.py (path:/tmp/pip-EJv9nJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:20:23 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:20:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:20:23 INFO - Running setup.py (path:/tmp/pip-Z7QlXs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:20:23 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:20:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:20:23 INFO - Running setup.py (path:/tmp/pip-yRevfw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:20:23 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:20:23 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:20:23 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:20:23 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:20:23 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:20:23 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:20:23 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:20:23 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:20:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:20:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:20:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:20:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:20:25 INFO - Downloading blessings-1.6.tar.gz 18:20:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:20:25 INFO - Installing collected packages: blessings 18:20:25 INFO - Running setup.py install for blessings 18:20:25 INFO - Successfully installed blessings 18:20:25 INFO - Cleaning up... 18:20:25 INFO - Return code: 0 18:20:25 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:20:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:20:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:20:25 INFO - Reading from file tmpfile_stdout 18:20:25 INFO - Current package versions: 18:20:25 INFO - argparse == 1.2.1 18:20:25 INFO - blessings == 1.6 18:20:25 INFO - blobuploader == 1.2.4 18:20:25 INFO - docopt == 0.6.1 18:20:25 INFO - functools32 == 3.2.3-2 18:20:25 INFO - jsonschema == 2.5.1 18:20:25 INFO - manifestparser == 1.1 18:20:25 INFO - mozInstall == 1.13 18:20:25 INFO - mozcrash == 1.0 18:20:25 INFO - mozdebug == 0.1 18:20:25 INFO - mozdevice == 0.51 18:20:25 INFO - mozfile == 1.2 18:20:25 INFO - mozhttpd == 0.7 18:20:25 INFO - mozinfo == 0.10 18:20:25 INFO - mozleak == 0.1 18:20:25 INFO - mozlog == 3.5 18:20:25 INFO - moznetwork == 0.27 18:20:25 INFO - mozprocess == 0.25 18:20:25 INFO - mozprofile == 0.28 18:20:25 INFO - mozrunner == 6.13 18:20:25 INFO - mozscreenshot == 0.1 18:20:25 INFO - mozsystemmonitor == 0.3 18:20:25 INFO - moztest == 0.8 18:20:25 INFO - mozversion == 1.4 18:20:25 INFO - psutil == 3.1.1 18:20:25 INFO - requests == 1.2.3 18:20:25 INFO - wsgiref == 0.1.2 18:20:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:20:25 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:20:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:20:25 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:20:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:20:25 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 0x10861f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1252a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13f3b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13f6f00>, '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 0x13f1ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13f2050>, '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': '923d835db2ba3686ebe126b700000192-1502413822.384271-334355300', '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:20:25 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:20:25 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:20:25 INFO - Using env: {'DISPLAY': ':0', 18:20:25 INFO - 'HOME': '/home/cltbld', 18:20:25 INFO - 'LANG': 'en_US.UTF-8', 18:20:25 INFO - 'LANGUAGE': 'en_US:en', 18:20:25 INFO - 'LOGNAME': 'cltbld', 18:20:25 INFO - 'MAIL': '/var/mail/cltbld', 18:20:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:20:25 INFO - 'MOZ_NO_REMOTE': '1', 18:20:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:20:25 INFO - 'NO_EM_RESTART': '1', 18:20:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:20:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:20:25 INFO - 'PWD': '/builds/slave/test', 18:20:25 INFO - 'SHELL': '/bin/bash', 18:20:25 INFO - 'SHLVL': '1', 18:20:25 INFO - 'TERM': 'linux', 18:20:25 INFO - 'TMOUT': '86400', 18:20:25 INFO - 'USER': 'cltbld', 18:20:25 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1502413822.384271-334355300', 18:20:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:20:25 INFO - '_': '/tools/buildbot/bin/python'} 18:20:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:20:25 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:20:25 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:20:25 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:20:25 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:20:25 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:20:25 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:20:25 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:20:25 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:20:25 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:20:25 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 18:20:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:20:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:20:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:20:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:20:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 18:20:26 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:20:26 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:20:26 INFO - Installing collected packages: simplejson 18:20:26 INFO - Running setup.py install for simplejson 18:20:27 INFO - building 'simplejson._speedups' extension 18:20:27 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:20:27 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:20:27 INFO - Successfully installed simplejson 18:20:27 INFO - Cleaning up... 18:20:28 INFO - Return code: 0 18:20:28 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:20:28 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:20:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:20:28 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:20:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:20:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x10861f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1252a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13f3b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13f6f00>, '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 0x13f1ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13f2050>, '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': '923d835db2ba3686ebe126b700000192-1502413822.384271-334355300', '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:20:28 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:20:28 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:20:28 INFO - Using env: {'DISPLAY': ':0', 18:20:28 INFO - 'HOME': '/home/cltbld', 18:20:28 INFO - 'LANG': 'en_US.UTF-8', 18:20:28 INFO - 'LANGUAGE': 'en_US:en', 18:20:28 INFO - 'LOGNAME': 'cltbld', 18:20:28 INFO - 'MAIL': '/var/mail/cltbld', 18:20:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:20:28 INFO - 'MOZ_NO_REMOTE': '1', 18:20:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:20:28 INFO - 'NO_EM_RESTART': '1', 18:20:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:20:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:20:28 INFO - 'PWD': '/builds/slave/test', 18:20:28 INFO - 'SHELL': '/bin/bash', 18:20:28 INFO - 'SHLVL': '1', 18:20:28 INFO - 'TERM': 'linux', 18:20:28 INFO - 'TMOUT': '86400', 18:20:28 INFO - 'USER': 'cltbld', 18:20:28 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1502413822.384271-334355300', 18:20:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:20:28 INFO - '_': '/tools/buildbot/bin/python'} 18:20:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:20:28 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:20:28 INFO - Cleaning up... 18:20:28 INFO - Return code: 0 18:20:28 INFO - Running post-action listener: _resource_record_post_action 18:20:28 INFO - Running post-action listener: _start_resource_monitoring 18:20:28 INFO - Starting resource monitoring. 18:20:28 INFO - [mozharness: 2017-08-11 01:20:28.281061Z] Finished create-virtualenv step (success) 18:20:28 INFO - [mozharness: 2017-08-11 01:20:28.281576Z] Running install step. 18:20:28 INFO - Running pre-action listener: _resource_record_pre_action 18:20:28 INFO - Running main action method: install 18:20:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:20:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:20:28 INFO - Reading from file tmpfile_stdout 18:20:28 INFO - Detecting whether we're running mozinstall >=1.0... 18:20:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:20:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:20:28 INFO - Reading from file tmpfile_stdout 18:20:28 INFO - Output received: 18:20:28 INFO - Usage: mozinstall [options] installer 18:20:28 INFO - Options: 18:20:28 INFO - -h, --help show this help message and exit 18:20:28 INFO - -d DEST, --destination=DEST 18:20:28 INFO - Directory to install application into. [default: 18:20:28 INFO - "/builds/slave/test"] 18:20:28 INFO - --app=APP Application being installed. [default: firefox] 18:20:28 INFO - mkdir: /builds/slave/test/build/application 18:20:28 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:20:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 18:20:46 INFO - Reading from file tmpfile_stdout 18:20:46 INFO - Output received: 18:20:46 INFO - /builds/slave/test/build/application/firefox/firefox 18:20:46 INFO - Running post-action listener: _resource_record_post_action 18:20:46 INFO - [mozharness: 2017-08-11 01:20:46.817177Z] Finished install step (success) 18:20:46 INFO - [mozharness: 2017-08-11 01:20:46.817429Z] Running setup-mitmproxy step. 18:20:46 INFO - Running pre-action listener: _resource_record_pre_action 18:20:46 INFO - Running main action method: setup_mitmproxy 18:20:46 INFO - Skipping: mitmproxy is not required 18:20:46 INFO - Running post-action listener: _resource_record_post_action 18:20:46 INFO - [mozharness: 2017-08-11 01:20:46.818573Z] Finished setup-mitmproxy step (success) 18:20:46 INFO - [mozharness: 2017-08-11 01:20:46.818862Z] Running run-tests step. 18:20:46 INFO - Running pre-action listener: _resource_record_pre_action 18:20:46 INFO - Running pre-action listener: _set_gcov_prefix 18:20:46 INFO - Running main action method: run_tests 18:20:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:20:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:20:46 INFO - Python 2.7.3 18:20:46 INFO - Return code: 0 18:20:46 INFO - grabbing minidump binary from tooltool 18:20:46 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:20:46 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 0x13f6f00>, '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 0x13f1ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13f2050>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:20:46 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:20:46 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:20:46 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:20:46 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:20:46 INFO - Return code: 0 18:20:46 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 18:20:46 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:20:46 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:20:46 INFO - ENV: RUST_BACKTRACE is now full 18:20:46 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 18:20:46 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:20:46 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/dTnd9BLdQ-u7ChXvVPHXNw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-007', '--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:20:46 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/dTnd9BLdQ-u7ChXvVPHXNw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-007 --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:20:46 INFO - Using env: {'DISPLAY': ':0', 18:20:46 INFO - 'HOME': '/home/cltbld', 18:20:46 INFO - 'LANG': 'en_US.UTF-8', 18:20:46 INFO - 'LANGUAGE': 'en_US:en', 18:20:46 INFO - 'LOGNAME': 'cltbld', 18:20:46 INFO - 'MAIL': '/var/mail/cltbld', 18:20:46 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:20:46 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:20:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:20:46 INFO - 'MOZ_NO_REMOTE': '1', 18:20:46 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:20:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:20:46 INFO - 'NO_EM_RESTART': '1', 18:20:46 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:20:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:20:46 INFO - 'PWD': '/builds/slave/test', 18:20:46 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:20:46 INFO - 'RUST_BACKTRACE': 'full', 18:20:46 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 18:20:46 INFO - 'SHELL': '/bin/bash', 18:20:46 INFO - 'SHLVL': '1', 18:20:46 INFO - 'STYLO_FORCE_ENABLED': '1', 18:20:46 INFO - 'TERM': 'linux', 18:20:46 INFO - 'TMOUT': '86400', 18:20:46 INFO - 'USER': 'cltbld', 18:20:46 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1502413822.384271-334355300', 18:20:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:20:46 INFO - '_': '/tools/buildbot/bin/python'} 18:20:46 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/dTnd9BLdQ-u7ChXvVPHXNw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-007', '--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:20:47 INFO - mozversion application_buildid: 20170811003658 18:20:47 INFO - mozversion application_changeset: d0068f9051be88eb4c97e28ec0a4f101ca4ff147 18:20:47 INFO - mozversion application_display_name: Nightly 18:20:47 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:20:47 INFO - mozversion application_name: Firefox 18:20:47 INFO - mozversion application_remotingname: firefox 18:20:47 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 18:20:47 INFO - mozversion application_vendor: Mozilla 18:20:47 INFO - mozversion application_version: 57.0a1 18:20:47 INFO - mozversion platform_buildid: 20170811003658 18:20:47 INFO - mozversion platform_changeset: d0068f9051be88eb4c97e28ec0a4f101ca4ff147 18:20:47 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 18:20:47 INFO - mozversion platform_version: 57.0a1 18:20:47 INFO - using testdate: 1502414447 18:20:47 INFO - actual date: 1502414447 18:20:47 INFO - starting webserver on 'localhost:47187' 18:20:47 INFO - SUITE-START | Running 2 tests 18:20:47 INFO - TEST-START | dromaeo_css 18:20:47 INFO - Initialising browser for dromaeo_css test... 18:20:47 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:47187/getInfo.html -profile /tmp/tmpbeau83/profile 18:20:47 INFO - TEST-INFO | started process 12221 (/builds/slave/test/build/application/firefox/firefox http://localhost:47187/getInfo.html) 18:20:52 INFO - TEST-INFO | 12221: exit 0 18:20:53 INFO - Browser initialized. 18:20:53 INFO - Running cycle 1/1 for dromaeo_css test... 18:20:53 INFO - TEST-INFO | started process 12457 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpbeau83/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 18:20:54 INFO - PID 12457 | 18:20:54 INFO - PID 12457 | (/builds/slave/test/build/application/firefox/firefox:12511): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 18:20:54 INFO - PID 12457 | 18:21:04 INFO - PID 12457 | RSS: Main: 168169472 18:21:04 INFO - PID 12457 | 18:24:03 INFO - PID 12457 | Cycle 1(1): loaded http://localhost:47187/tests/dromaeo/cssquery-dojo.html (next: http://localhost:47187/tests/dromaeo/cssquery-ext.html) 18:24:04 INFO - PID 12457 | RSS: Main: 175632384 18:24:04 INFO - PID 12457 | 18:27:06 INFO - PID 12457 | Cycle 1(1): loaded http://localhost:47187/tests/dromaeo/cssquery-ext.html (next: http://localhost:47187/tests/dromaeo/cssquery-jquery.html) 18:27:07 INFO - PID 12457 | RSS: Main: 172691456 18:27:07 INFO - PID 12457 | 18:30:09 INFO - PID 12457 | Cycle 1(1): loaded http://localhost:47187/tests/dromaeo/cssquery-jquery.html (next: http://localhost:47187/tests/dromaeo/cssquery-mootools.html) 18:30:10 INFO - PID 12457 | RSS: Main: 174477312 18:30:10 INFO - PID 12457 | 18:33:11 INFO - PID 12457 | Cycle 1(1): loaded http://localhost:47187/tests/dromaeo/cssquery-mootools.html (next: http://localhost:47187/tests/dromaeo/cssquery-prototype.html) 18:33:12 INFO - PID 12457 | RSS: Main: 172949504 18:33:12 INFO - PID 12457 | 18:36:16 INFO - PID 12457 | Cycle 1(1): loaded http://localhost:47187/tests/dromaeo/cssquery-prototype.html (next: http://localhost:47187/tests/dromaeo/cssquery-yui.html) 18:36:16 INFO - PID 12457 | RSS: Main: 174481408 18:36:16 INFO - PID 12457 | 18:39:18 INFO - PID 12457 | Cycle 1(1): loaded http://localhost:47187/tests/dromaeo/cssquery-yui.html (next: http://localhost:47187/tests/dromaeo/cssquery-dojo.html) 18:39:19 INFO - PID 12457 | RSS: Main: 177131520 18:39:19 INFO - PID 12457 | 18:39:19 INFO - PID 12457 | __start_tp_report 18:39:19 INFO - PID 12457 | _x_x_mozilla_page_load 18:39:19 INFO - PID 12457 | _x_x_mozilla_page_load_details 18:39:19 INFO - PID 12457 | |i|pagename|runs| 18:39:19 INFO - PID 12457 | |0;dojo.html;3122.0477754284934;3146.3392687535616;3148.165736079939;3153.3377990621966;3182.4589819730645;6240.43836054755;6259.874802503947;6298.433141017307;6314.494840412774;6317.589356691818;6098.878022439549;6151.969240153804;6162.198914141155;6172.814815555529;6189.13352130701;5618.91571626426;5643.689597072149;5661.716914154296;5670.4896559309655;5682.460166284196;5668.518175955049;5678.43215678433;5686.516646085088;5696.344920334152;5706.885862282752;5662.603617746739;5669.546436285087;5674.375818659944;5683.147527870811;5697.430256974311;5645.971770141164;5662.235598194232;5668.291463567054;5672.5178359839465;5786.189933409326;5673.8581535461535;5687.317521897357;5691.544676425918;5787.421257874221;5803.912941305884;5973.193618861442;6074.179985701918;6117.327094019654;6141;6165.290991535986;6653.900191497083;6662.800115996528;6677.499187560952;6686.966565167192;6689.632070236135;5998.430149135826;6700.363465470772;6742.797716068526;6749.426298764611;6826.941824017285;6192.628442293477;6219;6280.246370435531;6284.874302513924;6290.308066112724;6735.966320168368;6800.183977922681;6847.246802851682;6848.897266541006;6957.582545047313;6569.868602627921;6576.901346479806;6610.537262391586;6619.371159739817;6625.436837868739;6651.63416012124;6685.364890335411;6711.63086030273;6727.596344219362;6732.528722989344;6659.6670166491485;6728.596284222962;6731.1922569292;6740.662966851638;6771.661416929134;6642.568233064932;6667.499937504706;6690.866182676319;6751;6777.423918966943;5256.448072952361;5335.946640533545;5355.651882627617;5407.513323800877;5417.756200970965;4601.28680054592;4614.515475875052;4631.560001799824;4656.4412270527755;4697.506761790031;5872.442117998783;5875.4124587541755;5876.529877609781;5890.3815099414805;5893.469587737124;6713.597184168965;6725.764598239038;6736.7642132525125;6815.761448349284;6838.14523184602;5708.086706127;5803.767849285981;5878.030125029322;5890.410958904075;5901.291844978544;5861.5603829712945;5939.910901336483;5947.167396564484;5961.374055724086;5968.552358573038;6380.617162970144;6489.513286503531;6519.902201466982;6541.509386795914;6554.573952693155;6680.098186744922;6688.2977287386075;6775.051492791112;6776.8818145005525;6780.491463140185;6360.745570177141;6445.452136568444;6456.289808121009;6460.612363258219;6525.575837570543;6500.382463665944;6501.447376972821;6505.251896032017;6606.042123892104;6664.100346453167;6693.464522838064;6769.28922463154;6777.186737591521;6792.184937807495;6794.388505034571;6526.314736952551;6528.640924749185;6531.314212007861;6542.967285163544;6545;6447.2263328400895;6455.48356131499;6526.934730652632;6539.869202616016;6556.934430655823;6387.329330420239;6416.647084410402;6481.546291759721;6494.545381823227;6494.577852439576;6439.549231553746;6479.254885688206;6576.671166441754;6577.177852768404;6582.835429114119;6334.081558174131;6364.236291645032;6395.328490508616;6431.324710905475;6434.967825161031 18:39:19 INFO - PID 12457 | |1;ext.html;825.1377310710307;865.104616721693;892.3753372639151;895.6954635423955;1198.7242934125156;31418.214544636394;42206;42960.78519607398;43113.13773724523;43190;43314.783426082904;43530;43781.78109109458;43810.78094609531;43863.34204986928;7283.890741638879;7778.222177782233;7856.607169641524;7859.253370929781;7874.133845277015;7734;7838.059432868064;7848.372130229568;7884.724034658788;7907.090684571262;7855.214478552156;7862.056553213593;7907.48601340911;7911.169327220645;7922.643481043367;7861.882071768928;7896.407769417288;7916.366690664733;7920.841583168362;7921.722739704082;7737.690492380298;7768.7669369918995;7770;7775.961120194363;7811.804704882366;39371.4094288583;39591.802040989896;39710.801445992875;39858.20283594312;40083;3612.3317186320614;3660.8352624131976;3661.340958627446;3661.487391765155;3662.633736626343;31906.20234494156;32140;32270.515942260885;32311.353772924645;32365.51451728226;5082.74586270685;5089.592832573385;5142.61430392722;5149.562287205592;5169.095408303532;190802;192695;193221;193255;193794;47510.52489475061;47919;47954;48295.5170448298;48404.5159548407;50410.49589504131;50531.74734126306;50780.746096269286;50827.49172508302;50831.49168508341;14218.004739668331;16157.272922718506;16259.756103658454;16304.184790760415;16473.423430179886;11723.76552468946;13368.33158342079;13472.326383680776;13538.255395953318;13581.11722738019;14050.648733781738;14238.359273832702;14248.715025699428;14327.78508322376;14334.354954027094;5782.190493330937;5788.784355285437;5796.188533605298;5824.5340372770925;5829.650220986754;1011.549860312154;1020.4795554267229;1020.7346090016649;1022.3559157730731;1033.7725700345911;22407.655540667613;22537.661935070988;22686.432839179153;22960.540789184124;22966.54066918652;1097.6487523992405;1121.685927940178;1122.893325134111;1124.454639499842;1126.419893754717;12071.879281207075;12078.396080195955;12087.939560302142;12107.878921210675;12360.505579776887;207325;209409;209760;209947;212094;53695.731521342146;54165.72917135468;60887;62655;63350.0497492538;1537.9077255364714;1557.8130624325154;1579.5498282989292;1585;1588.7299159142974;27577.44845103127;28046.494188226472;28100.718992810627;28285.717142829133;28367.148985530468;40932.1813563733;41110.588894111876;41121.794391027855;41173.382399263435;41186.38220426636;214189;215576;215644;215908;216496;36644.63355366519;37962;38792.80603596964;38857.80571097126;38993.610063898996;46481;47679;48235.75882120567;48278.275825861936;48737;35130.82434587811;35382.29235415226;35581.288374232885;35648.465273021444;35783.28433431369;35428;35526.46710299296;35810.64189358073;35836.64163358333;36155.457668135525;39363;39546.2090758189;39618;39849.20301593894;39939.201215974936;33462.832685836416;33634.49548256729;33805.49291760576;33992.320153597284;34237.486437703956;23861;24292.757072429755;24313.149039783522;24467;24657.38356541029 18:39:19 INFO - PID 12457 | |2;jquery.html;573.6737529318611;574.4829653312021;578.5747475605432;588.8939990801655;599.2928344553427;22957.42606434838;23097.076116955344;23649.881750591245;23799.405014874636;23849.284521464382;26456.338591535172;26905.86547067262;27082.59376109355;27086.729132708668;27123;6444.935550644504;6461.256955450113;6551.639659818708;6598.307177746339;6601.768938087168;6322.114903913456;6552.868942621145;6611.735530578771;6637.137172167636;6652.234992975821;6591.044298576703;6597.2083349998065;6599.472042236634;6600.339966003386;6605;6532.575382600139;6535.8692826143215;6559.639219842907;6591.176102987127;6603.768868089618;6108.3280839107665;6151.446369826737;6152.938470615281;6153.446189842935;6161.137440758297;29539.409211815644;29932.401351973058;30473;31339.686603133905;31411.84294078538;1962.1954998450567;1986.2253721048803;1996.6206420780006;1998.680211166221;1999.6100760351592;19547.706784398244;19712.70430943537;20569;20698.172073117057;20788.168473261052;2411.686480757508;2446.412860913368;2500.374906273432;2550.6301586270074;2560.46230291639;124762.3761881194;131806;134746;150401.24799376042;152713.23643381824;14852.554423367315;14932.850671493363;15339.693206136038;15430.151341676315;15619.531414057596;23602.527949440915;23609.527809443716;23614.527709445716;23705.288841334786;23732.88133559321;10043.548040338203;10098.899011009797;10180;10238.53926573306;10270;9277.16505514507;9312.16190542841;9319.627214911328;9395;9446.244300455946;7816.843663126706;9314.487703176255;9570.47362395075;9585.712428627152;9601.615935362646;2855.614492043589;2872.1096460097438;2872.4973129702216;2875.353045564773;2976.7469765070214;954.8615450759601;970.975620720133;975.9268054895912;982.6806287956442;983.4050399508337;19601.901990489958;24964.750352496605;25146;25433;25706;994.4431118573622;998.1166667499315;1065.6962765611763;1069.6149386220955;1075.2580719303655;10537.473126343806;10802.351858888334;11005.284656497302;11376.829347559793;11532.005036071878;507178;509371;509876;533976;560294;131164.68835311715;132547.33726331498;132668.33665831608;135409;137565;1257.9685507862378;1271.8791714787264;1294.0553396020957;1300.232862611064;1307.4966138036734;21036.26373076935;21171.8941405292;21318.573628527654;21639.45901352416;21651.458713532287;59403.702981484814;60095.699521502116;60560.697196513735;61953;62230.68884655548;72525.6373718128;72904.27095728974;73601.63199183969;74030.88953665807;74369;16345.427910023092;21108.366748997556;21878.562428751655;22435.887820561446;22636;24557.87721061383;24732.629010564495;24857.378565536008;24884;25006.874965625055;18291.54271143179;18672.533186669898;18706.06469676538;18778.624427511648;18789.812101878808;17173.141342932555;17381.391651292142;17550.82449175492;17646.73529897027;17728.55678608045;19669.114889829707;19848.40454786359;19861.503462413555;20056.799432005493;20130.29543965954;20655.483612909797;20694.379168624964;20891.686624700338;21254.46863828355;21305;17468.388606399225;18655.347062852736;18747.718784217974;18773.90613046926;18942.337018204296 18:39:19 INFO - PID 12457 | |3;mootools.html;1390.6801435669795;1414.7736362182052;1441.9206943618096;1446.7106578684266;1449.1667291307501;13445.7983130253;13963.301834908265;13973.79039314411;13976.6505837354;14016.929915350422;13111.47554097835;13649.931750341235;13871.4451421943;14006.22965736884;14009.369578368998;2598.883050262738;2639.6172554979516;2641.28685254981;2642.114891511346;2643.9556375231773;2441.084593277521;2511.8618475983812;2541.682289713786;2629.9605505917425;2630.69746979097;2632.8946842126293;2634.196570046135;2661.4011847334386;2663.987684679819;2665.3203433124622;2493.4265119022634;2563.1669707345095;2563.423229773286;2564.12819641321;2565.5125526150064;2515.5220508103403;2534.4550921551954;2563.93596657387;2608.087169490663;2614.372550587864;13868;13890.23603701796;13891.166530008231;13911;13948.860511394858;7326.450516211252;7582.962085189594;7583.7724868254045;7589.354904833096;7595.772126836204;4713.481517033124;4831.734254615994;4999.625028122869;5019.598432125421;5028.346314979066;1874.2128306111465;1886.217219853759;1887.2073729033702;1938.6607343714934;1975.190172029461;22283.44291392712;22569.66145507819;22815.201467948544;22836.200732974266;23013.88493057541;6052.455279024821;6297.149884765589;6334.144890439732;6341.524385671093;6355.745770169141;15550.76673849893;15711.607209819618;15764.369425222863;15773.132477713607;15808.051516909023;7203.8559228816175;7428.85142297151;7430.554166750012;7434.070741157356;7439.85120297591;6661.733530658817;6711.63086030273;6865.794026179223;6878.312168783182;6888.207856096513;4871.585915197248;4983.426905905795;4989.351384320088;5002.249662550574;5014.17266151081;1398.2519352146553;1467.207707837777;1469.7060587882465;1500.3848422146868;1507.1183357735629;877.9868301975475;904.5477261369315;912.2109375390239;925.144241576539;930.6975233049286;7130.5112347764325;7173.89239161413;7175.677094530654;7219.386352160125;7327.560346379234;715.2632788228134;725.9528130671489;737.0565675934705;737.863689917521;739.4675833399943;4546.909061818745;5095.974520127375;5310.7610157543;5422.511973922287;5624.268845050199;19144.425667230007;19240.903795480932;19562.510937226398;19572.510687232934;19573.706394404096;17541.210645520983;17868.195931183127;17887;17909.7313540297;17960.820391795914;11064.391458469701;11121.388323642115;11127.610533631285;11150.21948463616;11160.330380177053;7456.440766942609;8053.83892322162;8068.193180682119;8316.62575184106;8335.541545214828;15042.247887605794;15521.68956620884;15529.534113976599;16776.74834877502;17036.65926681484;16629.667406652043;16993.490195294162;17121.914390428465;17182.828171718123;17184.140792960054;10939.78120437571;11019.393933333744;11050;11086.556537738723;11134.888651113386;12878;12971.481140754264;12991;13022.088453808143;13033.934830326167;10400.323978941648;10479.266451348332;10484.475776211311;10602.787944240918;10630.627928022483;9739.805203896025;9756.804863902824;9759.365641233297;9804.803903922024;9805.1175394212;9389.154976052188;9550.522473876417;9580.233581313618;9586.760330991781;9591;6904.895216765494;6906.758263460755;6942.097527321416;6984.895226571504;6990.650467476504;6577.704003319766;6645.335466453316;6652.600843949379;6727.663616819237;6753.763618273533 18:39:19 INFO - PID 12457 | |4;prototype.html;479.96255893731114;501.30318856789046;505.5424840519329;510.96784495319446;527.9841604751857;6134.570580059392;6205.379462053792;6213.937860621392;6218.22272215973;6253.968730156348;6146.754129834812;6148.292946311176;6173.938260617392;6185.226846644169;6224.28420731616;4921.4094308682825;4937.259411088347;5042.495750424965;5090.745462726858;5096.617753668471;4981.626378021645;4983.3870443928045;5007;5025.798968041274;5062.493750624945;5017.899642007158;5018.498150184971;5025.472325405834;5046.470120637336;5061.569766569846;5020.573251273646;5031.899362012758;5039.622028347871;5044.192929131322;5068.898622027576;4546.749928753918;4548.6808825440585;4564.680472366936;4570.565796249351;4573.199690054227;5920.467157955762;5992.9700351498395;6041.184440100574;6059.151718759334;6083.878322433571;5624;5674.801381951653;5693.743781529841;5736.311642602873;5739.827805165852;6538.934610653928;6607.579370435332;6632.2704502504685;6645.734170633169;6649.93350066498;4892.461829198785;4894;4927.6550641455115;4932.161532539477;4972.129877271463;6664.966675166593;6738;6833.89749153768;6873.553219040747;6953.20038195609;4957.479464656231;4958.578520825698;4978.2034874419915;5002.59979201663;5073.746312684351;5121.206213036985;5131.615128865275;5136.8715782105;5140.614453915971;5154.252633368215;5695.202671625976;5735.623450371881;5766.509846663081;5772.163036359789;5779.450952159538;6642.568233064835;6670.499712521483;6673.866522669519;6678.4991125665765;6714.496412769062;1784.1525275494037;1801.8468180364578;1804.0799192411703;1805.9006754628622;1806.3768000039966;1174.7063234191453;1226.7853125702966;1239.3617287097152;1241.453760345445;1266.379474057705;5774.566907481955;5806.1000544915605;5810.331811841692;5840.678762668094;5917.349091599921;6810.182778066664;6811.2167100784045;6812.863742725217;6828;6878.965605171942;5754;5812.186293918855;5822.475977162076;5836.970815145897;5853.678047707417;3535.681788639035;3585.8386372613295;3595.352836489457;3631.0559254593995;3655.2089476157025;6518.28298887122;6518.934810651833;6587.473002159816;6609.678064387142;6609.900851487231;6882.965585172042;6929.44564434844;6948.513604047769;6972.58164510131;6977.162740471176;6402.35976402356;6452.774152904626;6460.806175814733;6495.350464953467;6539.945233954978;6177.62934223948;6577.967110164419;6623.238327592196;6701.3298670134545;6756.45948324123;6599.769008084694;6623.6688165592495;6632.801015969529;6668.633225172662;6675.332466753286;6482.513811464253;6501.147173055657;6542.672866356568;6548.738050477928;6594.604323740591;5061.6962982221185;5064.974675126601;5069.188929771219;5090.592752579859;5103.617228707788;6487.18910136233;6640.601563906181;6694.83262918431;6847;6879.277675843964;6382.266039405342;6394.200724909387;6447.806565803034;6497.317781632861;6560.606363618007;6497.902531462127;6528.967355163194;6558.376954189344;6573.079768832272;6637.933620663925;6366.299707032315;6450.8064758057335;6463.64449955257;6490.935090649033;6510.18622672166;6519.869602607826;6541.182352205974;6554.705038273384;6568.934310657023;6616.867662646817;6394.936050639434;6396.680165991589;6397.680115994275;6462.579932304385;6473.514486413444 18:39:19 INFO - PID 12457 | |5;yui.html;891.3715830339611;912.5984566790612;925.5464822237097;927.9164875161239;940.6613619097124;18655;19402.51493712656;19413.417597472064;19540.41378758636;19565.706514402296;17800.64398712025;17878.642427151448;18330.450086497425;22157.00293486793;22334.88832555839;1041.1254546181206;1053.6733612580085;1055.767731099157;1057.2387880725903;1057.6932689520067;1013.6417200950724;1071.79635869185;1072.5709716113538;1074.274864466486;1078.1482628723338;1093.3713114958891;1095.8794532601407;1096.9122470202406;1097.2812807611028;1097.4732128578287;1085.5820509104012;1088.8747794003712;1089.6513115802986;1095.7151140703395;1098.2751384086544;797.9680812767482;802.4182467710921;802.4823988527426;802.6067227058758;804.5092493578912;20135.395938121732;20225.494362640904;20356.18575256988;20368.490787730276;20637.380878573666;22162.667559986614;22325.44186395337;22445.551088978293;22547.210847620256;23009.309720708407;16533.50399488017;16600.418985335575;16713.582160446087;16782.496525104267;16899.915500422543;3541.8583256669704;3551.3607550640872;3556.05764472415;3561.002919182633;3583.4445660922597;123202.76797232003;123295.38352308271;123535;125076;126932;16468.0942548161;17066.914665426593;17152.914235428743;17430.738538921927;17436.47690569285;30271.69728302733;31053.223669407995;31632.683673162974;31717.04848854538;31930.2017449561;9040.09599040104;9277.48973806447;9287.72136835896;9287.860682089638;9312.301577381724;4392.890177745582;4402.7358358498595;4467;4515.616172625298;4537.455505339381;5264.6051546134195;5305.1776974569;5343.438938911436;5372.435894231049;5392.191171324333;1043.9060484556426;1077.5743581285506;1089.3953855610212;1097.8298363753631;1099.8680158381046;689.5380095336137;704.0143798681784;711.6015031582331;717.1215261304964;726.7129483853848;11750.29498230109;11870.465829037714;11879.88120118805;12298.139130260975;12459.501619935101;698.4028655499576;704.7815177295055;708.6315116139579;710.9075820143452;715.7995761186876;5257;5262.578993680497;5342.252084708144;5391.81128660495;5424.783008679688;110566;114957;114977;116495;123391.3830430842;80608.59695701484;98558.50720746494;99192.50403747935;99828;104309;70610;85200.57399713085;86837;88287.55856220677;91420.5428972851;12017.939910300393;12421;12566.623001309976;12783.424745886272;13117.475300987853;38170;38934.6106538931;39230.80384598059;40019.79990100031;40179.799101005476;52744;73383;74115.62942185254;77676;78032.8295075563;18771.90614046921;19150.80849191546;19175.328863490267;19437.51406214856;20272.290469833486;22160;22230.44423889351;22438.88780556087;22449.10203591838;22569.5486090274;18067.909660451613;18543.814561854208;18579.72130418072;18914.905425472785;18921.810781892003;19547.609047818678;19805.504862378555;19871.900640496704;19932.202711891943;20038.799612003695;19234.615307693486;19793.802061979197;20072.799272007676;20100.296489622793;20176.39470815878;13587;13613.319334033456;13903.165810051429;13973.371198296305;14107.929460352632;14874.33065512033;15068.54794356171;15104.169270690218;15264.618384540476;15481.767773483632 18:39:19 INFO - PID 12457 | __end_tp_report 18:39:19 INFO - PID 12457 | __start_cc_report 18:39:19 INFO - PID 12457 | _x_x_mozilla_cycle_collect,377 18:39:19 INFO - PID 12457 | __end_cc_report 18:39:19 INFO - PID 12457 | __startTimestamp1502415559078__endTimestamp 18:39:19 INFO - PID 12457 | 18:39:19 INFO - PID 12457 | ------- Summary: start ------- 18:39:19 INFO - PID 12457 | Number of tests: 6 18:39:19 INFO - PID 12457 | 18:39:19 INFO - PID 12457 | [#0] dojo.html Cycles:165 Average:6153.52 Median:6416.65 stddev:731.32 (11.4%) stddev-sans-first:693.82 18:39:19 INFO - PID 12457 | Values: 3122.0 3146.3 3148.2 3153.3 3182.5 6240.4 6259.9 6298.4 6314.5 6317.6 6098.9 6152.0 6162.2 6172.8 6189.1 5618.9 5643.7 5661.7 5670.5 5682.5 5668.5 5678.4 5686.5 5696.3 5706.9 5662.6 5669.5 5674.4 5683.1 5697.4 5646.0 5662.2 5668.3 5672.5 5786.2 5673.9 5687.3 5691.5 5787.4 5803.9 5973.2 6074.2 6117.3 6141.0 6165.3 6653.9 6662.8 6677.5 6687.0 6689.6 5998.4 6700.4 6742.8 6749.4 6826.9 6192.6 6219.0 6280.2 6284.9 6290.3 6736.0 6800.2 6847.2 6848.9 6957.6 6569.9 6576.9 6610.5 6619.4 6625.4 6651.6 6685.4 6711.6 6727.6 6732.5 6659.7 6728.6 6731.2 6740.7 6771.7 6642.6 6667.5 6690.9 6751.0 6777.4 5256.4 5335.9 5355.7 5407.5 5417.8 4601.3 4614.5 4631.6 4656.4 4697.5 5872.4 5875.4 5876.5 5890.4 5893.5 6713.6 6725.8 6736.8 6815.8 6838.1 5708.1 5803.8 5878.0 5890.4 5901.3 5861.6 5939.9 5947.2 5961.4 5968.6 6380.6 6489.5 6519.9 6541.5 6554.6 6680.1 6688.3 6775.1 6776.9 6780.5 6360.7 6445.5 6456.3 6460.6 6525.6 6500.4 6501.4 6505.3 6606.0 6664.1 6693.5 6769.3 6777.2 6792.2 6794.4 6526.3 6528.6 6531.3 6543.0 6545.0 6447.2 6455.5 6526.9 6539.9 6556.9 6387.3 6416.6 6481.5 6494.5 6494.6 6439.5 6479.3 6576.7 6577.2 6582.8 6334.1 6364.2 6395.3 6431.3 6435.0 18:39:19 INFO - PID 12457 | 18:39:19 INFO - PID 12457 | [#1] ext.html Cycles:180 Average:38685.70 Median:3662.06 stddev:53388.20 (1457.9%) stddev-sans-first:53462.28 18:39:19 INFO - PID 12457 | Values: 825.1 865.1 892.4 895.7 1198.7 31418.2 42206.0 42960.8 43113.1 43190.0 43314.8 43530.0 43781.8 43810.8 43863.3 7283.9 7778.2 7856.6 7859.3 7874.1 7734.0 7838.1 7848.4 7884.7 7907.1 7855.2 7862.1 7907.5 7911.2 7922.6 7861.9 7896.4 7916.4 7920.8 7921.7 7737.7 7768.8 7770.0 7776.0 7811.8 39371.4 39591.8 39710.8 39858.2 40083.0 3612.3 3660.8 3661.3 3661.5 3662.6 31906.2 32140.0 32270.5 32311.4 32365.5 5082.7 5089.6 5142.6 5149.6 5169.1 190802.0 192695.0 193221.0 193255.0 193794.0 47510.5 47919.0 47954.0 48295.5 48404.5 50410.5 50531.7 50780.7 50827.5 50831.5 14218.0 16157.3 16259.8 16304.2 16473.4 11723.8 13368.3 13472.3 13538.3 13581.1 14050.6 14238.4 14248.7 14327.8 14334.4 5782.2 5788.8 5796.2 5824.5 5829.7 1011.5 1020.5 1020.7 1022.4 1033.8 22407.7 22537.7 22686.4 22960.5 22966.5 1097.6 1121.7 1122.9 1124.5 1126.4 12071.9 12078.4 12087.9 12107.9 12360.5 207325.0 209409.0 209760.0 209947.0 212094.0 53695.7 54165.7 60887.0 62655.0 63350.0 1537.9 1557.8 1579.5 1585.0 1588.7 27577.4 28046.5 28100.7 28285.7 28367.1 40932.2 41110.6 41121.8 41173.4 41186.4 214189.0 215576.0 215644.0 215908.0 216496.0 36644.6 37962.0 38792.8 38857.8 38993.6 46481.0 47679.0 48235.8 48278.3 48737.0 35130.8 35382.3 35581.3 35648.5 35783.3 35428.0 35526.5 35810.6 35836.6 36155.5 39363.0 39546.2 39618.0 39849.2 39939.2 33462.8 33634.5 33805.5 33992.3 34237.5 23861.0 24292.8 24313.1 24467.0 24657.4 18:39:19 INFO - PID 12457 | 18:39:19 INFO - PID 12457 | [#2] jquery.html Cycles:180 Average:37263.24 Median:13502.15 stddev:88370.32 (654.5%) stddev-sans-first:88575.29 18:39:19 INFO - PID 12457 | Values: 573.7 574.5 578.6 588.9 599.3 22957.4 23097.1 23649.9 23799.4 23849.3 26456.3 26905.9 27082.6 27086.7 27123.0 6444.9 6461.3 6551.6 6598.3 6601.8 6322.1 6552.9 6611.7 6637.1 6652.2 6591.0 6597.2 6599.5 6600.3 6605.0 6532.6 6535.9 6559.6 6591.2 6603.8 6108.3 6151.4 6152.9 6153.4 6161.1 29539.4 29932.4 30473.0 31339.7 31411.8 1962.2 1986.2 1996.6 1998.7 1999.6 19547.7 19712.7 20569.0 20698.2 20788.2 2411.7 2446.4 2500.4 2550.6 2560.5 124762.4 131806.0 134746.0 150401.2 152713.2 14852.6 14932.9 15339.7 15430.2 15619.5 23602.5 23609.5 23614.5 23705.3 23732.9 10043.5 10098.9 10180.0 10238.5 10270.0 9277.2 9312.2 9319.6 9395.0 9446.2 7816.8 9314.5 9570.5 9585.7 9601.6 2855.6 2872.1 2872.5 2875.4 2976.7 954.9 971.0 975.9 982.7 983.4 19601.9 24964.8 25146.0 25433.0 25706.0 994.4 998.1 1065.7 1069.6 1075.3 10537.5 10802.4 11005.3 11376.8 11532.0 507178.0 509371.0 509876.0 533976.0 560294.0 131164.7 132547.3 132668.3 135409.0 137565.0 1258.0 1271.9 1294.1 1300.2 1307.5 21036.3 21171.9 21318.6 21639.5 21651.5 59403.7 60095.7 60560.7 61953.0 62230.7 72525.6 72904.3 73601.6 74030.9 74369.0 16345.4 21108.4 21878.6 22435.9 22636.0 24557.9 24732.6 24857.4 24884.0 25006.9 18291.5 18672.5 18706.1 18778.6 18789.8 17173.1 17381.4 17550.8 17646.7 17728.6 19669.1 19848.4 19861.5 20056.8 20130.3 20655.5 20694.4 20891.7 21254.5 21305.0 17468.4 18655.3 18747.7 18773.9 18942.3 18:39:19 INFO - PID 12457 | 18:39:19 INFO - PID 12457 | [#3] mootools.html Cycles:180 Average:8514.84 Median:2582.20 stddev:5801.58 (224.7%) stddev-sans-first:5793.16 18:39:19 INFO - PID 12457 | Values: 1390.7 1414.8 1441.9 1446.7 1449.2 13445.8 13963.3 13973.8 13976.7 14016.9 13111.5 13649.9 13871.4 14006.2 14009.4 2598.9 2639.6 2641.3 2642.1 2644.0 2441.1 2511.9 2541.7 2630.0 2630.7 2632.9 2634.2 2661.4 2664.0 2665.3 2493.4 2563.2 2563.4 2564.1 2565.5 2515.5 2534.5 2563.9 2608.1 2614.4 13868.0 13890.2 13891.2 13911.0 13948.9 7326.5 7583.0 7583.8 7589.4 7595.8 4713.5 4831.7 4999.6 5019.6 5028.3 1874.2 1886.2 1887.2 1938.7 1975.2 22283.4 22569.7 22815.2 22836.2 23013.9 6052.5 6297.1 6334.1 6341.5 6355.7 15550.8 15711.6 15764.4 15773.1 15808.1 7203.9 7428.9 7430.6 7434.1 7439.9 6661.7 6711.6 6865.8 6878.3 6888.2 4871.6 4983.4 4989.4 5002.2 5014.2 1398.3 1467.2 1469.7 1500.4 1507.1 878.0 904.5 912.2 925.1 930.7 7130.5 7173.9 7175.7 7219.4 7327.6 715.3 726.0 737.1 737.9 739.5 4546.9 5096.0 5310.8 5422.5 5624.3 19144.4 19240.9 19562.5 19572.5 19573.7 17541.2 17868.2 17887.0 17909.7 17960.8 11064.4 11121.4 11127.6 11150.2 11160.3 7456.4 8053.8 8068.2 8316.6 8335.5 15042.2 15521.7 15529.5 16776.7 17036.7 16629.7 16993.5 17121.9 17182.8 17184.1 10939.8 11019.4 11050.0 11086.6 11134.9 12878.0 12971.5 12991.0 13022.1 13033.9 10400.3 10479.3 10484.5 10602.8 10630.6 9739.8 9756.8 9759.4 9804.8 9805.1 9389.2 9550.5 9580.2 9586.8 9591.0 6904.9 6906.8 6942.1 6984.9 6990.7 6577.7 6645.3 6652.6 6727.7 6753.8 18:39:19 INFO - PID 12457 | 18:39:19 INFO - PID 12457 | [#4] prototype.html Cycles:180 Average:5534.60 Median:6140.66 stddev:1540.38 (25.1%) stddev-sans-first:1497.25 18:39:19 INFO - PID 12457 | Values: 480.0 501.3 505.5 511.0 528.0 6134.6 6205.4 6213.9 6218.2 6254.0 6146.8 6148.3 6173.9 6185.2 6224.3 4921.4 4937.3 5042.5 5090.7 5096.6 4981.6 4983.4 5007.0 5025.8 5062.5 5017.9 5018.5 5025.5 5046.5 5061.6 5020.6 5031.9 5039.6 5044.2 5068.9 4546.7 4548.7 4564.7 4570.6 4573.2 5920.5 5993.0 6041.2 6059.2 6083.9 5624.0 5674.8 5693.7 5736.3 5739.8 6538.9 6607.6 6632.3 6645.7 6649.9 4892.5 4894.0 4927.7 4932.2 4972.1 6665.0 6738.0 6833.9 6873.6 6953.2 4957.5 4958.6 4978.2 5002.6 5073.7 5121.2 5131.6 5136.9 5140.6 5154.3 5695.2 5735.6 5766.5 5772.2 5779.5 6642.6 6670.5 6673.9 6678.5 6714.5 1784.2 1801.8 1804.1 1805.9 1806.4 1174.7 1226.8 1239.4 1241.5 1266.4 5774.6 5806.1 5810.3 5840.7 5917.3 6810.2 6811.2 6812.9 6828.0 6879.0 5754.0 5812.2 5822.5 5837.0 5853.7 3535.7 3585.8 3595.4 3631.1 3655.2 6518.3 6518.9 6587.5 6609.7 6609.9 6883.0 6929.4 6948.5 6972.6 6977.2 6402.4 6452.8 6460.8 6495.4 6539.9 6177.6 6578.0 6623.2 6701.3 6756.5 6599.8 6623.7 6632.8 6668.6 6675.3 6482.5 6501.1 6542.7 6548.7 6594.6 5061.7 5065.0 5069.2 5090.6 5103.6 6487.2 6640.6 6694.8 6847.0 6879.3 6382.3 6394.2 6447.8 6497.3 6560.6 6497.9 6529.0 6558.4 6573.1 6637.9 6366.3 6450.8 6463.6 6490.9 6510.2 6519.9 6541.2 6554.7 6568.9 6616.9 6394.9 6396.7 6397.7 6462.6 6473.5 18:39:19 INFO - PID 12457 | 18:39:19 INFO - PID 12457 | [#5] yui.html Cycles:180 Average:24141.33 Median:20086.55 stddev:32329.03 (160.9%) stddev-sans-first:32372.58 18:39:19 INFO - PID 12457 | Values: 891.4 912.6 925.5 927.9 940.7 18655.0 19402.5 19413.4 19540.4 19565.7 17800.6 17878.6 18330.5 22157.0 22334.9 1041.1 1053.7 1055.8 1057.2 1057.7 1013.6 1071.8 1072.6 1074.3 1078.1 1093.4 1095.9 1096.9 1097.3 1097.5 1085.6 1088.9 1089.7 1095.7 1098.3 798.0 802.4 802.5 802.6 804.5 20135.4 20225.5 20356.2 20368.5 20637.4 22162.7 22325.4 22445.6 22547.2 23009.3 16533.5 16600.4 16713.6 16782.5 16899.9 3541.9 3551.4 3556.1 3561.0 3583.4 123202.8 123295.4 123535.0 125076.0 126932.0 16468.1 17066.9 17152.9 17430.7 17436.5 30271.7 31053.2 31632.7 31717.0 31930.2 9040.1 9277.5 9287.7 9287.9 9312.3 4392.9 4402.7 4467.0 4515.6 4537.5 5264.6 5305.2 5343.4 5372.4 5392.2 1043.9 1077.6 1089.4 1097.8 1099.9 689.5 704.0 711.6 717.1 726.7 11750.3 11870.5 11879.9 12298.1 12459.5 698.4 704.8 708.6 710.9 715.8 5257.0 5262.6 5342.3 5391.8 5424.8 110566.0 114957.0 114977.0 116495.0 123391.4 80608.6 98558.5 99192.5 99828.0 104309.0 70610.0 85200.6 86837.0 88287.6 91420.5 12017.9 12421.0 12566.6 12783.4 13117.5 38170.0 38934.6 39230.8 40019.8 40179.8 52744.0 73383.0 74115.6 77676.0 78032.8 18771.9 19150.8 19175.3 19437.5 20272.3 22160.0 22230.4 22438.9 22449.1 22569.5 18067.9 18543.8 18579.7 18914.9 18921.8 19547.6 19805.5 19871.9 19932.2 20038.8 19234.6 19793.8 20072.8 20100.3 20176.4 13587.0 13613.3 13903.2 13973.4 14107.9 14874.3 15068.5 15104.2 15264.6 15481.8 18:39:19 INFO - PID 12457 | -------- Summary: end -------- 18:39:19 INFO - PID 12457 | 18:39:19 INFO - TEST-INFO | 12457: exit 0 18:39:19 INFO - TEST-OK | dromaeo_css | took 1112513ms 18:39:19 INFO - TEST-START | kraken 18:39:19 INFO - Initialising browser for kraken test... 18:39:19 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:47187/getInfo.html -profile /tmp/tmpMitLJ_/profile 18:39:19 INFO - TEST-INFO | started process 12837 (/builds/slave/test/build/application/firefox/firefox http://localhost:47187/getInfo.html) 18:39:25 INFO - TEST-INFO | 12837: exit 0 18:39:25 INFO - Browser initialized. 18:39:25 INFO - Running cycle 1/1 for kraken test... 18:39:25 INFO - TEST-INFO | started process 13073 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpMitLJ_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 18:39:26 INFO - PID 13073 | 18:39:26 INFO - PID 13073 | (/builds/slave/test/build/application/firefox/firefox:13127): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 18:39:26 INFO - PID 13073 | 18:39:36 INFO - PID 13073 | RSS: Main: 168681472 18:39:36 INFO - PID 13073 | 18:39:39 INFO - PID 13073 | Cycle 1(1): loaded http://localhost:47187/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:47187/tests/kraken/driver.html?testName=audio-beat-detection) 18:39:40 INFO - PID 13073 | RSS: Main: 174858240 18:39:40 INFO - PID 13073 | 18:39:43 INFO - PID 13073 | Cycle 1(1): loaded http://localhost:47187/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:47187/tests/kraken/driver.html?testName=audio-dft) 18:39:43 INFO - PID 13073 | RSS: Main: 178356224 18:39:43 INFO - PID 13073 | 18:39:46 INFO - PID 13073 | Cycle 1(1): loaded http://localhost:47187/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:47187/tests/kraken/driver.html?testName=audio-fft) 18:39:47 INFO - PID 13073 | RSS: Main: 172421120 18:39:47 INFO - PID 13073 | 18:39:49 INFO - PID 13073 | Cycle 1(1): loaded http://localhost:47187/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:47187/tests/kraken/driver.html?testName=audio-oscillator) 18:39:50 INFO - PID 13073 | RSS: Main: 173203456 18:39:50 INFO - PID 13073 | 18:39:52 INFO - PID 13073 | Cycle 1(1): loaded http://localhost:47187/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:47187/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:39:53 INFO - PID 13073 | RSS: Main: 173416448 18:39:53 INFO - PID 13073 | 18:39:56 INFO - PID 13073 | Cycle 1(1): loaded http://localhost:47187/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:47187/tests/kraken/driver.html?testName=imaging-darkroom) 18:39:56 INFO - PID 13073 | RSS: Main: 174333952 18:39:56 INFO - PID 13073 | 18:40:00 INFO - PID 13073 | Cycle 1(1): loaded http://localhost:47187/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:47187/tests/kraken/driver.html?testName=imaging-desaturate) 18:40:01 INFO - PID 13073 | RSS: Main: 173916160 18:40:01 INFO - PID 13073 | 18:40:03 INFO - PID 13073 | Cycle 1(1): loaded http://localhost:47187/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:47187/tests/kraken/driver.html?testName=json-parse-financial) 18:40:04 INFO - PID 13073 | RSS: Main: 174813184 18:40:04 INFO - PID 13073 | 18:40:06 INFO - PID 13073 | Cycle 1(1): loaded http://localhost:47187/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:47187/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:40:06 INFO - PID 13073 | RSS: Main: 174825472 18:40:06 INFO - PID 13073 | 18:40:08 INFO - PID 13073 | Cycle 1(1): loaded http://localhost:47187/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:47187/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:40:09 INFO - PID 13073 | RSS: Main: 174796800 18:40:09 INFO - PID 13073 | 18:40:11 INFO - PID 13073 | Cycle 1(1): loaded http://localhost:47187/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:47187/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:40:12 INFO - PID 13073 | RSS: Main: 174624768 18:40:12 INFO - PID 13073 | 18:40:15 INFO - PID 13073 | Cycle 1(1): loaded http://localhost:47187/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:47187/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:40:15 INFO - PID 13073 | RSS: Main: 174743552 18:40:15 INFO - PID 13073 | 18:40:18 INFO - PID 13073 | Cycle 1(1): loaded http://localhost:47187/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:47187/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:40:19 INFO - PID 13073 | RSS: Main: 174944256 18:40:19 INFO - PID 13073 | 18:40:21 INFO - PID 13073 | Cycle 1(1): loaded http://localhost:47187/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:47187/tests/kraken/driver.html?testName=ai-astar) 18:40:21 INFO - PID 13073 | RSS: Main: 176066560 18:40:21 INFO - PID 13073 | 18:40:21 INFO - PID 13073 | __start_tp_report 18:40:21 INFO - PID 13073 | _x_x_mozilla_page_load 18:40:21 INFO - PID 13073 | _x_x_mozilla_page_load_details 18:40:21 INFO - PID 13073 | |i|pagename|runs| 18:40:21 INFO - PID 13073 | |0;ai-astar;99;107;100;107;109;110;108;99;108;108 18:40:21 INFO - PID 13073 | |1;audio-beat-detection;144;117;146;165;119;143;147;118;167;118 18:40:21 INFO - PID 13073 | |2;audio-dft;163;162;164;162;164;164;163;178;171;166 18:40:21 INFO - PID 13073 | |3;audio-fft;83;75;84;187;76;76;74;74;155;78 18:40:21 INFO - PID 13073 | |4;audio-oscillator;82;79;131;87;79;85;134;79;79;133 18:40:21 INFO - PID 13073 | |5;imaging-gaussian-blur;119;114;114;114;113;116;117;116;118;115 18:40:21 INFO - PID 13073 | |6;imaging-darkroom;152;152;156;151;151;151;152;150;152;153 18:40:21 INFO - PID 13073 | |7;imaging-desaturate;94;94;94;94;95;94;93;94;95;94 18:40:21 INFO - PID 13073 | |8;json-parse-financial;53;58;55;56;58;61;54;53;53;55 18:40:21 INFO - PID 13073 | |9;json-stringify-tinderbox;54;55;55;54;55;54;53;55;61;55 18:40:21 INFO - PID 13073 | |10;stanford-crypto-aes;84;94;84;88;88;98;77;88;93;78 18:40:21 INFO - PID 13073 | |11;stanford-crypto-ccm;128;140;133;141;133;139;145;128;130;176 18:40:21 INFO - PID 13073 | |12;stanford-crypto-pbkdf2;176;179;187;173;175;182;182;181;192;183 18:40:21 INFO - PID 13073 | |13;stanford-crypto-sha256-iterative;72;74;65;67;65;72;67;64;66;73 18:40:21 INFO - PID 13073 | __end_tp_report 18:40:21 INFO - PID 13073 | __start_cc_report 18:40:21 INFO - PID 13073 | _x_x_mozilla_cycle_collect,954 18:40:21 INFO - PID 13073 | __end_cc_report 18:40:21 INFO - PID 13073 | __startTimestamp1502415621666__endTimestamp 18:40:21 INFO - PID 13073 | 18:40:21 INFO - PID 13073 | ------- Summary: start ------- 18:40:21 INFO - PID 13073 | Number of tests: 14 18:40:21 INFO - PID 13073 | 18:40:21 INFO - PID 13073 | [#0] ai-astar Cycles:10 Average:105.50 Median:108.50 stddev:4.35 (4.0%) stddev-sans-first:3.93 18:40:21 INFO - PID 13073 | Values: 99.0 107.0 100.0 107.0 109.0 110.0 108.0 99.0 108.0 108.0 18:40:21 INFO - PID 13073 | 18:40:21 INFO - PID 13073 | [#1] audio-beat-detection Cycles:10 Average:138.40 Median:145.00 stddev:19.37 (13.4%) stddev-sans-first:20.44 18:40:21 INFO - PID 13073 | Values: 144.0 117.0 146.0 165.0 119.0 143.0 147.0 118.0 167.0 118.0 18:40:21 INFO - PID 13073 | 18:40:21 INFO - PID 13073 | [#2] audio-dft Cycles:10 Average:165.70 Median:164.00 stddev:5.06 (3.1%) stddev-sans-first:5.27 18:40:21 INFO - PID 13073 | Values: 163.0 162.0 164.0 162.0 164.0 164.0 163.0 178.0 171.0 166.0 18:40:21 INFO - PID 13073 | 18:40:21 INFO - PID 13073 | [#3] audio-fft Cycles:10 Average:96.20 Median:76.00 stddev:40.29 (53.0%) stddev-sans-first:42.45 18:40:21 INFO - PID 13073 | Values: 83.0 75.0 84.0 187.0 76.0 76.0 74.0 74.0 155.0 78.0 18:40:21 INFO - PID 13073 | 18:40:21 INFO - PID 13073 | [#4] audio-oscillator Cycles:10 Average:96.80 Median:79.00 stddev:24.91 (31.5%) stddev-sans-first:25.84 18:40:21 INFO - PID 13073 | Values: 82.0 79.0 131.0 87.0 79.0 85.0 134.0 79.0 79.0 133.0 18:40:21 INFO - PID 13073 | 18:40:21 INFO - PID 13073 | [#5] imaging-gaussian-blur Cycles:10 Average:115.60 Median:116.00 stddev:1.96 (1.7%) stddev-sans-first:1.64 18:40:21 INFO - PID 13073 | Values: 119.0 114.0 114.0 114.0 113.0 116.0 117.0 116.0 118.0 115.0 18:40:21 INFO - PID 13073 | 18:40:21 INFO - PID 13073 | [#6] imaging-darkroom Cycles:10 Average:152.00 Median:152.00 stddev:1.63 (1.1%) stddev-sans-first:1.73 18:40:21 INFO - PID 13073 | Values: 152.0 152.0 156.0 151.0 151.0 151.0 152.0 150.0 152.0 153.0 18:40:21 INFO - PID 13073 | 18:40:21 INFO - PID 13073 | [#7] imaging-desaturate Cycles:10 Average:94.10 Median:94.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 18:40:21 INFO - PID 13073 | Values: 94.0 94.0 94.0 94.0 95.0 94.0 93.0 94.0 95.0 94.0 18:40:21 INFO - PID 13073 | 18:40:21 INFO - PID 13073 | [#8] json-parse-financial Cycles:10 Average:55.60 Median:55.50 stddev:2.67 (4.8%) stddev-sans-first:2.67 18:40:21 INFO - PID 13073 | Values: 53.0 58.0 55.0 56.0 58.0 61.0 54.0 53.0 53.0 55.0 18:40:21 INFO - PID 13073 | 18:40:21 INFO - PID 13073 | [#9] json-stringify-tinderbox Cycles:10 Average:55.10 Median:55.00 stddev:2.18 (4.0%) stddev-sans-first:2.28 18:40:21 INFO - PID 13073 | Values: 54.0 55.0 55.0 54.0 55.0 54.0 53.0 55.0 61.0 55.0 18:40:21 INFO - PID 13073 | 18:40:21 INFO - PID 13073 | [#10] stanford-crypto-aes Cycles:10 Average:87.20 Median:88.00 stddev:6.73 (7.6%) stddev-sans-first:7.04 18:40:21 INFO - PID 13073 | Values: 84.0 94.0 84.0 88.0 88.0 98.0 77.0 88.0 93.0 78.0 18:40:21 INFO - PID 13073 | 18:40:21 INFO - PID 13073 | [#11] stanford-crypto-ccm Cycles:10 Average:139.30 Median:139.50 stddev:14.16 (10.1%) stddev-sans-first:14.41 18:40:21 INFO - PID 13073 | Values: 128.0 140.0 133.0 141.0 133.0 139.0 145.0 128.0 130.0 176.0 18:40:21 INFO - PID 13073 | 18:40:21 INFO - PID 13073 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:181.00 Median:182.00 stddev:5.70 (3.1%) stddev-sans-first:5.75 18:40:21 INFO - PID 13073 | Values: 176.0 179.0 187.0 173.0 175.0 182.0 182.0 181.0 192.0 183.0 18:40:21 INFO - PID 13073 | 18:40:21 INFO - PID 13073 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.50 Median:69.50 stddev:3.81 (5.5%) stddev-sans-first:3.82 18:40:21 INFO - PID 13073 | Values: 72.0 74.0 65.0 67.0 65.0 72.0 67.0 64.0 66.0 73.0 18:40:21 INFO - PID 13073 | -------- Summary: end -------- 18:40:21 INFO - PID 13073 | 18:40:22 INFO - PID 13073 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 18:40:22 INFO - TEST-INFO | 13073: exit 0 18:40:22 INFO - TEST-OK | kraken | took 62726ms 18:40:22 INFO - SUITE-END | took 1175s 18:40:22 INFO - Completed test suite (00:19:35) 18:40:22 INFO - javascript benchmark 18:40:22 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8616.638916650125, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3122.0477754284934, 3146.3392687535616, 3148.165736079939, 3153.3377990621966, 3182.4589819730645, 6240.43836054755, 6259.874802503947, 6298.433141017307, 6314.494840412774, 6317.589356691818, 6098.878022439549, 6151.969240153804, 6162.198914141155, 6172.814815555529, 6189.13352130701, 5618.91571626426, 5643.689597072149, 5661.716914154296, 5670.4896559309655, 5682.460166284196, 5668.518175955049, 5678.43215678433, 5686.516646085088, 5696.344920334152, 5706.885862282752, 5662.603617746739, 5669.546436285087, 5674.375818659944, 5683.147527870811, 5697.430256974311, 5645.971770141164, 5662.235598194232, 5668.291463567054, 5672.5178359839465, 5786.189933409326, 5673.8581535461535, 5687.317521897357, 5691.544676425918, 5787.421257874221, 5803.912941305884, 5973.193618861442, 6074.179985701918, 6117.327094019654, 6141.0, 6165.290991535986, 6653.900191497083, 6662.800115996528, 6677.499187560952, 6686.966565167192, 6689.632070236135, 5998.430149135826, 6700.363465470772, 6742.797716068526, 6749.426298764611, 6826.941824017285, 6192.628442293477, 6219.0, 6280.246370435531, 6284.874302513924, 6290.308066112724, 6735.966320168368, 6800.183977922681, 6847.246802851682, 6848.897266541006, 6957.582545047313, 6569.868602627921, 6576.901346479806, 6610.537262391586, 6619.371159739817, 6625.436837868739, 6651.63416012124, 6685.364890335411, 6711.63086030273, 6727.596344219362, 6732.528722989344, 6659.6670166491485, 6728.596284222962, 6731.1922569292, 6740.662966851638, 6771.661416929134, 6642.568233064932, 6667.499937504706, 6690.866182676319, 6751.0, 6777.423918966943, 5256.448072952361, 5335.946640533545, 5355.651882627617, 5407.513323800877, 5417.756200970965, 4601.28680054592, 4614.515475875052, 4631.560001799824, 4656.4412270527755, 4697.506761790031, 5872.442117998783, 5875.4124587541755, 5876.529877609781, 5890.3815099414805, 5893.469587737124, 6713.597184168965, 6725.764598239038, 6736.7642132525125, 6815.761448349284, 6838.14523184602, 5708.086706127, 5803.767849285981, 5878.030125029322, 5890.410958904075, 5901.291844978544, 5861.5603829712945, 5939.910901336483, 5947.167396564484, 5961.374055724086, 5968.552358573038, 6380.617162970144, 6489.513286503531, 6519.902201466982, 6541.509386795914, 6554.573952693155, 6680.098186744922, 6688.2977287386075, 6775.051492791112, 6776.8818145005525, 6780.491463140185, 6360.745570177141, 6445.452136568444, 6456.289808121009, 6460.612363258219, 6525.575837570543, 6500.382463665944, 6501.447376972821, 6505.251896032017, 6606.042123892104, 6664.100346453167, 6693.464522838064, 6769.28922463154, 6777.186737591521, 6792.184937807495, 6794.388505034571, 6526.314736952551, 6528.640924749185, 6531.314212007861, 6542.967285163544, 6545.0, 6447.2263328400895, 6455.48356131499, 6526.934730652632, 6539.869202616016, 6556.934430655823, 6387.329330420239, 6416.647084410402, 6481.546291759721, 6494.545381823227, 6494.577852439576, 6439.549231553746, 6479.254885688206, 6576.671166441754, 6577.177852768404, 6582.835429114119, 6334.081558174131, 6364.236291645032, 6395.328490508616, 6431.324710905475, 6434.967825161031], "value": 6098.428748969184, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [825.1377310710307, 865.104616721693, 892.3753372639151, 895.6954635423955, 1198.7242934125156, 31418.214544636394, 42206.0, 42960.78519607398, 43113.13773724523, 43190.0, 43314.783426082904, 43530.0, 43781.78109109458, 43810.78094609531, 43863.34204986928, 7283.890741638879, 7778.222177782233, 7856.607169641524, 7859.253370929781, 7874.133845277015, 7734.0, 7838.059432868064, 7848.372130229568, 7884.724034658788, 7907.090684571262, 7855.214478552156, 7862.056553213593, 7907.48601340911, 7911.169327220645, 7922.643481043367, 7861.882071768928, 7896.407769417288, 7916.366690664733, 7920.841583168362, 7921.722739704082, 7737.690492380298, 7768.7669369918995, 7770.0, 7775.961120194363, 7811.804704882366, 39371.4094288583, 39591.802040989896, 39710.801445992875, 39858.20283594312, 40083.0, 3612.3317186320614, 3660.8352624131976, 3661.340958627446, 3661.487391765155, 3662.633736626343, 31906.20234494156, 32140.0, 32270.515942260885, 32311.353772924645, 32365.51451728226, 5082.74586270685, 5089.592832573385, 5142.61430392722, 5149.562287205592, 5169.095408303532, 190802.0, 192695.0, 193221.0, 193255.0, 193794.0, 47510.52489475061, 47919.0, 47954.0, 48295.5170448298, 48404.5159548407, 50410.49589504131, 50531.74734126306, 50780.746096269286, 50827.49172508302, 50831.49168508341, 14218.004739668331, 16157.272922718506, 16259.756103658454, 16304.184790760415, 16473.423430179886, 11723.76552468946, 13368.33158342079, 13472.326383680776, 13538.255395953318, 13581.11722738019, 14050.648733781738, 14238.359273832702, 14248.715025699428, 14327.78508322376, 14334.354954027094, 5782.190493330937, 5788.784355285437, 5796.188533605298, 5824.5340372770925, 5829.650220986754, 1011.549860312154, 1020.4795554267229, 1020.7346090016649, 1022.3559157730731, 1033.7725700345911, 22407.655540667613, 22537.661935070988, 22686.432839179153, 22960.540789184124, 22966.54066918652, 1097.6487523992405, 1121.685927940178, 1122.893325134111, 1124.454639499842, 1126.419893754717, 12071.879281207075, 12078.396080195955, 12087.939560302142, 12107.878921210675, 12360.505579776887, 207325.0, 209409.0, 209760.0, 209947.0, 212094.0, 53695.731521342146, 54165.72917135468, 60887.0, 62655.0, 63350.0497492538, 1537.9077255364714, 1557.8130624325154, 1579.5498282989292, 1585.0, 1588.7299159142974, 27577.44845103127, 28046.494188226472, 28100.718992810627, 28285.717142829133, 28367.148985530468, 40932.1813563733, 41110.588894111876, 41121.794391027855, 41173.382399263435, 41186.38220426636, 214189.0, 215576.0, 215644.0, 215908.0, 216496.0, 36644.63355366519, 37962.0, 38792.80603596964, 38857.80571097126, 38993.610063898996, 46481.0, 47679.0, 48235.75882120567, 48278.275825861936, 48737.0, 35130.82434587811, 35382.29235415226, 35581.288374232885, 35648.465273021444, 35783.28433431369, 35428.0, 35526.46710299296, 35810.64189358073, 35836.64163358333, 36155.457668135525, 39363.0, 39546.2090758189, 39618.0, 39849.20301593894, 39939.201215974936, 33462.832685836416, 33634.49548256729, 33805.49291760576, 33992.320153597284, 34237.486437703956, 23861.0, 24292.757072429755, 24313.149039783522, 24467.0, 24657.38356541029], "value": 17826.652806497616, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [573.6737529318611, 574.4829653312021, 578.5747475605432, 588.8939990801655, 599.2928344553427, 22957.42606434838, 23097.076116955344, 23649.881750591245, 23799.405014874636, 23849.284521464382, 26456.338591535172, 26905.86547067262, 27082.59376109355, 27086.729132708668, 27123.0, 6444.935550644504, 6461.256955450113, 6551.639659818708, 6598.307177746339, 6601.768938087168, 6322.114903913456, 6552.868942621145, 6611.735530578771, 6637.137172167636, 6652.234992975821, 6591.044298576703, 6597.2083349998065, 6599.472042236634, 6600.339966003386, 6605.0, 6532.575382600139, 6535.8692826143215, 6559.639219842907, 6591.176102987127, 6603.768868089618, 6108.3280839107665, 6151.446369826737, 6152.938470615281, 6153.446189842935, 6161.137440758297, 29539.409211815644, 29932.401351973058, 30473.0, 31339.686603133905, 31411.84294078538, 1962.1954998450567, 1986.2253721048803, 1996.6206420780006, 1998.680211166221, 1999.6100760351592, 19547.706784398244, 19712.70430943537, 20569.0, 20698.172073117057, 20788.168473261052, 2411.686480757508, 2446.412860913368, 2500.374906273432, 2550.6301586270074, 2560.46230291639, 124762.3761881194, 131806.0, 134746.0, 150401.24799376042, 152713.23643381824, 14852.554423367315, 14932.850671493363, 15339.693206136038, 15430.151341676315, 15619.531414057596, 23602.527949440915, 23609.527809443716, 23614.527709445716, 23705.288841334786, 23732.88133559321, 10043.548040338203, 10098.899011009797, 10180.0, 10238.53926573306, 10270.0, 9277.16505514507, 9312.16190542841, 9319.627214911328, 9395.0, 9446.244300455946, 7816.843663126706, 9314.487703176255, 9570.47362395075, 9585.712428627152, 9601.615935362646, 2855.614492043589, 2872.1096460097438, 2872.4973129702216, 2875.353045564773, 2976.7469765070214, 954.8615450759601, 970.975620720133, 975.9268054895912, 982.6806287956442, 983.4050399508337, 19601.901990489958, 24964.750352496605, 25146.0, 25433.0, 25706.0, 994.4431118573622, 998.1166667499315, 1065.6962765611763, 1069.6149386220955, 1075.2580719303655, 10537.473126343806, 10802.351858888334, 11005.284656497302, 11376.829347559793, 11532.005036071878, 507178.0, 509371.0, 509876.0, 533976.0, 560294.0, 131164.68835311715, 132547.33726331498, 132668.33665831608, 135409.0, 137565.0, 1257.9685507862378, 1271.8791714787264, 1294.0553396020957, 1300.232862611064, 1307.4966138036734, 21036.26373076935, 21171.8941405292, 21318.573628527654, 21639.45901352416, 21651.458713532287, 59403.702981484814, 60095.699521502116, 60560.697196513735, 61953.0, 62230.68884655548, 72525.6373718128, 72904.27095728974, 73601.63199183969, 74030.88953665807, 74369.0, 16345.427910023092, 21108.366748997556, 21878.562428751655, 22435.887820561446, 22636.0, 24557.87721061383, 24732.629010564495, 24857.378565536008, 24884.0, 25006.874965625055, 18291.54271143179, 18672.533186669898, 18706.06469676538, 18778.624427511648, 18789.812101878808, 17173.141342932555, 17381.391651292142, 17550.82449175492, 17646.73529897027, 17728.55678608045, 19669.114889829707, 19848.40454786359, 19861.503462413555, 20056.799432005493, 20130.29543965954, 20655.483612909797, 20694.379168624964, 20891.686624700338, 21254.46863828355, 21305.0, 17468.388606399225, 18655.347062852736, 18747.718784217974, 18773.90613046926, 18942.337018204296], "value": 12667.922324697582, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1390.6801435669795, 1414.7736362182052, 1441.9206943618096, 1446.7106578684266, 1449.1667291307501, 13445.7983130253, 13963.301834908265, 13973.79039314411, 13976.6505837354, 14016.929915350422, 13111.47554097835, 13649.931750341235, 13871.4451421943, 14006.22965736884, 14009.369578368998, 2598.883050262738, 2639.6172554979516, 2641.28685254981, 2642.114891511346, 2643.9556375231773, 2441.084593277521, 2511.8618475983812, 2541.682289713786, 2629.9605505917425, 2630.69746979097, 2632.8946842126293, 2634.196570046135, 2661.4011847334386, 2663.987684679819, 2665.3203433124622, 2493.4265119022634, 2563.1669707345095, 2563.423229773286, 2564.12819641321, 2565.5125526150064, 2515.5220508103403, 2534.4550921551954, 2563.93596657387, 2608.087169490663, 2614.372550587864, 13868.0, 13890.23603701796, 13891.166530008231, 13911.0, 13948.860511394858, 7326.450516211252, 7582.962085189594, 7583.7724868254045, 7589.354904833096, 7595.772126836204, 4713.481517033124, 4831.734254615994, 4999.625028122869, 5019.598432125421, 5028.346314979066, 1874.2128306111465, 1886.217219853759, 1887.2073729033702, 1938.6607343714934, 1975.190172029461, 22283.44291392712, 22569.66145507819, 22815.201467948544, 22836.200732974266, 23013.88493057541, 6052.455279024821, 6297.149884765589, 6334.144890439732, 6341.524385671093, 6355.745770169141, 15550.76673849893, 15711.607209819618, 15764.369425222863, 15773.132477713607, 15808.051516909023, 7203.8559228816175, 7428.85142297151, 7430.554166750012, 7434.070741157356, 7439.85120297591, 6661.733530658817, 6711.63086030273, 6865.794026179223, 6878.312168783182, 6888.207856096513, 4871.585915197248, 4983.426905905795, 4989.351384320088, 5002.249662550574, 5014.17266151081, 1398.2519352146553, 1467.207707837777, 1469.7060587882465, 1500.3848422146868, 1507.1183357735629, 877.9868301975475, 904.5477261369315, 912.2109375390239, 925.144241576539, 930.6975233049286, 7130.5112347764325, 7173.89239161413, 7175.677094530654, 7219.386352160125, 7327.560346379234, 715.2632788228134, 725.9528130671489, 737.0565675934705, 737.863689917521, 739.4675833399943, 4546.909061818745, 5095.974520127375, 5310.7610157543, 5422.511973922287, 5624.268845050199, 19144.425667230007, 19240.903795480932, 19562.510937226398, 19572.510687232934, 19573.706394404096, 17541.210645520983, 17868.195931183127, 17887.0, 17909.7313540297, 17960.820391795914, 11064.391458469701, 11121.388323642115, 11127.610533631285, 11150.21948463616, 11160.330380177053, 7456.440766942609, 8053.83892322162, 8068.193180682119, 8316.62575184106, 8335.541545214828, 15042.247887605794, 15521.68956620884, 15529.534113976599, 16776.74834877502, 17036.65926681484, 16629.667406652043, 16993.490195294162, 17121.914390428465, 17182.828171718123, 17184.140792960054, 10939.78120437571, 11019.393933333744, 11050.0, 11086.556537738723, 11134.888651113386, 12878.0, 12971.481140754264, 12991.0, 13022.088453808143, 13033.934830326167, 10400.323978941648, 10479.266451348332, 10484.475776211311, 10602.787944240918, 10630.627928022483, 9739.805203896025, 9756.804863902824, 9759.365641233297, 9804.803903922024, 9805.1175394212, 9389.154976052188, 9550.522473876417, 9580.233581313618, 9586.760330991781, 9591.0, 6904.895216765494, 6906.758263460755, 6942.097527321416, 6984.895226571504, 6990.650467476504, 6577.704003319766, 6645.335466453316, 6652.600843949379, 6727.663616819237, 6753.763618273533], "value": 6208.710877420528, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [479.96255893731114, 501.30318856789046, 505.5424840519329, 510.96784495319446, 527.9841604751857, 6134.570580059392, 6205.379462053792, 6213.937860621392, 6218.22272215973, 6253.968730156348, 6146.754129834812, 6148.292946311176, 6173.938260617392, 6185.226846644169, 6224.28420731616, 4921.4094308682825, 4937.259411088347, 5042.495750424965, 5090.745462726858, 5096.617753668471, 4981.626378021645, 4983.3870443928045, 5007.0, 5025.798968041274, 5062.493750624945, 5017.899642007158, 5018.498150184971, 5025.472325405834, 5046.470120637336, 5061.569766569846, 5020.573251273646, 5031.899362012758, 5039.622028347871, 5044.192929131322, 5068.898622027576, 4546.749928753918, 4548.6808825440585, 4564.680472366936, 4570.565796249351, 4573.199690054227, 5920.467157955762, 5992.9700351498395, 6041.184440100574, 6059.151718759334, 6083.878322433571, 5624.0, 5674.801381951653, 5693.743781529841, 5736.311642602873, 5739.827805165852, 6538.934610653928, 6607.579370435332, 6632.2704502504685, 6645.734170633169, 6649.93350066498, 4892.461829198785, 4894.0, 4927.6550641455115, 4932.161532539477, 4972.129877271463, 6664.966675166593, 6738.0, 6833.89749153768, 6873.553219040747, 6953.20038195609, 4957.479464656231, 4958.578520825698, 4978.2034874419915, 5002.59979201663, 5073.746312684351, 5121.206213036985, 5131.615128865275, 5136.8715782105, 5140.614453915971, 5154.252633368215, 5695.202671625976, 5735.623450371881, 5766.509846663081, 5772.163036359789, 5779.450952159538, 6642.568233064835, 6670.499712521483, 6673.866522669519, 6678.4991125665765, 6714.496412769062, 1784.1525275494037, 1801.8468180364578, 1804.0799192411703, 1805.9006754628622, 1806.3768000039966, 1174.7063234191453, 1226.7853125702966, 1239.3617287097152, 1241.453760345445, 1266.379474057705, 5774.566907481955, 5806.1000544915605, 5810.331811841692, 5840.678762668094, 5917.349091599921, 6810.182778066664, 6811.2167100784045, 6812.863742725217, 6828.0, 6878.965605171942, 5754.0, 5812.186293918855, 5822.475977162076, 5836.970815145897, 5853.678047707417, 3535.681788639035, 3585.8386372613295, 3595.352836489457, 3631.0559254593995, 3655.2089476157025, 6518.28298887122, 6518.934810651833, 6587.473002159816, 6609.678064387142, 6609.900851487231, 6882.965585172042, 6929.44564434844, 6948.513604047769, 6972.58164510131, 6977.162740471176, 6402.35976402356, 6452.774152904626, 6460.806175814733, 6495.350464953467, 6539.945233954978, 6177.62934223948, 6577.967110164419, 6623.238327592196, 6701.3298670134545, 6756.45948324123, 6599.769008084694, 6623.6688165592495, 6632.801015969529, 6668.633225172662, 6675.332466753286, 6482.513811464253, 6501.147173055657, 6542.672866356568, 6548.738050477928, 6594.604323740591, 5061.6962982221185, 5064.974675126601, 5069.188929771219, 5090.592752579859, 5103.617228707788, 6487.18910136233, 6640.601563906181, 6694.83262918431, 6847.0, 6879.277675843964, 6382.266039405342, 6394.200724909387, 6447.806565803034, 6497.317781632861, 6560.606363618007, 6497.902531462127, 6528.967355163194, 6558.376954189344, 6573.079768832272, 6637.933620663925, 6366.299707032315, 6450.8064758057335, 6463.64449955257, 6490.935090649033, 6510.18622672166, 6519.869602607826, 6541.182352205974, 6554.705038273384, 6568.934310657023, 6616.867662646817, 6394.936050639434, 6396.680165991589, 6397.680115994275, 6462.579932304385, 6473.514486413444], "value": 5079.956073879406, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [891.3715830339611, 912.5984566790612, 925.5464822237097, 927.9164875161239, 940.6613619097124, 18655.0, 19402.51493712656, 19413.417597472064, 19540.41378758636, 19565.706514402296, 17800.64398712025, 17878.642427151448, 18330.450086497425, 22157.00293486793, 22334.88832555839, 1041.1254546181206, 1053.6733612580085, 1055.767731099157, 1057.2387880725903, 1057.6932689520067, 1013.6417200950724, 1071.79635869185, 1072.5709716113538, 1074.274864466486, 1078.1482628723338, 1093.3713114958891, 1095.8794532601407, 1096.9122470202406, 1097.2812807611028, 1097.4732128578287, 1085.5820509104012, 1088.8747794003712, 1089.6513115802986, 1095.7151140703395, 1098.2751384086544, 797.9680812767482, 802.4182467710921, 802.4823988527426, 802.6067227058758, 804.5092493578912, 20135.395938121732, 20225.494362640904, 20356.18575256988, 20368.490787730276, 20637.380878573666, 22162.667559986614, 22325.44186395337, 22445.551088978293, 22547.210847620256, 23009.309720708407, 16533.50399488017, 16600.418985335575, 16713.582160446087, 16782.496525104267, 16899.915500422543, 3541.8583256669704, 3551.3607550640872, 3556.05764472415, 3561.002919182633, 3583.4445660922597, 123202.76797232003, 123295.38352308271, 123535.0, 125076.0, 126932.0, 16468.0942548161, 17066.914665426593, 17152.914235428743, 17430.738538921927, 17436.47690569285, 30271.69728302733, 31053.223669407995, 31632.683673162974, 31717.04848854538, 31930.2017449561, 9040.09599040104, 9277.48973806447, 9287.72136835896, 9287.860682089638, 9312.301577381724, 4392.890177745582, 4402.7358358498595, 4467.0, 4515.616172625298, 4537.455505339381, 5264.6051546134195, 5305.1776974569, 5343.438938911436, 5372.435894231049, 5392.191171324333, 1043.9060484556426, 1077.5743581285506, 1089.3953855610212, 1097.8298363753631, 1099.8680158381046, 689.5380095336137, 704.0143798681784, 711.6015031582331, 717.1215261304964, 726.7129483853848, 11750.29498230109, 11870.465829037714, 11879.88120118805, 12298.139130260975, 12459.501619935101, 698.4028655499576, 704.7815177295055, 708.6315116139579, 710.9075820143452, 715.7995761186876, 5257.0, 5262.578993680497, 5342.252084708144, 5391.81128660495, 5424.783008679688, 110566.0, 114957.0, 114977.0, 116495.0, 123391.3830430842, 80608.59695701484, 98558.50720746494, 99192.50403747935, 99828.0, 104309.0, 70610.0, 85200.57399713085, 86837.0, 88287.55856220677, 91420.5428972851, 12017.939910300393, 12421.0, 12566.623001309976, 12783.424745886272, 13117.475300987853, 38170.0, 38934.6106538931, 39230.80384598059, 40019.79990100031, 40179.799101005476, 52744.0, 73383.0, 74115.62942185254, 77676.0, 78032.8295075563, 18771.90614046921, 19150.80849191546, 19175.328863490267, 19437.51406214856, 20272.290469833486, 22160.0, 22230.44423889351, 22438.88780556087, 22449.10203591838, 22569.5486090274, 18067.909660451613, 18543.814561854208, 18579.72130418072, 18914.905425472785, 18921.810781892003, 19547.609047818678, 19805.504862378555, 19871.900640496704, 19932.202711891943, 20038.799612003695, 19234.615307693486, 19793.802061979197, 20072.799272007676, 20100.296489622793, 20176.39470815878, 13587.0, 13613.319334033456, 13903.165810051429, 13973.371198296305, 14107.929460352632, 14874.33065512033, 15068.54794356171, 15104.169270690218, 15264.618384540476, 15481.767773483632], "value": 9422.016120058257, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1551.0, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 107.0, 100.0, 107.0, 109.0, 110.0, 108.0, 99.0, 108.0, 108.0], "value": 105.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 117.0, 146.0, 165.0, 119.0, 143.0, 147.0, 118.0, 167.0, 118.0], "value": 138.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 162.0, 164.0, 162.0, 164.0, 164.0, 163.0, 178.0, 171.0, 166.0], "value": 165.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 75.0, 84.0, 187.0, 76.0, 76.0, 74.0, 74.0, 155.0, 78.0], "value": 96.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 79.0, 131.0, 87.0, 79.0, 85.0, 134.0, 79.0, 79.0, 133.0], "value": 96.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 114.0, 114.0, 114.0, 113.0, 116.0, 117.0, 116.0, 118.0, 115.0], "value": 115.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 152.0, 156.0, 151.0, 151.0, 151.0, 152.0, 150.0, 152.0, 153.0], "value": 152.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 94.0, 95.0, 94.0, 93.0, 94.0, 95.0, 94.0], "value": 94.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 58.0, 55.0, 56.0, 58.0, 61.0, 54.0, 53.0, 53.0, 55.0], "value": 55.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 55.0, 55.0, 54.0, 55.0, 54.0, 53.0, 55.0, 61.0, 55.0], "value": 55.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 94.0, 84.0, 88.0, 88.0, 98.0, 77.0, 88.0, 93.0, 78.0], "value": 87.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [128.0, 140.0, 133.0, 141.0, 133.0, 139.0, 145.0, 128.0, 130.0, 176.0], "value": 139.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 179.0, 187.0, 173.0, 175.0, 182.0, 182.0, 181.0, 192.0, 183.0], "value": 181.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 74.0, 65.0, 67.0, 65.0, 72.0, 67.0, 64.0, 66.0, 73.0], "value": 68.5, "unit": "score"}]}]} 18:40:22 INFO - Return code: 0 18:40:22 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:40:22 INFO - # TBPL SUCCESS # 18:40:22 INFO - Running post-action listener: _package_coverage_data 18:40:22 INFO - Running post-action listener: _resource_record_post_action 18:40:22 INFO - [mozharness: 2017-08-11 01:40:22.944949Z] Finished run-tests step (success) 18:40:22 INFO - Running post-run listener: _resource_record_post_run 18:40:23 INFO - Total resource usage - Wall time: 1194s; CPU: 13.0%; Read bytes: 45056; Write bytes: 445669376; Read time: 408; Write time: 272268 18:40:23 INFO - TinderboxPrint: CPU usage
13.2% 18:40:23 INFO - TinderboxPrint: I/O read bytes / time
45,056 / 408 18:40:23 INFO - TinderboxPrint: I/O write bytes / time
445,669,376 / 272,268 18:40:23 INFO - TinderboxPrint: CPU idle
8,264.8 (86.8%) 18:40:23 INFO - TinderboxPrint: CPU user
1,205.1 (12.7%) 18:40:23 INFO - TinderboxPrint: Swap in / out
0 / 0 18:40:23 INFO - install - Wall time: 19s; CPU: 13.0%; Read bytes: 4096; Write bytes: 117956608; Read time: 192; Write time: 77468 18:40:23 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 18:40:23 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 36864; Write bytes: 327712768; Read time: 204; Write time: 194800 18:40:23 INFO - Running post-run listener: _upload_blobber_files 18:40:23 INFO - Blob upload gear active. 18:40:23 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:40:23 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 18:40:23 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'] 18:40:23 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 18:40:24 INFO - (blobuploader) - INFO - Open directory for files ... 18:40:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 18:40:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:40:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:40:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 18:40:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:40:24 INFO - (blobuploader) - INFO - Done attempting. 18:40:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:40:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:40:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:40:32 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:40:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:40:32 INFO - (blobuploader) - INFO - Done attempting. 18:40:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 18:40:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:40:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:40:33 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 18:40:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:40:33 INFO - (blobuploader) - INFO - Done attempting. 18:40:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 18:40:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:40:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:40:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 18:40:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:40:34 INFO - (blobuploader) - INFO - Done attempting. 18:40:34 INFO - (blobuploader) - INFO - Iteration through files over. 18:40:34 INFO - Return code: 0 18:40:34 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:40:34 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:40:34 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9e3b2888680622835467d191b3b7ca3ed29696aff4af8d70fb6e2534388b8100745c4a1766438a83ed67e878b8c6709cedd0f98bf8f1e52a402931997351ab3f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8536e62a0fbeffe3ffd3a9bd88d79eb6c42ada270e06ee5929869d93b9949ff833ceca5585465c66c2cdad067feb2355b40b9db7e3327a8d11bf076490bb1adf", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/19d96282c51b8e7540326eb34f5317ad5b07255877997a9f19ef30a50c28b503bb72d7a835b14ea3356d2a2383d86d00527515c17d0d08020cc9307179b29ebd", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d102f4de270adc1b0e958cfea51132fd8acfdd55993556e2a667f83d23c37aa27b15b4149617216e95881c1fc680684b77145c401396dd30cfd926b6f8f9312d"} 18:40:34 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:40:34 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:40:34 INFO - Contents: 18:40:34 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9e3b2888680622835467d191b3b7ca3ed29696aff4af8d70fb6e2534388b8100745c4a1766438a83ed67e878b8c6709cedd0f98bf8f1e52a402931997351ab3f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8536e62a0fbeffe3ffd3a9bd88d79eb6c42ada270e06ee5929869d93b9949ff833ceca5585465c66c2cdad067feb2355b40b9db7e3327a8d11bf076490bb1adf", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/19d96282c51b8e7540326eb34f5317ad5b07255877997a9f19ef30a50c28b503bb72d7a835b14ea3356d2a2383d86d00527515c17d0d08020cc9307179b29ebd", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d102f4de270adc1b0e958cfea51132fd8acfdd55993556e2a667f83d23c37aa27b15b4149617216e95881c1fc680684b77145c401396dd30cfd926b6f8f9312d"} 18:40:34 INFO - Running post-run listener: copy_logs_to_upload_dir 18:40:34 INFO - Copying logs to upload dir... 18:40:34 INFO - mkdir: /builds/slave/test/build/upload/logs 18:40:34 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1261.921430 ========= master_lag: 0.27 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2017-08-10 18:40:34.268504) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-10 18:40:34.271787) ========= 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=923d835db2ba3686ebe126b700000192-1502413822.384271-334355300 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/9e3b2888680622835467d191b3b7ca3ed29696aff4af8d70fb6e2534388b8100745c4a1766438a83ed67e878b8c6709cedd0f98bf8f1e52a402931997351ab3f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8536e62a0fbeffe3ffd3a9bd88d79eb6c42ada270e06ee5929869d93b9949ff833ceca5585465c66c2cdad067feb2355b40b9db7e3327a8d11bf076490bb1adf", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/19d96282c51b8e7540326eb34f5317ad5b07255877997a9f19ef30a50c28b503bb72d7a835b14ea3356d2a2383d86d00527515c17d0d08020cc9307179b29ebd", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d102f4de270adc1b0e958cfea51132fd8acfdd55993556e2a667f83d23c37aa27b15b4149617216e95881c1fc680684b77145c401396dd30cfd926b6f8f9312d"} build_url:https://queue.taskcluster.net/v1/task/dTnd9BLdQ-u7ChXvVPHXNw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011214 build_url: 'https://queue.taskcluster.net/v1/task/dTnd9BLdQ-u7ChXvVPHXNw/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9e3b2888680622835467d191b3b7ca3ed29696aff4af8d70fb6e2534388b8100745c4a1766438a83ed67e878b8c6709cedd0f98bf8f1e52a402931997351ab3f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8536e62a0fbeffe3ffd3a9bd88d79eb6c42ada270e06ee5929869d93b9949ff833ceca5585465c66c2cdad067feb2355b40b9db7e3327a8d11bf076490bb1adf", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/19d96282c51b8e7540326eb34f5317ad5b07255877997a9f19ef30a50c28b503bb72d7a835b14ea3356d2a2383d86d00527515c17d0d08020cc9307179b29ebd", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d102f4de270adc1b0e958cfea51132fd8acfdd55993556e2a667f83d23c37aa27b15b4149617216e95881c1fc680684b77145c401396dd30cfd926b6f8f9312d"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-10 18:40:34.296604) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-10 18:40:34.296904) ========= 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=923d835db2ba3686ebe126b700000192-1502413822.384271-334355300 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004487 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-10 18:40:34.379230) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-10 18:40:34.379549) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-10 18:40:34.379825) ========= ========= Total master_lag: 0.43 =========