builder: mozilla-central_ubuntu64_hw_styloseq_test-dromaeojs-e10s slave: talos-linux64-ix-003 starttime: 1502835303.62 results: success (0) revision: d25db0546c92afa72d61685c9310104ef28235bd ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-08-15 15:15:03.622636) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-08-15 15:15:03.623141) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-15 15:15:03.623416) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-15 15:15:03.682694) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-15 15:15:03.683001) ========= 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=7de08804244eb36560b44971000001c3-1502832647.67872-1550337739 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-08-15 15:15:03-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 37.0M=0s 2017-08-15 15:15:03 (37.0 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.199171 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-15 15:15:03.900291) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-15 15:15:03.900610) ========= 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=7de08804244eb36560b44971000001c3-1502832647.67872-1550337739 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018092 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-15 15:15:03.953638) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 13 secs) (at 2017-08-15 15:15:03.956984) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev d25db0546c92afa72d61685c9310104ef28235bd --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 d25db0546c92afa72d61685c9310104ef28235bd --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=7de08804244eb36560b44971000001c3-1502832647.67872-1550337739 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-08-15 15:15:03,994 truncating revision to first 12 chars 2017-08-15 15:15:03,994 Setting DEBUG logging. 2017-08-15 15:15:03,994 attempt 1/10 2017-08-15 15:15:03,994 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/d25db0546c92?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-08-15 15:15:04,906 attempt 1/10 2017-08-15 15:15:05,767 current task status: no status available at this point. state: STARTED 2017-08-15 15:15:05,767 sleeping for 11.00s (attempt 1/10) 2017-08-15 15:15:16,778 attempt 2/10 2017-08-15 15:15:17,090 current task status: Task completed! Check 's3_urls' for upload locations. state: SUCCESS 2017-08-15 15:15:17,311 unpacking tar archive at: mozilla-central-d25db0546c92/testing/mozharness/ program finished with exit code 0 elapsedTime=13.622923 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 13 secs) (at 2017-08-15 15:15:17.597934) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-15 15:15:17.598242) ========= script_repo_revision: d25db0546c92afa72d61685c9310104ef28235bd ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-15 15:15:17.598614) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-15 15:15:17.598892) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-15 15:15:17.610338) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 58 secs) (at 2017-08-15 15:15:17.610606) ========= /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=7de08804244eb36560b44971000001c3-1502832647.67872-1550337739 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:15:17 INFO - MultiFileLogger online at 20170815 15:15:17 in /builds/slave/test 15:15:17 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 15:15:17 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:15:17 INFO - {'append_to_log': False, 15:15:17 INFO - 'base_work_dir': '/builds/slave/test', 15:15:17 INFO - 'blob_upload_branch': 'Firefox', 15:15:17 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:15:17 INFO - 'branch': 'Firefox', 15:15:17 INFO - 'buildbot_json_path': 'buildprops.json', 15:15:17 INFO - 'code_coverage': False, 15:15:17 INFO - 'config_files': ('talos/linux_config.py',), 15:15:17 INFO - 'default_actions': ('clobber', 15:15:17 INFO - 'read-buildbot-config', 15:15:17 INFO - 'download-and-extract', 15:15:17 INFO - 'populate-webroot', 15:15:17 INFO - 'create-virtualenv', 15:15:17 INFO - 'install', 15:15:17 INFO - 'setup-mitmproxy', 15:15:17 INFO - 'run-tests'), 15:15:17 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:15:17 INFO - 'disable_ccov_upload': False, 15:15:17 INFO - 'download_minidump_stackwalk': True, 15:15:17 INFO - 'download_symbols': 'ondemand', 15:15:17 INFO - 'enable_stylo': False, 15:15:17 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:15:17 INFO - 'tooltool.py': '/tools/tooltool.py', 15:15:17 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:15:17 INFO - '/tools/misc-python/virtualenv.py')}, 15:15:17 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:15:17 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:15:17 INFO - 'gecko_profile': False, 15:15:17 INFO - 'gecko_profile_interval': 0, 15:15:17 INFO - 'installer_path': 'installer.exe', 15:15:17 INFO - 'log_level': 'info', 15:15:17 INFO - 'log_name': 'talos', 15:15:17 INFO - 'log_to_console': True, 15:15:17 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:15:17 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:15:17 INFO - 'opt_config_files': (), 15:15:17 INFO - 'pip_index': False, 15:15:17 INFO - 'suite': 'dromaeojs-e10s', 15:15:17 INFO - 'system_bits': '32', 15:15:17 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:15:17 INFO - 'title': 'talos-linux64-ix-003', 15:15:17 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:15:17 INFO - 'use_talos_json': True, 15:15:17 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:15:17 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:15:17 INFO - 'work_dir': 'build'} 15:15:17 INFO - [mozharness: 2017-08-15 22:15:17.749719Z] Running clobber step. 15:15:17 INFO - Running pre-action listener: _resource_record_pre_action 15:15:17 INFO - Running main action method: clobber 15:15:17 INFO - rmtree: /builds/slave/test/build 15:15:17 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:15:18 INFO - Running post-action listener: _resource_record_post_action 15:15:18 INFO - [mozharness: 2017-08-15 22:15:18.774938Z] Finished clobber step (success) 15:15:18 INFO - [mozharness: 2017-08-15 22:15:18.775040Z] Running read-buildbot-config step. 15:15:18 INFO - Running pre-action listener: _resource_record_pre_action 15:15:18 INFO - Running main action method: read_buildbot_config 15:15:18 INFO - Using buildbot properties: 15:15:18 INFO - { 15:15:18 INFO - "project": "", 15:15:18 INFO - "product": "firefox", 15:15:18 INFO - "who": "asasaki@mozilla.com", 15:15:18 INFO - "installer_path": "public/build/target.tar.bz2", 15:15:18 INFO - "builddir": "mozilla-central_ubuntu64_hw_styloseq_test-dromaeojs-e10s", 15:15:18 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 15:15:18 INFO - "buildername": "Ubuntu HW 12.04 x64 stylo-sequential mozilla-central talos dromaeojs-e10s", 15:15:18 INFO - "stage_platform": "linux64-stylosequential", 15:15:18 INFO - "basedir": "/builds/slave/test", 15:15:18 INFO - "buildnumber": 36, 15:15:18 INFO - "platform": "ubuntu64_hw_styloseq", 15:15:18 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 15:15:18 INFO - "slavebuilddir": "test", 15:15:18 INFO - "taskId": "GPHeuIubTtGvKRAJxk4rKA", 15:15:18 INFO - "branch": "mozilla-central", 15:15:18 INFO - "script_repo_revision": "production", 15:15:18 INFO - "revision": "d25db0546c92afa72d61685c9310104ef28235bd", 15:15:18 INFO - "slavename": "talos-linux64-ix-003", 15:15:18 INFO - "repo_path": "mozilla-central" 15:15:18 INFO - } 15:15:18 INFO - Finding installer, test and symbols from parent task. 15:15:18 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GPHeuIubTtGvKRAJxk4rKA'}, attempt #1 15:15:19 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GPHeuIubTtGvKRAJxk4rKA'}, attempt #1 15:15:19 INFO - Task dependencies: RMB3t9HoRkqz14OC2cjDLw 15:15:19 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RMB3t9HoRkqz14OC2cjDLw'}, attempt #1 15:15:20 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/RMB3t9HoRkqz14OC2cjDLw/artifacts/public/build/target.tar.bz2 15:15:20 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/RMB3t9HoRkqz14OC2cjDLw/artifacts/public/build/target.test_packages.json 15:15:20 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/RMB3t9HoRkqz14OC2cjDLw/artifacts/public/build/target.crashreporter-symbols.zip 15:15:20 INFO - Running post-action listener: _resource_record_post_action 15:15:20 INFO - [mozharness: 2017-08-15 22:15:20.162127Z] Finished read-buildbot-config step (success) 15:15:20 INFO - [mozharness: 2017-08-15 22:15:20.162348Z] Running download-and-extract step. 15:15:20 INFO - Running pre-action listener: _resource_record_pre_action 15:15:20 INFO - Running main action method: download_and_extract 15:15:20 INFO - mkdir: /builds/slave/test/build/tests 15:15:20 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')]} 15:15:20 INFO - trying https://queue.taskcluster.net/v1/task/RMB3t9HoRkqz14OC2cjDLw/artifacts/public/build/target.test_packages.json 15:15:20 INFO - Downloading https://queue.taskcluster.net/v1/task/RMB3t9HoRkqz14OC2cjDLw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 15:15:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RMB3t9HoRkqz14OC2cjDLw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 15:15:20 INFO - Downloaded 989 bytes. 15:15:20 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 15:15:20 INFO - Using the following test package requirements: 15:15:20 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 15:15:20 INFO - u'common': [u'target.common.tests.zip'], 15:15:20 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 15:15:20 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 15:15:20 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 15:15:20 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 15:15:20 INFO - u'mozbase': [u'target.common.tests.zip'], 15:15:20 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 15:15:20 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 15:15:20 INFO - u'web-platform': [u'target.common.tests.zip', 15:15:20 INFO - u'target.web-platform.tests.tar.gz'], 15:15:20 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 15:15:20 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 15:15:20 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RMB3t9HoRkqz14OC2cjDLw/artifacts/public/build/target.common.tests.zip 15:15:20 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RMB3t9HoRkqz14OC2cjDLw/artifacts/public/build/target.common.tests.zip'}, attempt #1 15:15:20 INFO - Fetch https://queue.taskcluster.net/v1/task/RMB3t9HoRkqz14OC2cjDLw/artifacts/public/build/target.common.tests.zip into memory 15:15:21 INFO - Content-Length response header: 54002592 15:15:21 INFO - Bytes received: 54002592 15:15:32 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 15:15:32 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RMB3t9HoRkqz14OC2cjDLw/artifacts/public/build/target.common.tests.zip 15:15:32 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RMB3t9HoRkqz14OC2cjDLw/artifacts/public/build/target.common.tests.zip'}, attempt #1 15:15:32 INFO - Fetch https://queue.taskcluster.net/v1/task/RMB3t9HoRkqz14OC2cjDLw/artifacts/public/build/target.common.tests.zip into memory 15:15:34 INFO - Content-Length response header: 54002592 15:15:34 INFO - Bytes received: 54002592 15:15:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RMB3t9HoRkqz14OC2cjDLw/artifacts/public/build/target.talos.tests.zip 15:15:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RMB3t9HoRkqz14OC2cjDLw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 15:15:48 INFO - Fetch https://queue.taskcluster.net/v1/task/RMB3t9HoRkqz14OC2cjDLw/artifacts/public/build/target.talos.tests.zip into memory 15:15:48 INFO - Content-Length response header: 14496050 15:15:48 INFO - Bytes received: 14496050 15:15:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:15:49 INFO - trying https://queue.taskcluster.net/v1/task/RMB3t9HoRkqz14OC2cjDLw/artifacts/public/build/target.tar.bz2 15:15:49 INFO - Downloading https://queue.taskcluster.net/v1/task/RMB3t9HoRkqz14OC2cjDLw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 15:15:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RMB3t9HoRkqz14OC2cjDLw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 15:15:51 INFO - Downloaded 61907779 bytes. 15:15:51 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/RMB3t9HoRkqz14OC2cjDLw/artifacts/public/build/target.tar.bz2 15:15:51 INFO - mkdir: /builds/slave/test/properties 15:15:51 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:15:51 INFO - Writing to file /builds/slave/test/properties/build_url 15:15:51 INFO - Contents: 15:15:51 INFO - build_url:https://queue.taskcluster.net/v1/task/RMB3t9HoRkqz14OC2cjDLw/artifacts/public/build/target.tar.bz2 15:15:51 INFO - Running post-action listener: _resource_record_post_action 15:15:51 INFO - Running post-action listener: set_extra_try_arguments 15:15:51 INFO - [mozharness: 2017-08-15 22:15:51.009987Z] Finished download-and-extract step (success) 15:15:51 INFO - [mozharness: 2017-08-15 22:15:51.010216Z] Running populate-webroot step. 15:15:51 INFO - Running pre-action listener: _resource_record_pre_action 15:15:51 INFO - Running main action method: populate_webroot 15:15:51 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:15:51 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:15:51 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 15:15:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:15:51 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 15:15:51 INFO - 'tests': ['damp', 'tps']}, 15:15:51 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:15:51 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 15:15:51 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 15:15:51 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 15:15:51 INFO - 'other-e10s': {'tests': ['a11yr', 15:15:51 INFO - 'ts_paint', 15:15:51 INFO - 'tpaint', 15:15:51 INFO - 'sessionrestore', 15:15:51 INFO - 'sessionrestore_many_windows', 15:15:51 INFO - 'sessionrestore_no_auto_restore', 15:15:51 INFO - 'tabpaint']}, 15:15:51 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 15:15:51 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 15:15:51 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:15:51 INFO - 'tsvgr_opacity', 15:15:51 INFO - 'tart', 15:15:51 INFO - 'tscrollx', 15:15:51 INFO - 'tsvg_static']}, 15:15:51 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 15:15:51 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:15:51 INFO - 'talos_options': ['--mitmproxy', 15:15:51 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:15:51 INFO - '--firstNonBlankPaint'], 15:15:51 INFO - 'tests': ['tp6_google', 15:15:51 INFO - 'tp6_youtube', 15:15:51 INFO - 'tp6_amazon', 15:15:51 INFO - 'tp6_facebook']}, 15:15:51 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:15:51 INFO - 'talos_options': ['--stylo', 15:15:51 INFO - '--mitmproxy', 15:15:51 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:15:51 INFO - '--firstNonBlankPaint'], 15:15:51 INFO - 'tests': ['tp6_google', 15:15:51 INFO - 'tp6_youtube', 15:15:51 INFO - 'tp6_amazon', 15:15:51 INFO - 'tp6_facebook']}, 15:15:51 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:15:51 INFO - 'talos_options': ['--stylo', 15:15:51 INFO - '--stylo-threads=1', 15:15:51 INFO - '--mitmproxy', 15:15:51 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:15:51 INFO - '--firstNonBlankPaint'], 15:15:51 INFO - 'tests': ['tp6_google', 15:15:51 INFO - 'tp6_youtube', 15:15:51 INFO - 'tp6_amazon', 15:15:51 INFO - 'tp6_facebook']}, 15:15:51 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 15:15:51 INFO - 'talos_options': ['--xperf_path', 15:15:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:15:51 INFO - 'tests': ['tp5n']}}} 15:15:51 INFO - Running post-action listener: _resource_record_post_action 15:15:51 INFO - [mozharness: 2017-08-15 22:15:51.027425Z] Finished populate-webroot step (success) 15:15:51 INFO - [mozharness: 2017-08-15 22:15:51.027515Z] Running create-virtualenv step. 15:15:51 INFO - Running pre-action listener: _resource_record_pre_action 15:15:51 INFO - Running main action method: create_virtualenv 15:15:51 INFO - Creating virtualenv /builds/slave/test/build/venv 15:15:51 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 15:15:51 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:15:51 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 15:15:51 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:15:51 INFO - Using real prefix '/usr' 15:15:51 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:15:51 INFO - Installing distribute.............................................................................................................................................................................................done. 15:15:53 INFO - Installing pip.................done. 15:15:53 INFO - Return code: 0 15:15:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 15:15:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 15:15:53 INFO - Reading from file tmpfile_stdout 15:15:53 INFO - Output received: 15:15:53 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 15:15:53 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:15:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:15:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:15:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:15:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:15:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e321f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ffea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x219fb20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21a2f00>, '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 0x219dba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x219e050>, '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': '7de08804244eb36560b44971000001c3-1502832647.67872-1550337739', '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 15:15:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 15:15:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 15:15:53 INFO - Using env: {'DISPLAY': ':0', 15:15:53 INFO - 'HOME': '/home/cltbld', 15:15:53 INFO - 'LANG': 'en_US.UTF-8', 15:15:53 INFO - 'LANGUAGE': 'en_US:en', 15:15:53 INFO - 'LOGNAME': 'cltbld', 15:15:53 INFO - 'MAIL': '/var/mail/cltbld', 15:15:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:15:53 INFO - 'MOZ_NO_REMOTE': '1', 15:15:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:15:53 INFO - 'NO_EM_RESTART': '1', 15:15:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:15:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:15:53 INFO - 'PWD': '/builds/slave/test', 15:15:53 INFO - 'SHELL': '/bin/bash', 15:15:53 INFO - 'SHLVL': '1', 15:15:53 INFO - 'TERM': 'linux', 15:15:53 INFO - 'TMOUT': '86400', 15:15:53 INFO - 'USER': 'cltbld', 15:15:53 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1502832647.67872-1550337739', 15:15:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:15:53 INFO - '_': '/tools/buildbot/bin/python'} 15:15:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:15:54 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 15:15:54 INFO - Cleaning up... 15:15:54 INFO - Return code: 0 15:15:54 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:15:54 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')]} 15:15:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:15:54 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')]} 15:15:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:15:54 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 0x1e321f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ffea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x219fb20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21a2f00>, '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 0x219dba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x219e050>, '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': '7de08804244eb36560b44971000001c3-1502832647.67872-1550337739', '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 15:15:54 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 15:15:54 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 15:15:54 INFO - Using env: {'DISPLAY': ':0', 15:15:54 INFO - 'HOME': '/home/cltbld', 15:15:54 INFO - 'LANG': 'en_US.UTF-8', 15:15:54 INFO - 'LANGUAGE': 'en_US:en', 15:15:54 INFO - 'LOGNAME': 'cltbld', 15:15:54 INFO - 'MAIL': '/var/mail/cltbld', 15:15:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:15:54 INFO - 'MOZ_NO_REMOTE': '1', 15:15:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:15:54 INFO - 'NO_EM_RESTART': '1', 15:15:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:15:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:15:54 INFO - 'PWD': '/builds/slave/test', 15:15:54 INFO - 'SHELL': '/bin/bash', 15:15:54 INFO - 'SHLVL': '1', 15:15:54 INFO - 'TERM': 'linux', 15:15:54 INFO - 'TMOUT': '86400', 15:15:54 INFO - 'USER': 'cltbld', 15:15:54 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1502832647.67872-1550337739', 15:15:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:15:54 INFO - '_': '/tools/buildbot/bin/python'} 15:15:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:15:54 INFO - Downloading/unpacking psutil>=3.1.1 15:15:54 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 15:15:54 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 15:15:54 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 15:15:54 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 15:15:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:15:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:15:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:15:55 INFO - Installing collected packages: psutil 15:15:55 INFO - Running setup.py install for psutil 15:15:55 INFO - building 'psutil._psutil_linux' extension 15:15:55 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 15:15:55 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 15:15:55 INFO - building 'psutil._psutil_posix' extension 15:15:55 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 15:15:55 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 15:15:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:15:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:15:55 INFO - Successfully installed psutil 15:15:55 INFO - Cleaning up... 15:15:55 INFO - Return code: 0 15:15:55 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:15:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:15:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:15:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:15:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:15:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e321f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ffea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x219fb20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21a2f00>, '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 0x219dba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x219e050>, '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': '7de08804244eb36560b44971000001c3-1502832647.67872-1550337739', '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 15:15:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 15:15:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 15:15:55 INFO - Using env: {'DISPLAY': ':0', 15:15:55 INFO - 'HOME': '/home/cltbld', 15:15:55 INFO - 'LANG': 'en_US.UTF-8', 15:15:55 INFO - 'LANGUAGE': 'en_US:en', 15:15:55 INFO - 'LOGNAME': 'cltbld', 15:15:55 INFO - 'MAIL': '/var/mail/cltbld', 15:15:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:15:55 INFO - 'MOZ_NO_REMOTE': '1', 15:15:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:15:55 INFO - 'NO_EM_RESTART': '1', 15:15:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:15:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:15:55 INFO - 'PWD': '/builds/slave/test', 15:15:55 INFO - 'SHELL': '/bin/bash', 15:15:55 INFO - 'SHLVL': '1', 15:15:55 INFO - 'TERM': 'linux', 15:15:55 INFO - 'TMOUT': '86400', 15:15:55 INFO - 'USER': 'cltbld', 15:15:55 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1502832647.67872-1550337739', 15:15:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:15:55 INFO - '_': '/tools/buildbot/bin/python'} 15:15:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:15:56 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:15:56 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 15:15:56 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 15:15:56 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 15:15:56 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 15:15:57 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:15:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:15:57 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:15:57 INFO - Installing collected packages: mozsystemmonitor 15:15:57 INFO - Running setup.py install for mozsystemmonitor 15:15:57 INFO - Successfully installed mozsystemmonitor 15:15:57 INFO - Cleaning up... 15:15:57 INFO - Return code: 0 15:15:57 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:15:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:15:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:15:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:15:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:15:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e321f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ffea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x219fb20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21a2f00>, '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 0x219dba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x219e050>, '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': '7de08804244eb36560b44971000001c3-1502832647.67872-1550337739', '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 15:15:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 15:15:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 15:15:57 INFO - Using env: {'DISPLAY': ':0', 15:15:57 INFO - 'HOME': '/home/cltbld', 15:15:57 INFO - 'LANG': 'en_US.UTF-8', 15:15:57 INFO - 'LANGUAGE': 'en_US:en', 15:15:57 INFO - 'LOGNAME': 'cltbld', 15:15:57 INFO - 'MAIL': '/var/mail/cltbld', 15:15:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:15:57 INFO - 'MOZ_NO_REMOTE': '1', 15:15:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:15:57 INFO - 'NO_EM_RESTART': '1', 15:15:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:15:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:15:57 INFO - 'PWD': '/builds/slave/test', 15:15:57 INFO - 'SHELL': '/bin/bash', 15:15:57 INFO - 'SHLVL': '1', 15:15:57 INFO - 'TERM': 'linux', 15:15:57 INFO - 'TMOUT': '86400', 15:15:57 INFO - 'USER': 'cltbld', 15:15:57 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1502832647.67872-1550337739', 15:15:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:15:57 INFO - '_': '/tools/buildbot/bin/python'} 15:15:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:15:57 INFO - Downloading/unpacking jsonschema==2.5.1 15:15:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:15:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:15:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:15:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:15:58 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:15:58 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:15:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:15:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:15:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:15:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:15:58 INFO - Downloading functools32-3.2.3-2.tar.gz 15:15:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:15:58 INFO - warning: no files found matching '*.txt' 15:15:58 INFO - no previously-included directories found matching 'build' 15:15:58 INFO - no previously-included directories found matching 'dist' 15:15:58 INFO - no previously-included directories found matching '.git*' 15:15:59 INFO - Installing collected packages: jsonschema, functools32 15:15:59 INFO - Running setup.py install for functools32 15:15:59 INFO - warning: no files found matching '*.txt' 15:15:59 INFO - no previously-included directories found matching 'build' 15:15:59 INFO - no previously-included directories found matching 'dist' 15:15:59 INFO - no previously-included directories found matching '.git*' 15:15:59 INFO - Successfully installed jsonschema functools32 15:15:59 INFO - Cleaning up... 15:15:59 INFO - Return code: 0 15:15:59 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:15:59 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')]} 15:15:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:15:59 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')]} 15:15:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:15:59 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 0x1e321f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ffea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x219fb20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21a2f00>, '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 0x219dba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x219e050>, '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': '7de08804244eb36560b44971000001c3-1502832647.67872-1550337739', '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 15:15:59 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 15:15:59 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 15:15:59 INFO - Using env: {'DISPLAY': ':0', 15:15:59 INFO - 'HOME': '/home/cltbld', 15:15:59 INFO - 'LANG': 'en_US.UTF-8', 15:15:59 INFO - 'LANGUAGE': 'en_US:en', 15:15:59 INFO - 'LOGNAME': 'cltbld', 15:15:59 INFO - 'MAIL': '/var/mail/cltbld', 15:15:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:15:59 INFO - 'MOZ_NO_REMOTE': '1', 15:15:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:15:59 INFO - 'NO_EM_RESTART': '1', 15:15:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:15:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:15:59 INFO - 'PWD': '/builds/slave/test', 15:15:59 INFO - 'SHELL': '/bin/bash', 15:15:59 INFO - 'SHLVL': '1', 15:15:59 INFO - 'TERM': 'linux', 15:15:59 INFO - 'TMOUT': '86400', 15:15:59 INFO - 'USER': 'cltbld', 15:15:59 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1502832647.67872-1550337739', 15:15:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:15:59 INFO - '_': '/tools/buildbot/bin/python'} 15:15:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:15:59 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:15:59 INFO - Cleaning up... 15:15:59 INFO - Return code: 0 15:15:59 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:15:59 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')]} 15:15:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:15:59 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')]} 15:15:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:15:59 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 0x1e321f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ffea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x219fb20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21a2f00>, '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 0x219dba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x219e050>, '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': '7de08804244eb36560b44971000001c3-1502832647.67872-1550337739', '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 15:15:59 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 15:15:59 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 15:15:59 INFO - Using env: {'DISPLAY': ':0', 15:15:59 INFO - 'HOME': '/home/cltbld', 15:15:59 INFO - 'LANG': 'en_US.UTF-8', 15:15:59 INFO - 'LANGUAGE': 'en_US:en', 15:15:59 INFO - 'LOGNAME': 'cltbld', 15:15:59 INFO - 'MAIL': '/var/mail/cltbld', 15:15:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:15:59 INFO - 'MOZ_NO_REMOTE': '1', 15:15:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:15:59 INFO - 'NO_EM_RESTART': '1', 15:15:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:15:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:15:59 INFO - 'PWD': '/builds/slave/test', 15:15:59 INFO - 'SHELL': '/bin/bash', 15:15:59 INFO - 'SHLVL': '1', 15:15:59 INFO - 'TERM': 'linux', 15:15:59 INFO - 'TMOUT': '86400', 15:15:59 INFO - 'USER': 'cltbld', 15:15:59 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1502832647.67872-1550337739', 15:15:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:15:59 INFO - '_': '/tools/buildbot/bin/python'} 15:15:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:15:59 INFO - Downloading/unpacking blobuploader==1.2.4 15:15:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:15:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:15:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:15:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:16:00 INFO - Downloading blobuploader-1.2.4.tar.gz 15:16:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:16:00 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:16:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:16:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:16:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:16:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:16:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:16:01 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:16:01 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 15:16:01 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 15:16:01 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 15:16:01 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 15:16:01 INFO - Downloading docopt-0.6.1.tar.gz 15:16:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:16:01 INFO - Installing collected packages: blobuploader, requests, docopt 15:16:01 INFO - Running setup.py install for blobuploader 15:16:01 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:16:01 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:16:01 INFO - Running setup.py install for requests 15:16:01 INFO - Running setup.py install for docopt 15:16:01 INFO - Successfully installed blobuploader requests docopt 15:16:01 INFO - Cleaning up... 15:16:01 INFO - Return code: 0 15:16:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:16:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:16:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:16:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:16:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:16:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e321f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ffea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x219fb20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21a2f00>, '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 0x219dba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x219e050>, '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': '7de08804244eb36560b44971000001c3-1502832647.67872-1550337739', '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 15:16:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 15:16:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:16:01 INFO - Using env: {'DISPLAY': ':0', 15:16:01 INFO - 'HOME': '/home/cltbld', 15:16:01 INFO - 'LANG': 'en_US.UTF-8', 15:16:01 INFO - 'LANGUAGE': 'en_US:en', 15:16:01 INFO - 'LOGNAME': 'cltbld', 15:16:01 INFO - 'MAIL': '/var/mail/cltbld', 15:16:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:16:01 INFO - 'MOZ_NO_REMOTE': '1', 15:16:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:16:01 INFO - 'NO_EM_RESTART': '1', 15:16:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:16:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:16:01 INFO - 'PWD': '/builds/slave/test', 15:16:01 INFO - 'SHELL': '/bin/bash', 15:16:01 INFO - 'SHLVL': '1', 15:16:01 INFO - 'TERM': 'linux', 15:16:01 INFO - 'TMOUT': '86400', 15:16:01 INFO - 'USER': 'cltbld', 15:16:01 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1502832647.67872-1550337739', 15:16:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:16:01 INFO - '_': '/tools/buildbot/bin/python'} 15:16:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:16:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:16:02 INFO - Running setup.py (path:/tmp/pip-Be2fdq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:16:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:16:02 INFO - Running setup.py (path:/tmp/pip-SFf9ze-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:16:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:16:02 INFO - Running setup.py (path:/tmp/pip-8bQppf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:16:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:16:02 INFO - Running setup.py (path:/tmp/pip-cNMCk_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:16:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:16:02 INFO - Running setup.py (path:/tmp/pip-V2tiST-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:16:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:16:02 INFO - Running setup.py (path:/tmp/pip-nfxnmd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:16:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:16:02 INFO - Running setup.py (path:/tmp/pip-m2eQ9Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:16:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:16:02 INFO - Running setup.py (path:/tmp/pip-P_ZsKT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:16:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:16:02 INFO - Running setup.py (path:/tmp/pip-W1wBTE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:16:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:16:02 INFO - Running setup.py (path:/tmp/pip-bgOdET-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:16:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:16:02 INFO - Running setup.py (path:/tmp/pip-dEli1F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:16:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:16:02 INFO - Running setup.py (path:/tmp/pip-G68ub9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:16:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:16:03 INFO - Running setup.py (path:/tmp/pip-AMIzeS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:16:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:16:03 INFO - Running setup.py (path:/tmp/pip-wpJcDE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:16:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:16:03 INFO - Running setup.py (path:/tmp/pip-kCnnYe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:16:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:16:03 INFO - Running setup.py (path:/tmp/pip-wijAiN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:16:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:16:03 INFO - Running setup.py (path:/tmp/pip-mTIIeh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:16:03 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:16:03 INFO - Running setup.py install for manifestparser 15:16:03 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:16:03 INFO - Running setup.py install for mozcrash 15:16:03 INFO - Running setup.py install for mozdebug 15:16:03 INFO - Running setup.py install for mozdevice 15:16:03 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:16:03 INFO - Running setup.py install for mozfile 15:16:03 INFO - Running setup.py install for mozhttpd 15:16:03 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:16:03 INFO - Running setup.py install for mozinfo 15:16:04 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:16:04 INFO - Running setup.py install for mozInstall 15:16:04 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:16:04 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:16:04 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:16:04 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:16:04 INFO - Running setup.py install for mozleak 15:16:04 INFO - Running setup.py install for mozlog 15:16:04 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:16:04 INFO - Running setup.py install for moznetwork 15:16:04 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:16:04 INFO - Running setup.py install for mozprocess 15:16:04 INFO - Running setup.py install for mozprofile 15:16:04 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:16:04 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:16:04 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:16:04 INFO - Running setup.py install for mozrunner 15:16:04 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:16:04 INFO - Running setup.py install for mozscreenshot 15:16:04 INFO - Running setup.py install for moztest 15:16:05 INFO - Running setup.py install for mozversion 15:16:05 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:16:05 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:16:05 INFO - Cleaning up... 15:16:05 INFO - Return code: 0 15:16:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:16: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')]} 15:16:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:16: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')]} 15:16:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:16:05 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 0x1e321f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ffea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x219fb20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21a2f00>, '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 0x219dba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x219e050>, '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': '7de08804244eb36560b44971000001c3-1502832647.67872-1550337739', '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 15:16:05 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 15:16:05 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 15:16:05 INFO - Using env: {'DISPLAY': ':0', 15:16:05 INFO - 'HOME': '/home/cltbld', 15:16:05 INFO - 'LANG': 'en_US.UTF-8', 15:16:05 INFO - 'LANGUAGE': 'en_US:en', 15:16:05 INFO - 'LOGNAME': 'cltbld', 15:16:05 INFO - 'MAIL': '/var/mail/cltbld', 15:16:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:16:05 INFO - 'MOZ_NO_REMOTE': '1', 15:16:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:16:05 INFO - 'NO_EM_RESTART': '1', 15:16:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:16:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:16:05 INFO - 'PWD': '/builds/slave/test', 15:16:05 INFO - 'SHELL': '/bin/bash', 15:16:05 INFO - 'SHLVL': '1', 15:16:05 INFO - 'TERM': 'linux', 15:16:05 INFO - 'TMOUT': '86400', 15:16:05 INFO - 'USER': 'cltbld', 15:16:05 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1502832647.67872-1550337739', 15:16:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:16:05 INFO - '_': '/tools/buildbot/bin/python'} 15:16:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:16:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:16:05 INFO - Running setup.py (path:/tmp/pip-VOYrqL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:16:05 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 15:16:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:16:05 INFO - Running setup.py (path:/tmp/pip-Nkhog1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:16:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:16:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:16:05 INFO - Running setup.py (path:/tmp/pip-c0uiIA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:16:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 15:16:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:16:05 INFO - Running setup.py (path:/tmp/pip-xyZTe5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:16:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:16:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:16:05 INFO - Running setup.py (path:/tmp/pip-jJZG81-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:16:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 15:16:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:16:05 INFO - Running setup.py (path:/tmp/pip-qTYnxT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:16:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 15:16:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:16:05 INFO - Running setup.py (path:/tmp/pip-rXXcz3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:16:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 15:16:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:16:05 INFO - Running setup.py (path:/tmp/pip-tK0Hkg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:16:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 15:16:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:16:05 INFO - Running setup.py (path:/tmp/pip-LEIoti-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:16:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 15:16:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:16:06 INFO - Running setup.py (path:/tmp/pip-dukdkk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:16:06 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)) 15:16:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:16:06 INFO - Running setup.py (path:/tmp/pip-RBs663-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:16:06 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)) 15:16:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:16:06 INFO - Running setup.py (path:/tmp/pip-HMma9h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:16:06 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)) 15:16:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:16:06 INFO - Running setup.py (path:/tmp/pip-Uu69C3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:16:06 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)) 15:16:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:16:06 INFO - Running setup.py (path:/tmp/pip-NUFLis-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:16:06 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)) 15:16:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:16:06 INFO - Running setup.py (path:/tmp/pip-gPC29D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:16:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 15:16:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:16:06 INFO - Running setup.py (path:/tmp/pip-4VU2Lp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:16:06 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 15:16:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:16:06 INFO - Running setup.py (path:/tmp/pip-NzLxwD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:16:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 15:16:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:16:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:16:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 15:16:06 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:16:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:16:06 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 15:16:06 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:16:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:16:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:16:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:16:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:16:07 INFO - Downloading blessings-1.6.tar.gz 15:16:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:16:07 INFO - Installing collected packages: blessings 15:16:07 INFO - Running setup.py install for blessings 15:16:07 INFO - Successfully installed blessings 15:16:07 INFO - Cleaning up... 15:16:07 INFO - Return code: 0 15:16:07 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:16:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:16:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:16:08 INFO - Reading from file tmpfile_stdout 15:16:08 INFO - Current package versions: 15:16:08 INFO - argparse == 1.2.1 15:16:08 INFO - blessings == 1.6 15:16:08 INFO - blobuploader == 1.2.4 15:16:08 INFO - docopt == 0.6.1 15:16:08 INFO - functools32 == 3.2.3-2 15:16:08 INFO - jsonschema == 2.5.1 15:16:08 INFO - manifestparser == 1.1 15:16:08 INFO - mozInstall == 1.13 15:16:08 INFO - mozcrash == 1.0 15:16:08 INFO - mozdebug == 0.1 15:16:08 INFO - mozdevice == 0.51 15:16:08 INFO - mozfile == 1.2 15:16:08 INFO - mozhttpd == 0.7 15:16:08 INFO - mozinfo == 0.10 15:16:08 INFO - mozleak == 0.1 15:16:08 INFO - mozlog == 3.5 15:16:08 INFO - moznetwork == 0.27 15:16:08 INFO - mozprocess == 0.25 15:16:08 INFO - mozprofile == 0.28 15:16:08 INFO - mozrunner == 6.13 15:16:08 INFO - mozscreenshot == 0.1 15:16:08 INFO - mozsystemmonitor == 0.3 15:16:08 INFO - moztest == 0.8 15:16:08 INFO - mozversion == 1.4 15:16:08 INFO - psutil == 3.1.1 15:16:08 INFO - requests == 1.2.3 15:16:08 INFO - wsgiref == 0.1.2 15:16:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:16:08 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')]} 15:16:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:16:08 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')]} 15:16:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:16:08 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 0x1e321f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ffea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x219fb20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21a2f00>, '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 0x219dba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x219e050>, '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': '7de08804244eb36560b44971000001c3-1502832647.67872-1550337739', '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 15:16:08 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 15:16:08 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 15:16:08 INFO - Using env: {'DISPLAY': ':0', 15:16:08 INFO - 'HOME': '/home/cltbld', 15:16:08 INFO - 'LANG': 'en_US.UTF-8', 15:16:08 INFO - 'LANGUAGE': 'en_US:en', 15:16:08 INFO - 'LOGNAME': 'cltbld', 15:16:08 INFO - 'MAIL': '/var/mail/cltbld', 15:16:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:16:08 INFO - 'MOZ_NO_REMOTE': '1', 15:16:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:16:08 INFO - 'NO_EM_RESTART': '1', 15:16:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:16:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:16:08 INFO - 'PWD': '/builds/slave/test', 15:16:08 INFO - 'SHELL': '/bin/bash', 15:16:08 INFO - 'SHLVL': '1', 15:16:08 INFO - 'TERM': 'linux', 15:16:08 INFO - 'TMOUT': '86400', 15:16:08 INFO - 'USER': 'cltbld', 15:16:08 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1502832647.67872-1550337739', 15:16:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:16:08 INFO - '_': '/tools/buildbot/bin/python'} 15:16:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:16:08 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)) 15:16:08 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)) 15:16:08 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)) 15:16:08 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)) 15:16:08 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)) 15:16:08 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)) 15:16:08 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)) 15:16:08 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)) 15:16:08 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)) 15:16:08 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 15:16:08 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 15:16:08 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 15:16:08 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 15:16:08 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 15:16:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 15:16:09 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 15:16:09 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 15:16:09 INFO - Installing collected packages: simplejson 15:16:09 INFO - Running setup.py install for simplejson 15:16:09 INFO - building 'simplejson._speedups' extension 15:16:09 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c simplejson/_speedups.c -o build/temp.linux-x86_64-2.7/simplejson/_speedups.o 15:16:10 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/simplejson/_speedups.o -o build/lib.linux-x86_64-2.7/simplejson/_speedups.so 15:16:10 INFO - Successfully installed simplejson 15:16:10 INFO - Cleaning up... 15:16:10 INFO - Return code: 0 15:16:10 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:16:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:16:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:16:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:16:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:16:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e321f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ffea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x219fb20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21a2f00>, '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 0x219dba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x219e050>, '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': '7de08804244eb36560b44971000001c3-1502832647.67872-1550337739', '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 15:16:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 15:16:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 15:16:10 INFO - Using env: {'DISPLAY': ':0', 15:16:10 INFO - 'HOME': '/home/cltbld', 15:16:10 INFO - 'LANG': 'en_US.UTF-8', 15:16:10 INFO - 'LANGUAGE': 'en_US:en', 15:16:10 INFO - 'LOGNAME': 'cltbld', 15:16:10 INFO - 'MAIL': '/var/mail/cltbld', 15:16:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:16:10 INFO - 'MOZ_NO_REMOTE': '1', 15:16:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:16:10 INFO - 'NO_EM_RESTART': '1', 15:16:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:16:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:16:10 INFO - 'PWD': '/builds/slave/test', 15:16:10 INFO - 'SHELL': '/bin/bash', 15:16:10 INFO - 'SHLVL': '1', 15:16:10 INFO - 'TERM': 'linux', 15:16:10 INFO - 'TMOUT': '86400', 15:16:10 INFO - 'USER': 'cltbld', 15:16:10 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1502832647.67872-1550337739', 15:16:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:16:10 INFO - '_': '/tools/buildbot/bin/python'} 15:16:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:16:10 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:16:10 INFO - Cleaning up... 15:16:10 INFO - Return code: 0 15:16:10 INFO - Running post-action listener: _resource_record_post_action 15:16:10 INFO - Running post-action listener: _start_resource_monitoring 15:16:10 INFO - Starting resource monitoring. 15:16:10 INFO - [mozharness: 2017-08-15 22:16:10.865218Z] Finished create-virtualenv step (success) 15:16:10 INFO - [mozharness: 2017-08-15 22:16:10.865686Z] Running install step. 15:16:10 INFO - Running pre-action listener: _resource_record_pre_action 15:16:10 INFO - Running main action method: install 15:16:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:16:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:16:11 INFO - Reading from file tmpfile_stdout 15:16:11 INFO - Detecting whether we're running mozinstall >=1.0... 15:16:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:16:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:16:11 INFO - Reading from file tmpfile_stdout 15:16:11 INFO - Output received: 15:16:11 INFO - Usage: mozinstall [options] installer 15:16:11 INFO - Options: 15:16:11 INFO - -h, --help show this help message and exit 15:16:11 INFO - -d DEST, --destination=DEST 15:16:11 INFO - Directory to install application into. [default: 15:16:11 INFO - "/builds/slave/test"] 15:16:11 INFO - --app=APP Application being installed. [default: firefox] 15:16:11 INFO - mkdir: /builds/slave/test/build/application 15:16:11 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'] 15:16:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 15:16:29 INFO - Reading from file tmpfile_stdout 15:16:29 INFO - Output received: 15:16:29 INFO - /builds/slave/test/build/application/firefox/firefox 15:16:29 INFO - Running post-action listener: _resource_record_post_action 15:16:29 INFO - [mozharness: 2017-08-15 22:16:29.338259Z] Finished install step (success) 15:16:29 INFO - [mozharness: 2017-08-15 22:16:29.338604Z] Running setup-mitmproxy step. 15:16:29 INFO - Running pre-action listener: _resource_record_pre_action 15:16:29 INFO - Running main action method: setup_mitmproxy 15:16:29 INFO - Skipping: mitmproxy is not required 15:16:29 INFO - Running post-action listener: _resource_record_post_action 15:16:29 INFO - [mozharness: 2017-08-15 22:16:29.340242Z] Finished setup-mitmproxy step (success) 15:16:29 INFO - [mozharness: 2017-08-15 22:16:29.340686Z] Running run-tests step. 15:16:29 INFO - Running pre-action listener: _resource_record_pre_action 15:16:29 INFO - Running pre-action listener: _set_gcov_prefix 15:16:29 INFO - Running main action method: run_tests 15:16:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:16:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:16:29 INFO - Python 2.7.3 15:16:29 INFO - Return code: 0 15:16:29 INFO - grabbing minidump binary from tooltool 15:16:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:16:29 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21a2f00>, '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 0x219dba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x219e050>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:16:29 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 15:16:29 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 15:16:29 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 15:16:29 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:16:29 INFO - Return code: 0 15:16:29 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 15:16:29 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:16:29 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:16:29 INFO - ENV: RUST_BACKTRACE is now full 15:16:29 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 15:16:29 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:16:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RMB3t9HoRkqz14OC2cjDLw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-003', '--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 15:16:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/RMB3t9HoRkqz14OC2cjDLw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-003 --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 15:16:29 INFO - Using env: {'DISPLAY': ':0', 15:16:29 INFO - 'HOME': '/home/cltbld', 15:16:29 INFO - 'LANG': 'en_US.UTF-8', 15:16:29 INFO - 'LANGUAGE': 'en_US:en', 15:16:29 INFO - 'LOGNAME': 'cltbld', 15:16:29 INFO - 'MAIL': '/var/mail/cltbld', 15:16:29 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:16:29 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:16:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:16:29 INFO - 'MOZ_NO_REMOTE': '1', 15:16:29 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:16:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:16:29 INFO - 'NO_EM_RESTART': '1', 15:16:29 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:16:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:16:29 INFO - 'PWD': '/builds/slave/test', 15:16:29 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:16:29 INFO - 'RUST_BACKTRACE': 'full', 15:16:29 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 15:16:29 INFO - 'SHELL': '/bin/bash', 15:16:29 INFO - 'SHLVL': '1', 15:16:29 INFO - 'STYLO_FORCE_ENABLED': '1', 15:16:29 INFO - 'TERM': 'linux', 15:16:29 INFO - 'TMOUT': '86400', 15:16:29 INFO - 'USER': 'cltbld', 15:16:29 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1502832647.67872-1550337739', 15:16:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:16:29 INFO - '_': '/tools/buildbot/bin/python'} 15:16:29 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RMB3t9HoRkqz14OC2cjDLw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-003', '--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 15:16:29 INFO - mozversion application_buildid: 20170815212701 15:16:29 INFO - mozversion application_changeset: d25db0546c92afa72d61685c9310104ef28235bd 15:16:29 INFO - mozversion application_display_name: Nightly 15:16:29 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:16:29 INFO - mozversion application_name: Firefox 15:16:29 INFO - mozversion application_remotingname: firefox 15:16:29 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 15:16:29 INFO - mozversion application_vendor: Mozilla 15:16:29 INFO - mozversion application_version: 57.0a1 15:16:29 INFO - mozversion platform_buildid: 20170815212701 15:16:29 INFO - mozversion platform_changeset: d25db0546c92afa72d61685c9310104ef28235bd 15:16:29 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 15:16:29 INFO - mozversion platform_version: 57.0a1 15:16:29 INFO - using testdate: 1502835389 15:16:29 INFO - actual date: 1502835389 15:16:29 INFO - starting webserver on 'localhost:59303' 15:16:29 INFO - SUITE-START | Running 2 tests 15:16:29 INFO - TEST-START | dromaeo_css 15:16:29 INFO - Initialising browser for dromaeo_css test... 15:16:29 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:59303/getInfo.html -profile /tmp/tmpxNfVpf/profile 15:16:29 INFO - TEST-INFO | started process 21891 (/builds/slave/test/build/application/firefox/firefox http://localhost:59303/getInfo.html) 15:16:36 INFO - TEST-INFO | 21891: exit 0 15:16:36 INFO - Browser initialized. 15:16:36 INFO - Running cycle 1/1 for dromaeo_css test... 15:16:36 INFO - TEST-INFO | started process 22124 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpxNfVpf/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 15:16:37 INFO - PID 22124 | 15:16:37 INFO - PID 22124 | (/builds/slave/test/build/application/firefox/firefox:22178): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 15:16:37 INFO - PID 22124 | 15:16:47 INFO - PID 22124 | RSS: Main: 167215104 15:16:47 INFO - PID 22124 | 15:19:46 INFO - PID 22124 | Cycle 1(1): loaded http://localhost:59303/tests/dromaeo/cssquery-dojo.html (next: http://localhost:59303/tests/dromaeo/cssquery-ext.html) 15:19:47 INFO - PID 22124 | RSS: Main: 174788608 15:19:47 INFO - PID 22124 | 15:22:49 INFO - PID 22124 | Cycle 1(1): loaded http://localhost:59303/tests/dromaeo/cssquery-ext.html (next: http://localhost:59303/tests/dromaeo/cssquery-jquery.html) 15:22:49 INFO - PID 22124 | RSS: Main: 172150784 15:22:49 INFO - PID 22124 | 15:25:51 INFO - PID 22124 | Cycle 1(1): loaded http://localhost:59303/tests/dromaeo/cssquery-jquery.html (next: http://localhost:59303/tests/dromaeo/cssquery-mootools.html) 15:25:52 INFO - PID 22124 | RSS: Main: 174370816 15:25:52 INFO - PID 22124 | 15:28:54 INFO - PID 22124 | Cycle 1(1): loaded http://localhost:59303/tests/dromaeo/cssquery-mootools.html (next: http://localhost:59303/tests/dromaeo/cssquery-prototype.html) 15:28:54 INFO - PID 22124 | RSS: Main: 170819584 15:28:54 INFO - PID 22124 | 15:31:58 INFO - PID 22124 | Cycle 1(1): loaded http://localhost:59303/tests/dromaeo/cssquery-prototype.html (next: http://localhost:59303/tests/dromaeo/cssquery-yui.html) 15:31:58 INFO - PID 22124 | RSS: Main: 174305280 15:31:58 INFO - PID 22124 | 15:35:00 INFO - PID 22124 | Cycle 1(1): loaded http://localhost:59303/tests/dromaeo/cssquery-yui.html (next: http://localhost:59303/tests/dromaeo/cssquery-dojo.html) 15:35:00 INFO - PID 22124 | RSS: Main: 176881664 15:35:00 INFO - PID 22124 | 15:35:00 INFO - PID 22124 | __start_tp_report 15:35:00 INFO - PID 22124 | _x_x_mozilla_page_load 15:35:00 INFO - PID 22124 | _x_x_mozilla_page_load_details 15:35:00 INFO - PID 22124 | |i|pagename|runs| 15:35:00 INFO - PID 22124 | |0;dojo.html;3153.2432216268103;3174.825384603846;3175.6348019977695;3187.5856138701974;3205.4230238557066;6125.0506171543375;6200.379962003803;6232.096346029829;6256.092866534355;6277.995520716686;6039.245094363205;6123.3876612338745;6135.539834512407;6141.355157708444;6169.722362493687;5614.9157762633595;5625.099984002561;5642.830715078554;5648.74580643872;5674;5649.717514124298;5652.236948012028;5656.632318899258;5666.914996275059;5688.544916406699;5746.7413966371405;5775.480206781389;5779.7688092476465;5784.566157538202;5794.536437085023;5606.943930560683;5652.152177173416;5685.346185188715;5729.369769325413;5768.596198266144;5397.487238712316;5437.864053398657;5555.749991250363;5564.360098588673;5586.050371436865;6037.02428877148;6121.571489995702;6146.29317628474;6215.067739839015;6220.720067596969;6467.644279564622;6579.736810527573;6606.768763093269;6667.366600172976;6673.265940746514;6615.305392933721;6632.469402447792;6649.60102393858;6656.467482601381;6665.200175978865;6022.909656355158;6150.876982460326;6158.6612736299485;6164.599301045418;6170.784022559189;6886.345797149263;6902.689378977958;6918.757843475454;6939.132608423947;6952.721891124298;6540.345965403421;6560.770373036921;6564.9671751640935;6640;6651.401373876374;6477.902831457532;6679.933200668028;6697.665116744143;6759.729610815611;6782.728690852311;6475.449586785176;6542.542022058506;6543.509236807164;6564.343565643398;6575.145231120019;6593.142891424084;6681.465482761368;6714.462842972551;6727.62998035103;6758.155230596176;5263;5328.813491527778;5339.893202135935;5340.706261155596;5346.518813306743;4500.837446013294;4536.206163921301;4592.127495775792;4604.332371806069;4612.677112602086;5582.441755824466;5668.914966275509;5672.574556908248;5737.311522617312;5743.62666426681;6618.271990081087;6705.228898676714;6707;6709.496787740939;6719;5730.426957304237;5742.54059675225;5800.709964501758;5804.390538993429;5804.738786754606;5820.738066787005;5843.5909486336395;5911.083782013795;5918.112283157475;5927.436893495197;6261.373862613702;6400.519961002943;6417.518686098561;6441.194850643669;6550.213974323112;6591.703373348307;6596.2744098148205;6612.206535215805;6616.073749675145;6621.900671489835;6289.308176100534;6336.17629708135;6344.619322840645;6347.365263473615;6379.808605741835;6457.741690332522;6473.64394958263;6479.1901012373455;6553.836154096186;6565.212174539086;6729.5625784323865;6731.798046058626;6747.257801641914;6770.390664840188;6777.356151165632;6462.030695395728;6481;6540.411362977355;6553.606783593;6579.243387010555;6458.935410645833;6468.353164683494;6534.673266336759;6538.574992625464;6554.180727409074;6280.340564240872;6307.211598550181;6353.237611486651;6367.649779261999;6477.222733272038;6568.981807819796;6574.276829548841;6590.2421221560135;6621.470282377494;6635.867282654416;6121.418465245794;6215.720292586935;6241.469475094668;6298.842528936813;6380.521460890544 15:35:00 INFO - PID 22124 | |1;ext.html;832.5920299053462;839.8950131233596;862.5902696219297;879.8680197970306;890.3322508118907;41137;41798.164036719245;41878.37182442262;41996.790016049956;42264;38684.22631547367;39068;39131.80434097833;39170.8041459793;39177.41233881494;6819.624920629363;6978.720851165947;6980.127484064492;6984.4412447004115;6987.441004719611;6809.944458608924;6903.482238832083;6905.482088843331;6909.689063992132;6911.48163887708;7605.809854753647;7765.728199513018;7803.10264319602;7803.765887023398;7812.257835505618;7522.097348318237;7647.120581133157;7739.57432341221;7740.6129693515095;7743.574103424309;7575.6969721211635;7589.203133670941;7642.121156067068;7648;7650.808729781745;39544.802275988724;39923.8003809982;40085.79957100225;40101.398479022835;40139.59860401417;3515.2793677296095;3526.7883926964464;3545.9113522161892;3550.218951830619;3559.3771090059136;32167;32170.517442238386;32275.51586726201;32286;32325;5090.236464530313;5094.3886733591835;5138.2806407103035;5144.717040562768;5189.065968125736;175838;180139;187113;189127;200018;52008.47991520112;52062.739686302084;52099.47900520946;52139;52291.477085229424;53284;53901.1914821278;53966.46033539693;54009.729951350775;54128.45871541313;16928.915355423145;17009.149542522824;17036.574085647957;17049.14754262282;17054.232559534852;13904.096233744976;13914.373853176632;13940.58178254654;13943.093698909539;13963.301834908214;15096.924515377354;15113.622159446102;15138;15164.772528412082;15367.077975321516;5698.914516282176;5988.730507127189;6027.095935609694;6067.150598916155;6088.847778805565;977.8582105594649;1003.6838395905266;1006.5420233793607;1007.8588997540351;1008.6923488335981;22926.19758308451;22976.770232297797;23246.76753232446;23255.558386157438;23320.183793567143;1104.1222228328497;1111.4609414433994;1117.4628423721329;1120.6357933671588;1125.5216532973454;12449.17483855534;12463.065270104778;12505.749885002431;12577.496900124075;12597.307148106758;236043;237239;237801;237829;237948;52333;52342;52408.737956309975;52674.47325526772;52692.736536317076;1425.4796999095256;1462.393106860662;1479.5265515035085;1480.052766229614;1484.7921291019265;22250.443738906655;22981;23077.53844923126;23418.53162936766;27621;53410.46589534055;53774.731126344115;53810.730946345015;54016;54055.459445405046;207694;208993;210274;210657;210657;31466.37067258688;32425;32456.188595285308;36074.09814754652;36715.44926826046;43717;45554;45833.312500313186;45870.77064614656;46872.7656361716;33383;33798.66201337955;33844.83077584596;33905.83047084749;33976.15059623529;32842.50736239006;33216.33567328592;33360.332793343514;33558.83220583979;33581.83209083939;36139.45790813088;36506.26987460183;38612.6138738609;39702.404463932486;39838.80080599579;34970.47544286787;35244.29511409809;35244.64755352413;35641.28717425585;35830.82084589665;23961.640575391033;25375.238742837744;25481.10816121501;25855;25943.22170334893 15:35:00 INFO - PID 22124 | |2;jquery.html;562.9634073785204;572.8023831778036;577.9135225775539;579.3250862744901;594.6372712645285;22190.334289971288;22854.200102996398;23111.075556977743;23179.18872839451;23243;26071.73928260717;26148.73851261487;26347.73652263477;26604.201873943763;26748;6667.233268174162;6853.657317134148;6855.4172895303955;6855.657217139148;6855.691493882762;5972.134040564116;6234.376562343752;6451.16134902462;6458.031295305718;6494.675266236693;6441.1304473896125;6459.321771214025;6467.223933128032;6472.741090356403;6478.708458119372;6429.582077164992;6439.9356006440275;6445.129907462503;6450.2259728832605;6452;5997.700114994276;5998.1602575639445;5998.220231369894;6016.157737916695;6019.849503762397;29419.11742647724;29559.556606650916;30030.399392012256;31051.068467946;31085.378292434023;1878.605492846504;1931.2757715856553;1931.5267759398896;1934.864559480837;1939.3212375668545;19805.504862378555;19922.701159482618;20705.792942070537;20792.896035519727;20866.165353385848;2310.595645761985;2323.6398358254496;2360.9527809443716;2364.137089962169;2368.786809187164;134358;140759;141692;142215.86676199865;142290;14751.40994360235;15286.770698439532;15349.232538373037;15385.538253893008;15600.531984040497;23874.88062559676;23877;23907.16324928654;23945.640815387436;23984.40038999004;9914.305998580174;10141.29010969225;10239.692809215736;10288.12550933179;10307.74230644225;8616.526091065052;9442.716718498456;9501.097395747393;9557.569909354095;9574.234061275081;8630.697925572575;11741.882581174079;12070.215435996632;12104.757904841854;12207.450664720109;3869.342211823997;4045.3931910213114;4336.848210312624;4355.564443555619;4457.7771111444945;951.46242373059;956.1768827838497;966.9661561845302;979.8236317462926;981.0434826044692;19593.216271348985;19826.306079287155;19855.801441985393;20150.596988060155;24275.757242427702;1037.1391744851755;1042.775803202315;1070.844727514506;1072.2494254022215;1072.6031368393744;10926.289791163548;11219.943900280447;11326.943365283121;11435.313881167156;13737.656558586115;483710;484559;484910;486443;495137;113265;116294;116705.41647291879;116986;118053;1258.9622311330675;1283.7625039367747;1308.535469908188;1313.3105119811985;1313.6124843171287;21059.578808424052;21116.472088197304;21211.575768484854;21444.24945126913;21523;56935.71532142313;56957.715211423674;57971;59000;61023;68439.94680159603;68563.31436685768;69045.65477172582;69613;70849.64575177297;14947.402103915721;15317.693646126792;15328.463503777315;15358.846411535742;15362.30869610848;24177.395565111012;24303.51392972166;24483.877580611985;24521.87739061293;24527.75472245326;18856.905715471334;19485.220591176196;19529.511762206057;19865.60268794587;19924;17111.57221069483;17703.114844257478;17848.28606855763;18056.90971545134;18200.99894505815;19246.903765481642;19838.10728517249;19861.900690496455;19917.402477925687;19989.60020799605;14569.708605828037;14818.25908704582;14915.433879442502;14961.326740296496;14978.850211497745;18968.051597420348;19001.619967600847;19082.713759293343;19323.8067619322;19360.4191874244 15:35:00 INFO - PID 22124 | |3;mootools.html;1372.4373007067104;1424.7934049562814;1437.6334034821118;1448.2686243447058;1450.3618407900517;14042.859571404282;14402.855971440309;14614.56156315311;14635.926820365883;14644.633884152901;14870.405183792662;15100.773488397655;15112.773308400383;15113.471028514005;15121.395144194219;2553.438243586408;2619.0440489221487;2620.3711109333813;2621.646077779504;2622.4230669252734;2588.93527661809;2593.053535459553;2638.538255805236;2752.174347695693;2752.477029364424;2663.8002149838744;2671.3321669582606;2674;2675.036986684792;2686.9596956045675;2590.041684576709;2628.487444948236;2630.7500787425165;2632.328756167184;2632.710401855804;2535.7210706822234;2545.287319550529;2565.8332208406396;2566.242958327298;2567.3838278813137;14078.929605351908;14404.495842645561;14408.63978400538;14437.639059023504;14447.855521444757;7479.289467500579;7732.961335193344;7755.495892766952;7771.455998080137;7777.961110194413;4937.604991600663;5130.050940576006;5271.499207575274;5304.2839216706025;5376.596755243372;1835.8347748702547;1851.2132343753985;1856.5915498590423;1910.388675623801;1931.9323823666246;23509.294721158392;23526;23660.053597856066;23719.288421347388;23797.405064873343;6663.900041499382;6692.163479565054;6846.418054465326;6857.27998560154;6895.862082758316;16187.433439829549;16245.837541624669;16252.18739063042;16264.83735162657;16276;7555.773326800205;7674.76975690719;7692.92307076922;7695.345895598937;7695.422843286664;6955.130608673916;7111.857762844714;7280.271972802678;7280.6723697433745;7282.70869165238;4814.566688998007;5063.746812659426;5079.034983353151;5096.311997880239;5118.488151184852;1507.148461119464;1516.840731723153;1522.6345677037432;1530.7703844423204;1531.2802982598162;877.4603618774407;896.0994200828126;903.2909166304551;908.5411867007119;918.3617385916857;7254.600996945113;7302.2697730226555;7324.37742791858;7339.7064117435775;7347.8163045924275;665.7303791964263;669.8693754717881;673.4612310151957;693.4383149648801;710.4529512275519;4355.455568053993;4373;4375.409319741858;4896.3389942358035;4981.352424184833;19292.710609340873;19889.10499027547;19932.103055362542;20121.094550745252;20295.898520507304;19578;19638.21447142127;19639.607207855763;19985.100670469863;20007.59984800296;9377.15605595486;9849.556769945513;10521.368717876952;10700.464976751131;10708.411037393018;7440.73957411488;7945.364370850433;8779.780505487159;8831.955840220759;8947.731568052968;16744.832551674328;16754.497365079067;16770.266899476177;16774.32902683879;16833.747493787847;17024.48926532206;17045.659086817945;17045.91477042607;17054.14729263507;17351.56621084483;10404.375737455777;10593.788124237626;10598.576056957636;10598.735031623963;10621.044106030495;13301.534446294334;13303.201807891557;13375.39810708501;13464.798028029392;13530.797038044635;10816.188785840935;10917.181211409335;11178.608748693756;11281.548738050386;11467.369294688539;10112.494375281354;10658.467076645982;10701.090407315465;10754.462276885968;10942.617008404668;9065.184133428023;9168.495732734496;9243.76890577741;9246.722598322061;9248.861267081134;6945.166580010431;6947.40947019509;6972.651367431709;6992.685329160098;6993.4055605274125;7195.388391986739;7210.315020073084;7238.312360325761;7274.418046556369;7329.780106596811 15:35:00 INFO - PID 22124 | |4;prototype.html;508.3036240350719;509.08619028843225;521.9686818790873;530.8991291654588;535.4538370861719;6336.497083651961;6338.176037115174;6348.46038086762;6353.3646635336445;6361.459275961549;6191.659458729768;6219.533534984872;6237.220347456568;6270.372962703727;6275.843103922404;4836.879078023042;4888.657793954407;4939.604831613462;5087.465816089313;5100.520848953799;4916.680415772981;4926.064047830901;4931.926021109686;4934.28452874333;4945.554900058994;4909.877253068684;4913.1647619904525;4932.5807306379;4952.653314268003;4982.252662100677;4945.975270123662;4946.604271658259;4970.204767237225;4979.45226025137;4985.501449855022;4433.800478978454;4434.667399945017;4454.420925279725;4461.977690111561;4467.932981005255;5966.164736936832;5985.431384018511;6032.607880487754;6071.362506936753;6118.296395914483;5615.943840561583;5669.461401166883;5672.8865422691315;5688;5728.682403047323;6569.638669873155;6587.5718078325235;6601.768938087192;6610.01699490151;6616.603003819786;4762.1428142934255;4819.722773465034;4840.588549973257;4843.636727245435;4850.151223535902;6639.634820084893;6724.9327506725285;6824.897626535606;6871.072405225281;6942.895856562156;4838.250071238964;4859.34398856157;4887.877803054881;4902.48523904992;4953.207486802166;4923.630727695438;4954.182559877652;4998.750062496861;5017.347744793154;5029.924551131736;5580.302462192226;5640.71796410186;5657.246253661378;5693.572982026365;5701.287339082614;6575.309592492815;6662.701368028022;6674.933250667431;6675.866482670416;6695.832604184934;1771.4057348386555;1785.8482029027678;1790.6955817511055;1795.9102044897702;1801.4865763257408;1157.2015309436463;1191.5710344276054;1193.1826698711236;1193.570314686712;1214.6356093172012;5684.346300175533;5748.338941021836;5764.221830052973;5831.766729330864;5835.854103647443;6695.46436285096;6749;6775.373910261502;6777.423918966844;6890.27834005575;5680.77276908919;5686.402927692616;5746.712664366849;5750.654960702372;5755.510781583613;3565.2691198304287;3589.8743543975834;3592.8742494012586;3617;3630.5643322801434;6514.576552524166;6580.506462015368;6591.176102987127;6601.768938087144;6638.17022872141;6918.37734603878;6946;6947.407337093587;6960.512764106464;6992.720291188295;6431.839204019937;6459.741610335628;6539.090104903273;6571.3757193066585;6601.966990165114;6706.396424321834;6741.471948019493;6760.18877734675;6779;6811.93188068113;6724.830605024916;6745.898811517733;6747.865042699217;6769;6803.21068361989;5870.618409803349;6455.86824432373;6562.114114594659;6585.703643335965;6618.238902526078;4931.186354251508;4945.381827271591;4951.554360107608;4969.900601988013;4979.278004689228;6529.249136349381;6551.836204094936;6644.368784965421;6714.899276510759;6764.391204791593;6548.148740663683;6557.377049180321;6562.671866406756;6576.572522786004;6625.515431713107;6474.158359413246;6481.157449531531;6568.343165683394;6621;6628.403443690091;6264.655443950444;6407.295197528175;6516.218053833571;6520.771772987922;6522.315156908457;6486.578372405778;6509.251436084911;6532.366908272932;6590.308017658077;6612.140421745143;6360.204974378203;6384.712687929147;6417.26201486835;6441.6135031896365;6488.870222595616 15:35:00 INFO - PID 22124 | |5;yui.html;920.4983284110159;921.3044151665491;935.5322338830584;945.0360632155201;946.7159852044385;18399.172037258322;19193;19195.904020479895;19197.232110715555;19199.13603887825;17872.64254714908;17890.194941227644;18291.359802406918;22088.668669969924;22144.778552214473;1053.0838170791385;1076.0907033556632;1076.7146706122883;1079.4602698650674;1079.7138758229075;1017.872765904262;1053.3258714422775;1066.0884943373383;1077.6174458067392;1077.6174458067392;1111.8443417921496;1116.0959622705593;1116.2799994003863;1120.848685427465;1121.5121422181337;1111.9610813621525;1112.5438570186225;1114.0697517572837;1115.3475216998024;1115.7935781880296;814.3647954595425;814.4095530740225;815.889854869591;818.7339114787718;818.9672413103468;18137.727934081;18343.35798247055;18420.53948651281;18880.716789248174;20918.163273469043;20681.89659051695;21001.159953601837;21251.574968500543;21306.14775408982;21334.25330113454;16097.356105755756;16507.58731031734;16566.917165414216;16686.24911878956;16739.414120505844;3560.1633616100194;3579.785212887209;3597.6222496638;3599.1721903961975;3599.1721903961975;109495;112074.43962780216;113283;113707.99999999917;114972;14341.6414589634;15103.848961510244;15123.546293611209;15146.469873554594;15164;29341;31014.22464438362;31114.53328200079;31281.374372512877;31356.372872542423;8183.304419124441;8353.83292334162;8456.619452124669;8471.491710497266;8479.406441549032;4058.127502586899;4133.565975572522;4134.441850350225;4135.400366946836;4137.81379837908;5127.717975511308;5182.067227899014;5229.816956406507;5297.54970827484;5303.522682958553;1040.3445829127602;1049.4228174503992;1056.3556230699267;1060.7931453366673;1060.992057545335;685.5132855672429;703.96480175991;709.6309918842173;712.9607871567114;714.8248679073556;12246.326452045223;12650.367481625883;12720.491180352867;12873.163244389272;13114.278714670594;664.64773669955;668.5687731413211;671.214678825775;672.7813460625316;682.0655701688718;5516.475934786189;5646.435356464321;5650.152477128381;5651.9152212716845;5780.219670344533;109815.45092274487;110619.44690276498;112794.43602781933;115898.42050789692;116330;92024;94688.52655736677;96273;99134.5043274779;100061;73879.63060184772;81262.59368703119;83322.58338708268;83681;83705.58147209225;12760.106792524433;12833.358332083546;12851;12875.871241287467;12916.160449570747;38931.805340973115;39056.21887562289;39095.82712518629;39391.212175755754;39606.603933960294;69799;70361.64819175871;70706;70723;71800;16973.745393818856;18764.530886727942;19645.31241406544;19775;19786.604267914274;19912.50218744485;20551.383458496268;20643.380698578465;20693.379198624065;20889.26887558928;17728.645427091127;18145.364912228008;18176.90911545434;18215.089245537405;18596.44210673682;17678.292868285123;18584.349547765763;18812.52968675794;18830.811691882907;18905.810941890406;17358.91320543389;17549.736753948957;18041.278348865897;18302.908485457487;18699.626007480045;12305.26168429897;12429;12761.61715148547;13959.441622335398;14499.637509062359;13122.803157952829;13582.524611638546;13920.303984800921;13989.790153147507;14025.088369255966 15:35:00 INFO - PID 22124 | __end_tp_report 15:35:00 INFO - PID 22124 | __start_cc_report 15:35:00 INFO - PID 22124 | _x_x_mozilla_cycle_collect,358 15:35:00 INFO - PID 22124 | __end_cc_report 15:35:00 INFO - PID 22124 | __startTimestamp1502836500980__endTimestamp 15:35:00 INFO - PID 22124 | 15:35:00 INFO - PID 22124 | ------- Summary: start ------- 15:35:00 INFO - PID 22124 | Number of tests: 6 15:35:00 INFO - PID 22124 | 15:35:00 INFO - PID 22124 | [#0] dojo.html Cycles:165 Average:6107.07 Median:6307.21 stddev:726.49 (11.5%) stddev-sans-first:690.77 15:35:00 INFO - PID 22124 | Values: 3153.2 3174.8 3175.6 3187.6 3205.4 6125.1 6200.4 6232.1 6256.1 6278.0 6039.2 6123.4 6135.5 6141.4 6169.7 5614.9 5625.1 5642.8 5648.7 5674.0 5649.7 5652.2 5656.6 5666.9 5688.5 5746.7 5775.5 5779.8 5784.6 5794.5 5606.9 5652.2 5685.3 5729.4 5768.6 5397.5 5437.9 5555.7 5564.4 5586.1 6037.0 6121.6 6146.3 6215.1 6220.7 6467.6 6579.7 6606.8 6667.4 6673.3 6615.3 6632.5 6649.6 6656.5 6665.2 6022.9 6150.9 6158.7 6164.6 6170.8 6886.3 6902.7 6918.8 6939.1 6952.7 6540.3 6560.8 6565.0 6640.0 6651.4 6477.9 6679.9 6697.7 6759.7 6782.7 6475.4 6542.5 6543.5 6564.3 6575.1 6593.1 6681.5 6714.5 6727.6 6758.2 5263.0 5328.8 5339.9 5340.7 5346.5 4500.8 4536.2 4592.1 4604.3 4612.7 5582.4 5668.9 5672.6 5737.3 5743.6 6618.3 6705.2 6707.0 6709.5 6719.0 5730.4 5742.5 5800.7 5804.4 5804.7 5820.7 5843.6 5911.1 5918.1 5927.4 6261.4 6400.5 6417.5 6441.2 6550.2 6591.7 6596.3 6612.2 6616.1 6621.9 6289.3 6336.2 6344.6 6347.4 6379.8 6457.7 6473.6 6479.2 6553.8 6565.2 6729.6 6731.8 6747.3 6770.4 6777.4 6462.0 6481.0 6540.4 6553.6 6579.2 6458.9 6468.4 6534.7 6538.6 6554.2 6280.3 6307.2 6353.2 6367.6 6477.2 6569.0 6574.3 6590.2 6621.5 6635.9 6121.4 6215.7 6241.5 6298.8 6380.5 15:35:00 INFO - PID 22124 | 15:35:00 INFO - PID 22124 | [#1] ext.html Cycles:180 Average:39050.68 Median:35736.05 stddev:55071.43 (154.1%) stddev-sans-first:55151.15 15:35:00 INFO - PID 22124 | Values: 832.6 839.9 862.6 879.9 890.3 41137.0 41798.2 41878.4 41996.8 42264.0 38684.2 39068.0 39131.8 39170.8 39177.4 6819.6 6978.7 6980.1 6984.4 6987.4 6809.9 6903.5 6905.5 6909.7 6911.5 7605.8 7765.7 7803.1 7803.8 7812.3 7522.1 7647.1 7739.6 7740.6 7743.6 7575.7 7589.2 7642.1 7648.0 7650.8 39544.8 39923.8 40085.8 40101.4 40139.6 3515.3 3526.8 3545.9 3550.2 3559.4 32167.0 32170.5 32275.5 32286.0 32325.0 5090.2 5094.4 5138.3 5144.7 5189.1 175838.0 180139.0 187113.0 189127.0 200018.0 52008.5 52062.7 52099.5 52139.0 52291.5 53284.0 53901.2 53966.5 54009.7 54128.5 16928.9 17009.1 17036.6 17049.1 17054.2 13904.1 13914.4 13940.6 13943.1 13963.3 15096.9 15113.6 15138.0 15164.8 15367.1 5698.9 5988.7 6027.1 6067.2 6088.8 977.9 1003.7 1006.5 1007.9 1008.7 22926.2 22976.8 23246.8 23255.6 23320.2 1104.1 1111.5 1117.5 1120.6 1125.5 12449.2 12463.1 12505.7 12577.5 12597.3 236043.0 237239.0 237801.0 237829.0 237948.0 52333.0 52342.0 52408.7 52674.5 52692.7 1425.5 1462.4 1479.5 1480.1 1484.8 22250.4 22981.0 23077.5 23418.5 27621.0 53410.5 53774.7 53810.7 54016.0 54055.5 207694.0 208993.0 210274.0 210657.0 210657.0 31466.4 32425.0 32456.2 36074.1 36715.4 43717.0 45554.0 45833.3 45870.8 46872.8 33383.0 33798.7 33844.8 33905.8 33976.2 32842.5 33216.3 33360.3 33558.8 33581.8 36139.5 36506.3 38612.6 39702.4 39838.8 34970.5 35244.3 35244.6 35641.3 35830.8 23961.6 25375.2 25481.1 25855.0 25943.2 15:35:00 INFO - PID 22124 | 15:35:00 INFO - PID 22124 | [#2] jquery.html Cycles:180 Average:35280.18 Median:23145.13 stddev:82080.57 (354.6%) stddev-sans-first:82269.44 15:35:00 INFO - PID 22124 | Values: 563.0 572.8 577.9 579.3 594.6 22190.3 22854.2 23111.1 23179.2 23243.0 26071.7 26148.7 26347.7 26604.2 26748.0 6667.2 6853.7 6855.4 6855.7 6855.7 5972.1 6234.4 6451.2 6458.0 6494.7 6441.1 6459.3 6467.2 6472.7 6478.7 6429.6 6439.9 6445.1 6450.2 6452.0 5997.7 5998.2 5998.2 6016.2 6019.8 29419.1 29559.6 30030.4 31051.1 31085.4 1878.6 1931.3 1931.5 1934.9 1939.3 19805.5 19922.7 20705.8 20792.9 20866.2 2310.6 2323.6 2361.0 2364.1 2368.8 134358.0 140759.0 141692.0 142215.9 142290.0 14751.4 15286.8 15349.2 15385.5 15600.5 23874.9 23877.0 23907.2 23945.6 23984.4 9914.3 10141.3 10239.7 10288.1 10307.7 8616.5 9442.7 9501.1 9557.6 9574.2 8630.7 11741.9 12070.2 12104.8 12207.5 3869.3 4045.4 4336.8 4355.6 4457.8 951.5 956.2 967.0 979.8 981.0 19593.2 19826.3 19855.8 20150.6 24275.8 1037.1 1042.8 1070.8 1072.2 1072.6 10926.3 11219.9 11326.9 11435.3 13737.7 483710.0 484559.0 484910.0 486443.0 495137.0 113265.0 116294.0 116705.4 116986.0 118053.0 1259.0 1283.8 1308.5 1313.3 1313.6 21059.6 21116.5 21211.6 21444.2 21523.0 56935.7 56957.7 57971.0 59000.0 61023.0 68439.9 68563.3 69045.7 69613.0 70849.6 14947.4 15317.7 15328.5 15358.8 15362.3 24177.4 24303.5 24483.9 24521.9 24527.8 18856.9 19485.2 19529.5 19865.6 19924.0 17111.6 17703.1 17848.3 18056.9 18201.0 19246.9 19838.1 19861.9 19917.4 19989.6 14569.7 14818.3 14915.4 14961.3 14978.9 18968.1 19001.6 19082.7 19323.8 19360.4 15:35:00 INFO - PID 22124 | 15:35:00 INFO - PID 22124 | [#3] mootools.html Cycles:180 Average:8780.92 Median:2591.55 stddev:6088.07 (234.9%) stddev-sans-first:6079.70 15:35:00 INFO - PID 22124 | Values: 1372.4 1424.8 1437.6 1448.3 1450.4 14042.9 14402.9 14614.6 14635.9 14644.6 14870.4 15100.8 15112.8 15113.5 15121.4 2553.4 2619.0 2620.4 2621.6 2622.4 2588.9 2593.1 2638.5 2752.2 2752.5 2663.8 2671.3 2674.0 2675.0 2687.0 2590.0 2628.5 2630.8 2632.3 2632.7 2535.7 2545.3 2565.8 2566.2 2567.4 14078.9 14404.5 14408.6 14437.6 14447.9 7479.3 7733.0 7755.5 7771.5 7778.0 4937.6 5130.1 5271.5 5304.3 5376.6 1835.8 1851.2 1856.6 1910.4 1931.9 23509.3 23526.0 23660.1 23719.3 23797.4 6663.9 6692.2 6846.4 6857.3 6895.9 16187.4 16245.8 16252.2 16264.8 16276.0 7555.8 7674.8 7692.9 7695.3 7695.4 6955.1 7111.9 7280.3 7280.7 7282.7 4814.6 5063.7 5079.0 5096.3 5118.5 1507.1 1516.8 1522.6 1530.8 1531.3 877.5 896.1 903.3 908.5 918.4 7254.6 7302.3 7324.4 7339.7 7347.8 665.7 669.9 673.5 693.4 710.5 4355.5 4373.0 4375.4 4896.3 4981.4 19292.7 19889.1 19932.1 20121.1 20295.9 19578.0 19638.2 19639.6 19985.1 20007.6 9377.2 9849.6 10521.4 10700.5 10708.4 7440.7 7945.4 8779.8 8832.0 8947.7 16744.8 16754.5 16770.3 16774.3 16833.7 17024.5 17045.7 17045.9 17054.1 17351.6 10404.4 10593.8 10598.6 10598.7 10621.0 13301.5 13303.2 13375.4 13464.8 13530.8 10816.2 10917.2 11178.6 11281.5 11467.4 10112.5 10658.5 10701.1 10754.5 10942.6 9065.2 9168.5 9243.8 9246.7 9248.9 6945.2 6947.4 6972.7 6992.7 6993.4 7195.4 7210.3 7238.3 7274.4 7329.8 15:35:00 INFO - PID 22124 | 15:35:00 INFO - PID 22124 | [#4] prototype.html Cycles:180 Average:5512.86 Median:6154.98 stddev:1558.43 (25.3%) stddev-sans-first:1516.85 15:35:01 INFO - PID 22124 | Values: 508.3 509.1 522.0 530.9 535.5 6336.5 6338.2 6348.5 6353.4 6361.5 6191.7 6219.5 6237.2 6270.4 6275.8 4836.9 4888.7 4939.6 5087.5 5100.5 4916.7 4926.1 4931.9 4934.3 4945.6 4909.9 4913.2 4932.6 4952.7 4982.3 4946.0 4946.6 4970.2 4979.5 4985.5 4433.8 4434.7 4454.4 4462.0 4467.9 5966.2 5985.4 6032.6 6071.4 6118.3 5615.9 5669.5 5672.9 5688.0 5728.7 6569.6 6587.6 6601.8 6610.0 6616.6 4762.1 4819.7 4840.6 4843.6 4850.2 6639.6 6724.9 6824.9 6871.1 6942.9 4838.3 4859.3 4887.9 4902.5 4953.2 4923.6 4954.2 4998.8 5017.3 5029.9 5580.3 5640.7 5657.2 5693.6 5701.3 6575.3 6662.7 6674.9 6675.9 6695.8 1771.4 1785.8 1790.7 1795.9 1801.5 1157.2 1191.6 1193.2 1193.6 1214.6 5684.3 5748.3 5764.2 5831.8 5835.9 6695.5 6749.0 6775.4 6777.4 6890.3 5680.8 5686.4 5746.7 5750.7 5755.5 3565.3 3589.9 3592.9 3617.0 3630.6 6514.6 6580.5 6591.2 6601.8 6638.2 6918.4 6946.0 6947.4 6960.5 6992.7 6431.8 6459.7 6539.1 6571.4 6602.0 6706.4 6741.5 6760.2 6779.0 6811.9 6724.8 6745.9 6747.9 6769.0 6803.2 5870.6 6455.9 6562.1 6585.7 6618.2 4931.2 4945.4 4951.6 4969.9 4979.3 6529.2 6551.8 6644.4 6714.9 6764.4 6548.1 6557.4 6562.7 6576.6 6625.5 6474.2 6481.2 6568.3 6621.0 6628.4 6264.7 6407.3 6516.2 6520.8 6522.3 6486.6 6509.3 6532.4 6590.3 6612.1 6360.2 6384.7 6417.3 6441.6 6488.9 15:35:01 INFO - PID 22124 | 15:35:01 INFO - PID 22124 | [#5] yui.html Cycles:180 Average:23239.50 Median:19049.41 stddev:30886.62 (162.1%) stddev-sans-first:30927.80 15:35:01 INFO - PID 22124 | Values: 920.5 921.3 935.5 945.0 946.7 18399.2 19193.0 19195.9 19197.2 19199.1 17872.6 17890.2 18291.4 22088.7 22144.8 1053.1 1076.1 1076.7 1079.5 1079.7 1017.9 1053.3 1066.1 1077.6 1077.6 1111.8 1116.1 1116.3 1120.8 1121.5 1112.0 1112.5 1114.1 1115.3 1115.8 814.4 814.4 815.9 818.7 819.0 18137.7 18343.4 18420.5 18880.7 20918.2 20681.9 21001.2 21251.6 21306.1 21334.3 16097.4 16507.6 16566.9 16686.2 16739.4 3560.2 3579.8 3597.6 3599.2 3599.2 109495.0 112074.4 113283.0 113708.0 114972.0 14341.6 15103.8 15123.5 15146.5 15164.0 29341.0 31014.2 31114.5 31281.4 31356.4 8183.3 8353.8 8456.6 8471.5 8479.4 4058.1 4133.6 4134.4 4135.4 4137.8 5127.7 5182.1 5229.8 5297.5 5303.5 1040.3 1049.4 1056.4 1060.8 1061.0 685.5 704.0 709.6 713.0 714.8 12246.3 12650.4 12720.5 12873.2 13114.3 664.6 668.6 671.2 672.8 682.1 5516.5 5646.4 5650.2 5651.9 5780.2 109815.5 110619.4 112794.4 115898.4 116330.0 92024.0 94688.5 96273.0 99134.5 100061.0 73879.6 81262.6 83322.6 83681.0 83705.6 12760.1 12833.4 12851.0 12875.9 12916.2 38931.8 39056.2 39095.8 39391.2 39606.6 69799.0 70361.6 70706.0 70723.0 71800.0 16973.7 18764.5 19645.3 19775.0 19786.6 19912.5 20551.4 20643.4 20693.4 20889.3 17728.6 18145.4 18176.9 18215.1 18596.4 17678.3 18584.3 18812.5 18830.8 18905.8 17358.9 17549.7 18041.3 18302.9 18699.6 12305.3 12429.0 12761.6 13959.4 14499.6 13122.8 13582.5 13920.3 13989.8 14025.1 15:35:01 INFO - PID 22124 | -------- Summary: end -------- 15:35:01 INFO - PID 22124 | 15:35:01 INFO - TEST-INFO | 22124: exit 0 15:35:01 INFO - TEST-OK | dromaeo_css | took 1111964ms 15:35:01 INFO - TEST-START | kraken 15:35:01 INFO - Initialising browser for kraken test... 15:35:01 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:59303/getInfo.html -profile /tmp/tmpkFhmwJ/profile 15:35:01 INFO - TEST-INFO | started process 22507 (/builds/slave/test/build/application/firefox/firefox http://localhost:59303/getInfo.html) 15:35:07 INFO - TEST-INFO | 22507: exit 0 15:35:07 INFO - Browser initialized. 15:35:07 INFO - Running cycle 1/1 for kraken test... 15:35:07 INFO - TEST-INFO | started process 22741 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpkFhmwJ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 15:35:08 INFO - PID 22741 | 15:35:08 INFO - PID 22741 | (/builds/slave/test/build/application/firefox/firefox:22796): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 15:35:08 INFO - PID 22741 | 15:35:19 INFO - PID 22741 | RSS: Main: 167006208 15:35:19 INFO - PID 22741 | 15:35:21 INFO - PID 22741 | Cycle 1(1): loaded http://localhost:59303/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:59303/tests/kraken/driver.html?testName=audio-beat-detection) 15:35:22 INFO - PID 22741 | RSS: Main: 173514752 15:35:22 INFO - PID 22741 | 15:35:25 INFO - PID 22741 | Cycle 1(1): loaded http://localhost:59303/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:59303/tests/kraken/driver.html?testName=audio-dft) 15:35:26 INFO - PID 22741 | RSS: Main: 173674496 15:35:26 INFO - PID 22741 | 15:35:29 INFO - PID 22741 | Cycle 1(1): loaded http://localhost:59303/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:59303/tests/kraken/driver.html?testName=audio-fft) 15:35:29 INFO - PID 22741 | RSS: Main: 168886272 15:35:29 INFO - PID 22741 | 15:35:32 INFO - PID 22741 | Cycle 1(1): loaded http://localhost:59303/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:59303/tests/kraken/driver.html?testName=audio-oscillator) 15:35:32 INFO - PID 22741 | RSS: Main: 169193472 15:35:32 INFO - PID 22741 | 15:35:34 INFO - PID 22741 | Cycle 1(1): loaded http://localhost:59303/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:59303/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:35:35 INFO - PID 22741 | RSS: Main: 169345024 15:35:35 INFO - PID 22741 | 15:35:38 INFO - PID 22741 | Cycle 1(1): loaded http://localhost:59303/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:59303/tests/kraken/driver.html?testName=imaging-darkroom) 15:35:39 INFO - PID 22741 | RSS: Main: 172146688 15:35:39 INFO - PID 22741 | 15:35:42 INFO - PID 22741 | Cycle 1(1): loaded http://localhost:59303/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:59303/tests/kraken/driver.html?testName=imaging-desaturate) 15:35:43 INFO - PID 22741 | RSS: Main: 171335680 15:35:43 INFO - PID 22741 | 15:35:46 INFO - PID 22741 | Cycle 1(1): loaded http://localhost:59303/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:59303/tests/kraken/driver.html?testName=json-parse-financial) 15:35:46 INFO - PID 22741 | RSS: Main: 172167168 15:35:46 INFO - PID 22741 | 15:35:48 INFO - PID 22741 | Cycle 1(1): loaded http://localhost:59303/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:59303/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:35:49 INFO - PID 22741 | RSS: Main: 172654592 15:35:49 INFO - PID 22741 | 15:35:51 INFO - PID 22741 | Cycle 1(1): loaded http://localhost:59303/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:59303/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:35:51 INFO - PID 22741 | RSS: Main: 172593152 15:35:51 INFO - PID 22741 | 15:35:53 INFO - PID 22741 | Cycle 1(1): loaded http://localhost:59303/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:59303/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:35:54 INFO - PID 22741 | RSS: Main: 172605440 15:35:54 INFO - PID 22741 | 15:35:57 INFO - PID 22741 | Cycle 1(1): loaded http://localhost:59303/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:59303/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:35:58 INFO - PID 22741 | RSS: Main: 172695552 15:35:58 INFO - PID 22741 | 15:36:01 INFO - PID 22741 | Cycle 1(1): loaded http://localhost:59303/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:59303/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:36:01 INFO - PID 22741 | RSS: Main: 173416448 15:36:01 INFO - PID 22741 | 15:36:03 INFO - PID 22741 | Cycle 1(1): loaded http://localhost:59303/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:59303/tests/kraken/driver.html?testName=ai-astar) 15:36:03 INFO - PID 22741 | RSS: Main: 174407680 15:36:03 INFO - PID 22741 | 15:36:03 INFO - PID 22741 | __start_tp_report 15:36:03 INFO - PID 22741 | _x_x_mozilla_page_load 15:36:03 INFO - PID 22741 | _x_x_mozilla_page_load_details 15:36:03 INFO - PID 22741 | |i|pagename|runs| 15:36:03 INFO - PID 22741 | |0;ai-astar;101;107;104;109;109;115;109;114;111;110 15:36:03 INFO - PID 22741 | |1;audio-beat-detection;142;118;148;180;116;152;148;126;176;118 15:36:03 INFO - PID 22741 | |2;audio-dft;164;169;162;166;163;172;168;161;166;165 15:36:03 INFO - PID 22741 | |3;audio-fft;75;75;75;174;76;78;73;81;159;81 15:36:03 INFO - PID 22741 | |4;audio-oscillator;80;80;125;78;78;79;127;80;79;138 15:36:03 INFO - PID 22741 | |5;imaging-gaussian-blur;118;118;113;114;115;115;114;114;114;119 15:36:03 INFO - PID 22741 | |6;imaging-darkroom;149;150;151;151;150;155;151;150;151;150 15:36:03 INFO - PID 22741 | |7;imaging-desaturate;94;95;100;93;96;94;94;95;94;94 15:36:03 INFO - PID 22741 | |8;json-parse-financial;54;55;61;54;54;54;53;56;54;54 15:36:03 INFO - PID 22741 | |9;json-stringify-tinderbox;55;54;54;54;55;55;55;55;55;56 15:36:03 INFO - PID 22741 | |10;stanford-crypto-aes;86;76;84;77;83;78;78;80;77;79 15:36:03 INFO - PID 22741 | |11;stanford-crypto-ccm;134;123;123;133;132;130;127;125;123;173 15:36:03 INFO - PID 22741 | |12;stanford-crypto-pbkdf2;193;175;187;178;178;181;177;180;182;177 15:36:03 INFO - PID 22741 | |13;stanford-crypto-sha256-iterative;71;74;69;67;68;67;69;75;66;67 15:36:03 INFO - PID 22741 | __end_tp_report 15:36:03 INFO - PID 22741 | __start_cc_report 15:36:03 INFO - PID 22741 | _x_x_mozilla_cycle_collect,974 15:36:03 INFO - PID 22741 | __end_cc_report 15:36:03 INFO - PID 22741 | __startTimestamp1502836563911__endTimestamp 15:36:03 INFO - PID 22741 | 15:36:03 INFO - PID 22741 | ------- Summary: start ------- 15:36:03 INFO - PID 22741 | Number of tests: 14 15:36:03 INFO - PID 22741 | 15:36:03 INFO - PID 22741 | [#0] ai-astar Cycles:10 Average:108.90 Median:109.50 stddev:4.20 (3.8%) stddev-sans-first:3.35 15:36:03 INFO - PID 22741 | Values: 101.0 107.0 104.0 109.0 109.0 115.0 109.0 114.0 111.0 110.0 15:36:03 INFO - PID 22741 | 15:36:03 INFO - PID 22741 | [#1] audio-beat-detection Cycles:10 Average:142.40 Median:148.00 stddev:23.22 (15.7%) stddev-sans-first:24.63 15:36:03 INFO - PID 22741 | Values: 142.0 118.0 148.0 180.0 116.0 152.0 148.0 126.0 176.0 118.0 15:36:03 INFO - PID 22741 | 15:36:03 INFO - PID 22741 | [#2] audio-dft Cycles:10 Average:165.60 Median:166.00 stddev:3.37 (2.0%) stddev-sans-first:3.53 15:36:03 INFO - PID 22741 | Values: 164.0 169.0 162.0 166.0 163.0 172.0 168.0 161.0 166.0 165.0 15:36:03 INFO - PID 22741 | 15:36:03 INFO - PID 22741 | [#3] audio-fft Cycles:10 Average:94.70 Median:75.50 stddev:38.10 (50.5%) stddev-sans-first:39.73 15:36:03 INFO - PID 22741 | Values: 75.0 75.0 75.0 174.0 76.0 78.0 73.0 81.0 159.0 81.0 15:36:03 INFO - PID 22741 | 15:36:03 INFO - PID 22741 | [#4] audio-oscillator Cycles:10 Average:94.40 Median:79.00 stddev:24.80 (31.4%) stddev-sans-first:25.75 15:36:03 INFO - PID 22741 | Values: 80.0 80.0 125.0 78.0 78.0 79.0 127.0 80.0 79.0 138.0 15:36:03 INFO - PID 22741 | 15:36:03 INFO - PID 22741 | [#5] imaging-gaussian-blur Cycles:10 Average:115.40 Median:115.00 stddev:2.12 (1.8%) stddev-sans-first:2.03 15:36:03 INFO - PID 22741 | Values: 118.0 118.0 113.0 114.0 115.0 115.0 114.0 114.0 114.0 119.0 15:36:03 INFO - PID 22741 | 15:36:03 INFO - PID 22741 | [#6] imaging-darkroom Cycles:10 Average:150.80 Median:151.00 stddev:1.62 (1.1%) stddev-sans-first:1.58 15:36:03 INFO - PID 22741 | Values: 149.0 150.0 151.0 151.0 150.0 155.0 151.0 150.0 151.0 150.0 15:36:03 INFO - PID 22741 | 15:36:03 INFO - PID 22741 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:94.00 stddev:1.97 (2.1%) stddev-sans-first:2.06 15:36:03 INFO - PID 22741 | Values: 94.0 95.0 100.0 93.0 96.0 94.0 94.0 95.0 94.0 94.0 15:36:03 INFO - PID 22741 | 15:36:03 INFO - PID 22741 | [#8] json-parse-financial Cycles:10 Average:54.90 Median:54.00 stddev:2.28 (4.2%) stddev-sans-first:2.40 15:36:03 INFO - PID 22741 | Values: 54.0 55.0 61.0 54.0 54.0 54.0 53.0 56.0 54.0 54.0 15:36:03 INFO - PID 22741 | 15:36:03 INFO - PID 22741 | [#9] json-stringify-tinderbox Cycles:10 Average:54.80 Median:55.00 stddev:0.63 (1.1%) stddev-sans-first:0.67 15:36:03 INFO - PID 22741 | Values: 55.0 54.0 54.0 54.0 55.0 55.0 55.0 55.0 55.0 56.0 15:36:03 INFO - PID 22741 | 15:36:03 INFO - PID 22741 | [#10] stanford-crypto-aes Cycles:10 Average:79.80 Median:79.50 stddev:3.39 (4.3%) stddev-sans-first:2.76 15:36:03 INFO - PID 22741 | Values: 86.0 76.0 84.0 77.0 83.0 78.0 78.0 80.0 77.0 79.0 15:36:03 INFO - PID 22741 | 15:36:03 INFO - PID 22741 | [#11] stanford-crypto-ccm Cycles:10 Average:132.30 Median:131.00 stddev:14.93 (11.4%) stddev-sans-first:15.82 15:36:03 INFO - PID 22741 | Values: 134.0 123.0 123.0 133.0 132.0 130.0 127.0 125.0 123.0 173.0 15:36:03 INFO - PID 22741 | 15:36:03 INFO - PID 22741 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:180.80 Median:180.50 stddev:5.45 (3.0%) stddev-sans-first:3.57 15:36:03 INFO - PID 22741 | Values: 193.0 175.0 187.0 178.0 178.0 181.0 177.0 180.0 182.0 177.0 15:36:03 INFO - PID 22741 | 15:36:03 INFO - PID 22741 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.30 Median:69.00 stddev:3.09 (4.5%) stddev-sans-first:3.22 15:36:03 INFO - PID 22741 | Values: 71.0 74.0 69.0 67.0 68.0 67.0 69.0 75.0 66.0 67.0 15:36:03 INFO - PID 22741 | -------- Summary: end -------- 15:36:03 INFO - PID 22741 | 15:36:04 INFO - PID 22741 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 15:36:04 INFO - TEST-INFO | 22741: exit 0 15:36:04 INFO - TEST-OK | kraken | took 62928ms 15:36:04 INFO - SUITE-END | took 1174s 15:36:05 INFO - Completed test suite (00:19:35) 15:36:05 INFO - javascript benchmark 15:36:05 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8551.221549120268, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3153.2432216268103, 3174.825384603846, 3175.6348019977695, 3187.5856138701974, 3205.4230238557066, 6125.0506171543375, 6200.379962003803, 6232.096346029829, 6256.092866534355, 6277.995520716686, 6039.245094363205, 6123.3876612338745, 6135.539834512407, 6141.355157708444, 6169.722362493687, 5614.9157762633595, 5625.099984002561, 5642.830715078554, 5648.74580643872, 5674.0, 5649.717514124298, 5652.236948012028, 5656.632318899258, 5666.914996275059, 5688.544916406699, 5746.7413966371405, 5775.480206781389, 5779.7688092476465, 5784.566157538202, 5794.536437085023, 5606.943930560683, 5652.152177173416, 5685.346185188715, 5729.369769325413, 5768.596198266144, 5397.487238712316, 5437.864053398657, 5555.749991250363, 5564.360098588673, 5586.050371436865, 6037.02428877148, 6121.571489995702, 6146.29317628474, 6215.067739839015, 6220.720067596969, 6467.644279564622, 6579.736810527573, 6606.768763093269, 6667.366600172976, 6673.265940746514, 6615.305392933721, 6632.469402447792, 6649.60102393858, 6656.467482601381, 6665.200175978865, 6022.909656355158, 6150.876982460326, 6158.6612736299485, 6164.599301045418, 6170.784022559189, 6886.345797149263, 6902.689378977958, 6918.757843475454, 6939.132608423947, 6952.721891124298, 6540.345965403421, 6560.770373036921, 6564.9671751640935, 6640.0, 6651.401373876374, 6477.902831457532, 6679.933200668028, 6697.665116744143, 6759.729610815611, 6782.728690852311, 6475.449586785176, 6542.542022058506, 6543.509236807164, 6564.343565643398, 6575.145231120019, 6593.142891424084, 6681.465482761368, 6714.462842972551, 6727.62998035103, 6758.155230596176, 5263.0, 5328.813491527778, 5339.893202135935, 5340.706261155596, 5346.518813306743, 4500.837446013294, 4536.206163921301, 4592.127495775792, 4604.332371806069, 4612.677112602086, 5582.441755824466, 5668.914966275509, 5672.574556908248, 5737.311522617312, 5743.62666426681, 6618.271990081087, 6705.228898676714, 6707.0, 6709.496787740939, 6719.0, 5730.426957304237, 5742.54059675225, 5800.709964501758, 5804.390538993429, 5804.738786754606, 5820.738066787005, 5843.5909486336395, 5911.083782013795, 5918.112283157475, 5927.436893495197, 6261.373862613702, 6400.519961002943, 6417.518686098561, 6441.194850643669, 6550.213974323112, 6591.703373348307, 6596.2744098148205, 6612.206535215805, 6616.073749675145, 6621.900671489835, 6289.308176100534, 6336.17629708135, 6344.619322840645, 6347.365263473615, 6379.808605741835, 6457.741690332522, 6473.64394958263, 6479.1901012373455, 6553.836154096186, 6565.212174539086, 6729.5625784323865, 6731.798046058626, 6747.257801641914, 6770.390664840188, 6777.356151165632, 6462.030695395728, 6481.0, 6540.411362977355, 6553.606783593, 6579.243387010555, 6458.935410645833, 6468.353164683494, 6534.673266336759, 6538.574992625464, 6554.180727409074, 6280.340564240872, 6307.211598550181, 6353.237611486651, 6367.649779261999, 6477.222733272038, 6568.981807819796, 6574.276829548841, 6590.2421221560135, 6621.470282377494, 6635.867282654416, 6121.418465245794, 6215.720292586935, 6241.469475094668, 6298.842528936813, 6380.521460890544], "value": 6052.818749072197, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [832.5920299053462, 839.8950131233596, 862.5902696219297, 879.8680197970306, 890.3322508118907, 41137.0, 41798.164036719245, 41878.37182442262, 41996.790016049956, 42264.0, 38684.22631547367, 39068.0, 39131.80434097833, 39170.8041459793, 39177.41233881494, 6819.624920629363, 6978.720851165947, 6980.127484064492, 6984.4412447004115, 6987.441004719611, 6809.944458608924, 6903.482238832083, 6905.482088843331, 6909.689063992132, 6911.48163887708, 7605.809854753647, 7765.728199513018, 7803.10264319602, 7803.765887023398, 7812.257835505618, 7522.097348318237, 7647.120581133157, 7739.57432341221, 7740.6129693515095, 7743.574103424309, 7575.6969721211635, 7589.203133670941, 7642.121156067068, 7648.0, 7650.808729781745, 39544.802275988724, 39923.8003809982, 40085.79957100225, 40101.398479022835, 40139.59860401417, 3515.2793677296095, 3526.7883926964464, 3545.9113522161892, 3550.218951830619, 3559.3771090059136, 32167.0, 32170.517442238386, 32275.51586726201, 32286.0, 32325.0, 5090.236464530313, 5094.3886733591835, 5138.2806407103035, 5144.717040562768, 5189.065968125736, 175838.0, 180139.0, 187113.0, 189127.0, 200018.0, 52008.47991520112, 52062.739686302084, 52099.47900520946, 52139.0, 52291.477085229424, 53284.0, 53901.1914821278, 53966.46033539693, 54009.729951350775, 54128.45871541313, 16928.915355423145, 17009.149542522824, 17036.574085647957, 17049.14754262282, 17054.232559534852, 13904.096233744976, 13914.373853176632, 13940.58178254654, 13943.093698909539, 13963.301834908214, 15096.924515377354, 15113.622159446102, 15138.0, 15164.772528412082, 15367.077975321516, 5698.914516282176, 5988.730507127189, 6027.095935609694, 6067.150598916155, 6088.847778805565, 977.8582105594649, 1003.6838395905266, 1006.5420233793607, 1007.8588997540351, 1008.6923488335981, 22926.19758308451, 22976.770232297797, 23246.76753232446, 23255.558386157438, 23320.183793567143, 1104.1222228328497, 1111.4609414433994, 1117.4628423721329, 1120.6357933671588, 1125.5216532973454, 12449.17483855534, 12463.065270104778, 12505.749885002431, 12577.496900124075, 12597.307148106758, 236043.0, 237239.0, 237801.0, 237829.0, 237948.0, 52333.0, 52342.0, 52408.737956309975, 52674.47325526772, 52692.736536317076, 1425.4796999095256, 1462.393106860662, 1479.5265515035085, 1480.052766229614, 1484.7921291019265, 22250.443738906655, 22981.0, 23077.53844923126, 23418.53162936766, 27621.0, 53410.46589534055, 53774.731126344115, 53810.730946345015, 54016.0, 54055.459445405046, 207694.0, 208993.0, 210274.0, 210657.0, 210657.0, 31466.37067258688, 32425.0, 32456.188595285308, 36074.09814754652, 36715.44926826046, 43717.0, 45554.0, 45833.312500313186, 45870.77064614656, 46872.7656361716, 33383.0, 33798.66201337955, 33844.83077584596, 33905.83047084749, 33976.15059623529, 32842.50736239006, 33216.33567328592, 33360.332793343514, 33558.83220583979, 33581.83209083939, 36139.45790813088, 36506.26987460183, 38612.6138738609, 39702.404463932486, 39838.80080599579, 34970.47544286787, 35244.29511409809, 35244.64755352413, 35641.28717425585, 35830.82084589665, 23961.640575391033, 25375.238742837744, 25481.10816121501, 25855.0, 25943.22170334893], "value": 17649.47560534087, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [562.9634073785204, 572.8023831778036, 577.9135225775539, 579.3250862744901, 594.6372712645285, 22190.334289971288, 22854.200102996398, 23111.075556977743, 23179.18872839451, 23243.0, 26071.73928260717, 26148.73851261487, 26347.73652263477, 26604.201873943763, 26748.0, 6667.233268174162, 6853.657317134148, 6855.4172895303955, 6855.657217139148, 6855.691493882762, 5972.134040564116, 6234.376562343752, 6451.16134902462, 6458.031295305718, 6494.675266236693, 6441.1304473896125, 6459.321771214025, 6467.223933128032, 6472.741090356403, 6478.708458119372, 6429.582077164992, 6439.9356006440275, 6445.129907462503, 6450.2259728832605, 6452.0, 5997.700114994276, 5998.1602575639445, 5998.220231369894, 6016.157737916695, 6019.849503762397, 29419.11742647724, 29559.556606650916, 30030.399392012256, 31051.068467946, 31085.378292434023, 1878.605492846504, 1931.2757715856553, 1931.5267759398896, 1934.864559480837, 1939.3212375668545, 19805.504862378555, 19922.701159482618, 20705.792942070537, 20792.896035519727, 20866.165353385848, 2310.595645761985, 2323.6398358254496, 2360.9527809443716, 2364.137089962169, 2368.786809187164, 134358.0, 140759.0, 141692.0, 142215.86676199865, 142290.0, 14751.40994360235, 15286.770698439532, 15349.232538373037, 15385.538253893008, 15600.531984040497, 23874.88062559676, 23877.0, 23907.16324928654, 23945.640815387436, 23984.40038999004, 9914.305998580174, 10141.29010969225, 10239.692809215736, 10288.12550933179, 10307.74230644225, 8616.526091065052, 9442.716718498456, 9501.097395747393, 9557.569909354095, 9574.234061275081, 8630.697925572575, 11741.882581174079, 12070.215435996632, 12104.757904841854, 12207.450664720109, 3869.342211823997, 4045.3931910213114, 4336.848210312624, 4355.564443555619, 4457.7771111444945, 951.46242373059, 956.1768827838497, 966.9661561845302, 979.8236317462926, 981.0434826044692, 19593.216271348985, 19826.306079287155, 19855.801441985393, 20150.596988060155, 24275.757242427702, 1037.1391744851755, 1042.775803202315, 1070.844727514506, 1072.2494254022215, 1072.6031368393744, 10926.289791163548, 11219.943900280447, 11326.943365283121, 11435.313881167156, 13737.656558586115, 483710.0, 484559.0, 484910.0, 486443.0, 495137.0, 113265.0, 116294.0, 116705.41647291879, 116986.0, 118053.0, 1258.9622311330675, 1283.7625039367747, 1308.535469908188, 1313.3105119811985, 1313.6124843171287, 21059.578808424052, 21116.472088197304, 21211.575768484854, 21444.24945126913, 21523.0, 56935.71532142313, 56957.715211423674, 57971.0, 59000.0, 61023.0, 68439.94680159603, 68563.31436685768, 69045.65477172582, 69613.0, 70849.64575177297, 14947.402103915721, 15317.693646126792, 15328.463503777315, 15358.846411535742, 15362.30869610848, 24177.395565111012, 24303.51392972166, 24483.877580611985, 24521.87739061293, 24527.75472245326, 18856.905715471334, 19485.220591176196, 19529.511762206057, 19865.60268794587, 19924.0, 17111.57221069483, 17703.114844257478, 17848.28606855763, 18056.90971545134, 18200.99894505815, 19246.903765481642, 19838.10728517249, 19861.900690496455, 19917.402477925687, 19989.60020799605, 14569.708605828037, 14818.25908704582, 14915.433879442502, 14961.326740296496, 14978.850211497745, 18968.051597420348, 19001.619967600847, 19082.713759293343, 19323.8067619322, 19360.4191874244], "value": 12467.510292385115, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1372.4373007067104, 1424.7934049562814, 1437.6334034821118, 1448.2686243447058, 1450.3618407900517, 14042.859571404282, 14402.855971440309, 14614.56156315311, 14635.926820365883, 14644.633884152901, 14870.405183792662, 15100.773488397655, 15112.773308400383, 15113.471028514005, 15121.395144194219, 2553.438243586408, 2619.0440489221487, 2620.3711109333813, 2621.646077779504, 2622.4230669252734, 2588.93527661809, 2593.053535459553, 2638.538255805236, 2752.174347695693, 2752.477029364424, 2663.8002149838744, 2671.3321669582606, 2674.0, 2675.036986684792, 2686.9596956045675, 2590.041684576709, 2628.487444948236, 2630.7500787425165, 2632.328756167184, 2632.710401855804, 2535.7210706822234, 2545.287319550529, 2565.8332208406396, 2566.242958327298, 2567.3838278813137, 14078.929605351908, 14404.495842645561, 14408.63978400538, 14437.639059023504, 14447.855521444757, 7479.289467500579, 7732.961335193344, 7755.495892766952, 7771.455998080137, 7777.961110194413, 4937.604991600663, 5130.050940576006, 5271.499207575274, 5304.2839216706025, 5376.596755243372, 1835.8347748702547, 1851.2132343753985, 1856.5915498590423, 1910.388675623801, 1931.9323823666246, 23509.294721158392, 23526.0, 23660.053597856066, 23719.288421347388, 23797.405064873343, 6663.900041499382, 6692.163479565054, 6846.418054465326, 6857.27998560154, 6895.862082758316, 16187.433439829549, 16245.837541624669, 16252.18739063042, 16264.83735162657, 16276.0, 7555.773326800205, 7674.76975690719, 7692.92307076922, 7695.345895598937, 7695.422843286664, 6955.130608673916, 7111.857762844714, 7280.271972802678, 7280.6723697433745, 7282.70869165238, 4814.566688998007, 5063.746812659426, 5079.034983353151, 5096.311997880239, 5118.488151184852, 1507.148461119464, 1516.840731723153, 1522.6345677037432, 1530.7703844423204, 1531.2802982598162, 877.4603618774407, 896.0994200828126, 903.2909166304551, 908.5411867007119, 918.3617385916857, 7254.600996945113, 7302.2697730226555, 7324.37742791858, 7339.7064117435775, 7347.8163045924275, 665.7303791964263, 669.8693754717881, 673.4612310151957, 693.4383149648801, 710.4529512275519, 4355.455568053993, 4373.0, 4375.409319741858, 4896.3389942358035, 4981.352424184833, 19292.710609340873, 19889.10499027547, 19932.103055362542, 20121.094550745252, 20295.898520507304, 19578.0, 19638.21447142127, 19639.607207855763, 19985.100670469863, 20007.59984800296, 9377.15605595486, 9849.556769945513, 10521.368717876952, 10700.464976751131, 10708.411037393018, 7440.73957411488, 7945.364370850433, 8779.780505487159, 8831.955840220759, 8947.731568052968, 16744.832551674328, 16754.497365079067, 16770.266899476177, 16774.32902683879, 16833.747493787847, 17024.48926532206, 17045.659086817945, 17045.91477042607, 17054.14729263507, 17351.56621084483, 10404.375737455777, 10593.788124237626, 10598.576056957636, 10598.735031623963, 10621.044106030495, 13301.534446294334, 13303.201807891557, 13375.39810708501, 13464.798028029392, 13530.797038044635, 10816.188785840935, 10917.181211409335, 11178.608748693756, 11281.548738050386, 11467.369294688539, 10112.494375281354, 10658.467076645982, 10701.090407315465, 10754.462276885968, 10942.617008404668, 9065.184133428023, 9168.495732734496, 9243.76890577741, 9246.722598322061, 9248.861267081134, 6945.166580010431, 6947.40947019509, 6972.651367431709, 6992.685329160098, 6993.4055605274125, 7195.388391986739, 7210.315020073084, 7238.312360325761, 7274.418046556369, 7329.780106596811], "value": 6332.755249642791, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [508.3036240350719, 509.08619028843225, 521.9686818790873, 530.8991291654588, 535.4538370861719, 6336.497083651961, 6338.176037115174, 6348.46038086762, 6353.3646635336445, 6361.459275961549, 6191.659458729768, 6219.533534984872, 6237.220347456568, 6270.372962703727, 6275.843103922404, 4836.879078023042, 4888.657793954407, 4939.604831613462, 5087.465816089313, 5100.520848953799, 4916.680415772981, 4926.064047830901, 4931.926021109686, 4934.28452874333, 4945.554900058994, 4909.877253068684, 4913.1647619904525, 4932.5807306379, 4952.653314268003, 4982.252662100677, 4945.975270123662, 4946.604271658259, 4970.204767237225, 4979.45226025137, 4985.501449855022, 4433.800478978454, 4434.667399945017, 4454.420925279725, 4461.977690111561, 4467.932981005255, 5966.164736936832, 5985.431384018511, 6032.607880487754, 6071.362506936753, 6118.296395914483, 5615.943840561583, 5669.461401166883, 5672.8865422691315, 5688.0, 5728.682403047323, 6569.638669873155, 6587.5718078325235, 6601.768938087192, 6610.01699490151, 6616.603003819786, 4762.1428142934255, 4819.722773465034, 4840.588549973257, 4843.636727245435, 4850.151223535902, 6639.634820084893, 6724.9327506725285, 6824.897626535606, 6871.072405225281, 6942.895856562156, 4838.250071238964, 4859.34398856157, 4887.877803054881, 4902.48523904992, 4953.207486802166, 4923.630727695438, 4954.182559877652, 4998.750062496861, 5017.347744793154, 5029.924551131736, 5580.302462192226, 5640.71796410186, 5657.246253661378, 5693.572982026365, 5701.287339082614, 6575.309592492815, 6662.701368028022, 6674.933250667431, 6675.866482670416, 6695.832604184934, 1771.4057348386555, 1785.8482029027678, 1790.6955817511055, 1795.9102044897702, 1801.4865763257408, 1157.2015309436463, 1191.5710344276054, 1193.1826698711236, 1193.570314686712, 1214.6356093172012, 5684.346300175533, 5748.338941021836, 5764.221830052973, 5831.766729330864, 5835.854103647443, 6695.46436285096, 6749.0, 6775.373910261502, 6777.423918966844, 6890.27834005575, 5680.77276908919, 5686.402927692616, 5746.712664366849, 5750.654960702372, 5755.510781583613, 3565.2691198304287, 3589.8743543975834, 3592.8742494012586, 3617.0, 3630.5643322801434, 6514.576552524166, 6580.506462015368, 6591.176102987127, 6601.768938087144, 6638.17022872141, 6918.37734603878, 6946.0, 6947.407337093587, 6960.512764106464, 6992.720291188295, 6431.839204019937, 6459.741610335628, 6539.090104903273, 6571.3757193066585, 6601.966990165114, 6706.396424321834, 6741.471948019493, 6760.18877734675, 6779.0, 6811.93188068113, 6724.830605024916, 6745.898811517733, 6747.865042699217, 6769.0, 6803.21068361989, 5870.618409803349, 6455.86824432373, 6562.114114594659, 6585.703643335965, 6618.238902526078, 4931.186354251508, 4945.381827271591, 4951.554360107608, 4969.900601988013, 4979.278004689228, 6529.249136349381, 6551.836204094936, 6644.368784965421, 6714.899276510759, 6764.391204791593, 6548.148740663683, 6557.377049180321, 6562.671866406756, 6576.572522786004, 6625.515431713107, 6474.158359413246, 6481.157449531531, 6568.343165683394, 6621.0, 6628.403443690091, 6264.655443950444, 6407.295197528175, 6516.218053833571, 6520.771772987922, 6522.315156908457, 6486.578372405778, 6509.251436084911, 6532.366908272932, 6590.308017658077, 6612.140421745143, 6360.204974378203, 6384.712687929147, 6417.26201486835, 6441.6135031896365, 6488.870222595616], "value": 5055.7712262168725, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [920.4983284110159, 921.3044151665491, 935.5322338830584, 945.0360632155201, 946.7159852044385, 18399.172037258322, 19193.0, 19195.904020479895, 19197.232110715555, 19199.13603887825, 17872.64254714908, 17890.194941227644, 18291.359802406918, 22088.668669969924, 22144.778552214473, 1053.0838170791385, 1076.0907033556632, 1076.7146706122883, 1079.4602698650674, 1079.7138758229075, 1017.872765904262, 1053.3258714422775, 1066.0884943373383, 1077.6174458067392, 1077.6174458067392, 1111.8443417921496, 1116.0959622705593, 1116.2799994003863, 1120.848685427465, 1121.5121422181337, 1111.9610813621525, 1112.5438570186225, 1114.0697517572837, 1115.3475216998024, 1115.7935781880296, 814.3647954595425, 814.4095530740225, 815.889854869591, 818.7339114787718, 818.9672413103468, 18137.727934081, 18343.35798247055, 18420.53948651281, 18880.716789248174, 20918.163273469043, 20681.89659051695, 21001.159953601837, 21251.574968500543, 21306.14775408982, 21334.25330113454, 16097.356105755756, 16507.58731031734, 16566.917165414216, 16686.24911878956, 16739.414120505844, 3560.1633616100194, 3579.785212887209, 3597.6222496638, 3599.1721903961975, 3599.1721903961975, 109495.0, 112074.43962780216, 113283.0, 113707.99999999917, 114972.0, 14341.6414589634, 15103.848961510244, 15123.546293611209, 15146.469873554594, 15164.0, 29341.0, 31014.22464438362, 31114.53328200079, 31281.374372512877, 31356.372872542423, 8183.304419124441, 8353.83292334162, 8456.619452124669, 8471.491710497266, 8479.406441549032, 4058.127502586899, 4133.565975572522, 4134.441850350225, 4135.400366946836, 4137.81379837908, 5127.717975511308, 5182.067227899014, 5229.816956406507, 5297.54970827484, 5303.522682958553, 1040.3445829127602, 1049.4228174503992, 1056.3556230699267, 1060.7931453366673, 1060.992057545335, 685.5132855672429, 703.96480175991, 709.6309918842173, 712.9607871567114, 714.8248679073556, 12246.326452045223, 12650.367481625883, 12720.491180352867, 12873.163244389272, 13114.278714670594, 664.64773669955, 668.5687731413211, 671.214678825775, 672.7813460625316, 682.0655701688718, 5516.475934786189, 5646.435356464321, 5650.152477128381, 5651.9152212716845, 5780.219670344533, 109815.45092274487, 110619.44690276498, 112794.43602781933, 115898.42050789692, 116330.0, 92024.0, 94688.52655736677, 96273.0, 99134.5043274779, 100061.0, 73879.63060184772, 81262.59368703119, 83322.58338708268, 83681.0, 83705.58147209225, 12760.106792524433, 12833.358332083546, 12851.0, 12875.871241287467, 12916.160449570747, 38931.805340973115, 39056.21887562289, 39095.82712518629, 39391.212175755754, 39606.603933960294, 69799.0, 70361.64819175871, 70706.0, 70723.0, 71800.0, 16973.745393818856, 18764.530886727942, 19645.31241406544, 19775.0, 19786.604267914274, 19912.50218744485, 20551.383458496268, 20643.380698578465, 20693.379198624065, 20889.26887558928, 17728.645427091127, 18145.364912228008, 18176.90911545434, 18215.089245537405, 18596.44210673682, 17678.292868285123, 18584.349547765763, 18812.52968675794, 18830.811691882907, 18905.810941890406, 17358.91320543389, 17549.736753948957, 18041.278348865897, 18302.908485457487, 18699.626007480045, 12305.26168429897, 12429.0, 12761.61715148547, 13959.441622335398, 14499.637509062359, 13122.803157952829, 13582.524611638546, 13920.303984800921, 13989.790153147507, 14025.088369255966], "value": 9168.3527330078, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1538.9999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 107.0, 104.0, 109.0, 109.0, 115.0, 109.0, 114.0, 111.0, 110.0], "value": 108.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 118.0, 148.0, 180.0, 116.0, 152.0, 148.0, 126.0, 176.0, 118.0], "value": 142.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 169.0, 162.0, 166.0, 163.0, 172.0, 168.0, 161.0, 166.0, 165.0], "value": 165.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 75.0, 174.0, 76.0, 78.0, 73.0, 81.0, 159.0, 81.0], "value": 94.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 80.0, 125.0, 78.0, 78.0, 79.0, 127.0, 80.0, 79.0, 138.0], "value": 94.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 118.0, 113.0, 114.0, 115.0, 115.0, 114.0, 114.0, 114.0, 119.0], "value": 115.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 150.0, 151.0, 151.0, 150.0, 155.0, 151.0, 150.0, 151.0, 150.0], "value": 150.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 100.0, 93.0, 96.0, 94.0, 94.0, 95.0, 94.0, 94.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 55.0, 61.0, 54.0, 54.0, 54.0, 53.0, 56.0, 54.0, 54.0], "value": 54.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 54.0, 54.0, 54.0, 55.0, 55.0, 55.0, 55.0, 55.0, 56.0], "value": 54.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 76.0, 84.0, 77.0, 83.0, 78.0, 78.0, 80.0, 77.0, 79.0], "value": 79.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 123.0, 123.0, 133.0, 132.0, 130.0, 127.0, 125.0, 123.0, 173.0], "value": 132.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [193.0, 175.0, 187.0, 178.0, 178.0, 181.0, 177.0, 180.0, 182.0, 177.0], "value": 180.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 74.0, 69.0, 67.0, 68.0, 67.0, 69.0, 75.0, 66.0, 67.0], "value": 69.3, "unit": "score"}]}]} 15:36:05 INFO - Return code: 0 15:36:05 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:36:05 INFO - # TBPL SUCCESS # 15:36:05 INFO - Running post-action listener: _package_coverage_data 15:36:05 INFO - Running post-action listener: _resource_record_post_action 15:36:05 INFO - [mozharness: 2017-08-15 22:36:05.213238Z] Finished run-tests step (success) 15:36:05 INFO - Running post-run listener: _resource_record_post_run 15:36:05 INFO - Total resource usage - Wall time: 1194s; CPU: 13.0%; Read bytes: 4096; Write bytes: 437506048; Read time: 16; Write time: 421976 15:36:05 INFO - TinderboxPrint: CPU usage
13.1% 15:36:05 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 15:36:05 INFO - TinderboxPrint: I/O write bytes / time
437,506,048 / 421,976 15:36:05 INFO - TinderboxPrint: CPU idle
8,267.6 (86.8%) 15:36:05 INFO - TinderboxPrint: CPU user
1,201.1 (12.6%) 15:36:05 INFO - TinderboxPrint: Swap in / out
0 / 0 15:36:05 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 113709056; Read time: 0; Write time: 132524 15:36:05 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 15:36:05 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 0; Write bytes: 322674688; Read time: 0; Write time: 289400 15:36:05 INFO - Running post-run listener: _upload_blobber_files 15:36:05 INFO - Blob upload gear active. 15:36:05 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:36:05 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 15:36:05 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'] 15:36:05 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 15:36:06 INFO - (blobuploader) - INFO - Open directory for files ... 15:36:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 15:36:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:36:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:36:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 15:36:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:36:07 INFO - (blobuploader) - INFO - Done attempting. 15:36:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 15:36:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:36:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:36:08 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 15:36:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:36:08 INFO - (blobuploader) - INFO - Done attempting. 15:36:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 15:36:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:36:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:36:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 15:36:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:36:08 INFO - (blobuploader) - INFO - Done attempting. 15:36:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:36:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:36:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:36:16 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:36:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:36:16 INFO - (blobuploader) - INFO - Done attempting. 15:36:16 INFO - (blobuploader) - INFO - Iteration through files over. 15:36:16 INFO - Return code: 0 15:36:16 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:36:16 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:36:16 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/843e3d687a420f13cb95aad94d49b6d6b2a728d75a039d86e525afc4ce8f980850c5b06cca6a25f3c5896799fb9678bd1771b1881755121b9d19c12e66cd7c77", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/015517266ec4a82416250bc0186f3f83e41112c1c51084484dd65d742823138fac7de7008bc53c12cdaee9863012c713e07c22dab797e0b8d0022037c19c350f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/efeb8a7ae0daf5723680c94c1fa3b3aa2846bca67b5c098b93aa65be26afd14dc24e43a5acf3476988ab9dd954124cf5b74a7878f623652a3bedf4e761374fe6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b5c727be92082595b559b78a5c1615beb05c140fa9147bf243d815123655972879ed24f2760a6c2f8d5752bf8e862871b5430cb504fcb59f406027a0c0177f24"} 15:36:16 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:36:16 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:36:16 INFO - Contents: 15:36:16 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/843e3d687a420f13cb95aad94d49b6d6b2a728d75a039d86e525afc4ce8f980850c5b06cca6a25f3c5896799fb9678bd1771b1881755121b9d19c12e66cd7c77", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/015517266ec4a82416250bc0186f3f83e41112c1c51084484dd65d742823138fac7de7008bc53c12cdaee9863012c713e07c22dab797e0b8d0022037c19c350f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/efeb8a7ae0daf5723680c94c1fa3b3aa2846bca67b5c098b93aa65be26afd14dc24e43a5acf3476988ab9dd954124cf5b74a7878f623652a3bedf4e761374fe6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b5c727be92082595b559b78a5c1615beb05c140fa9147bf243d815123655972879ed24f2760a6c2f8d5752bf8e862871b5430cb504fcb59f406027a0c0177f24"} 15:36:16 INFO - Running post-run listener: copy_logs_to_upload_dir 15:36:16 INFO - Copying logs to upload dir... 15:36:16 INFO - mkdir: /builds/slave/test/build/upload/logs 15:36:16 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1258.663320 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 58 secs) (at 2017-08-15 15:36:16.329256) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-15 15:36:16.333137) ========= 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=7de08804244eb36560b44971000001c3-1502832647.67872-1550337739 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/843e3d687a420f13cb95aad94d49b6d6b2a728d75a039d86e525afc4ce8f980850c5b06cca6a25f3c5896799fb9678bd1771b1881755121b9d19c12e66cd7c77", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/015517266ec4a82416250bc0186f3f83e41112c1c51084484dd65d742823138fac7de7008bc53c12cdaee9863012c713e07c22dab797e0b8d0022037c19c350f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/efeb8a7ae0daf5723680c94c1fa3b3aa2846bca67b5c098b93aa65be26afd14dc24e43a5acf3476988ab9dd954124cf5b74a7878f623652a3bedf4e761374fe6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b5c727be92082595b559b78a5c1615beb05c140fa9147bf243d815123655972879ed24f2760a6c2f8d5752bf8e862871b5430cb504fcb59f406027a0c0177f24"} build_url:https://queue.taskcluster.net/v1/task/RMB3t9HoRkqz14OC2cjDLw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011361 build_url: 'https://queue.taskcluster.net/v1/task/RMB3t9HoRkqz14OC2cjDLw/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/843e3d687a420f13cb95aad94d49b6d6b2a728d75a039d86e525afc4ce8f980850c5b06cca6a25f3c5896799fb9678bd1771b1881755121b9d19c12e66cd7c77", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/015517266ec4a82416250bc0186f3f83e41112c1c51084484dd65d742823138fac7de7008bc53c12cdaee9863012c713e07c22dab797e0b8d0022037c19c350f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/efeb8a7ae0daf5723680c94c1fa3b3aa2846bca67b5c098b93aa65be26afd14dc24e43a5acf3476988ab9dd954124cf5b74a7878f623652a3bedf4e761374fe6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b5c727be92082595b559b78a5c1615beb05c140fa9147bf243d815123655972879ed24f2760a6c2f8d5752bf8e862871b5430cb504fcb59f406027a0c0177f24"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-15 15:36:16.359401) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-15 15:36:16.359702) ========= 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=7de08804244eb36560b44971000001c3-1502832647.67872-1550337739 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004414 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-15 15:36:16.443556) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-15 15:36:16.445878) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-15 15:36:16.446156) ========= ========= Total master_lag: 0.22 =========