builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-083 starttime: 1487653408.7 results: success (0) revision: d84beb192e57e26846c82d3df3599381f4663792 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-20 21:03:28.695264) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-20 21:03:28.695685) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-20 21:03:28.695962) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-20 21:03:28.712538) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-20 21:03:28.712859) ========= 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=3297cc2eebb5444a7688c33f000001b5-1487647778.468706-1683341729 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-02-20 21:03:29-- 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% 34.4M=0s 2017-02-20 21:03:29 (34.4 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.077263 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-20 21:03:28.803041) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-20 21:03:28.803324) ========= 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=3297cc2eebb5444a7688c33f000001b5-1487647778.468706-1683341729 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017949 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-20 21:03:28.852247) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-20 21:03:28.852542) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev d84beb192e57e26846c82d3df3599381f4663792 --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 d84beb192e57e26846c82d3df3599381f4663792 --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=3297cc2eebb5444a7688c33f000001b5-1487647778.468706-1683341729 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-02-20 21:03:29,689 truncating revision to first 12 chars 2017-02-20 21:03:29,689 Setting DEBUG logging. 2017-02-20 21:03:29,689 attempt 1/10 2017-02-20 21:03:29,689 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/d84beb192e57?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-20 21:03:30,622 unpacking tar archive at: mozilla-central-d84beb192e57/testing/mozharness/ program finished with exit code 0 elapsedTime=1.277314 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-20 21:03:30.141924) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-20 21:03:30.142215) ========= script_repo_revision: d84beb192e57e26846c82d3df3599381f4663792 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-20 21:03:30.142572) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-20 21:03:30.142930) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-20 21:03:30.150036) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2017-02-20 21:03:30.150312) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] 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=3297cc2eebb5444a7688c33f000001b5-1487647778.468706-1683341729 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 21:03:31 INFO - MultiFileLogger online at 20170220 21:03:31 in /builds/slave/test 21:03:31 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 21:03:31 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 21:03:31 INFO - {'append_to_log': False, 21:03:31 INFO - 'base_work_dir': '/builds/slave/test', 21:03:31 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 21:03:31 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 21:03:31 INFO - 'branch': 'Firefox-Non-PGO', 21:03:31 INFO - 'buildbot_json_path': 'buildprops.json', 21:03:31 INFO - 'config_files': ('talos/linux_config.py',), 21:03:31 INFO - 'default_actions': ('clobber', 21:03:31 INFO - 'read-buildbot-config', 21:03:31 INFO - 'download-and-extract', 21:03:31 INFO - 'populate-webroot', 21:03:31 INFO - 'create-virtualenv', 21:03:31 INFO - 'install', 21:03:31 INFO - 'run-tests'), 21:03:31 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:03:31 INFO - 'download_minidump_stackwalk': True, 21:03:31 INFO - 'download_symbols': 'ondemand', 21:03:31 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:03:31 INFO - 'tooltool.py': '/tools/tooltool.py', 21:03:31 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:03:31 INFO - '/tools/misc-python/virtualenv.py')}, 21:03:31 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:03:31 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:03:31 INFO - 'gecko_profile': False, 21:03:31 INFO - 'gecko_profile_interval': 0, 21:03:31 INFO - 'installer_path': 'installer.exe', 21:03:31 INFO - 'log_level': 'info', 21:03:31 INFO - 'log_name': 'talos', 21:03:31 INFO - 'log_to_console': True, 21:03:31 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 21:03:31 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 21:03:31 INFO - 'opt_config_files': (), 21:03:31 INFO - 'pip_index': False, 21:03:31 INFO - 'suite': 'dromaeojs', 21:03:31 INFO - 'system_bits': '32', 21:03:31 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:03:31 INFO - 'title': 'talos-linux64-ix-083', 21:03:31 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:03:31 INFO - 'use_talos_json': True, 21:03:31 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 21:03:31 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:03:31 INFO - 'work_dir': 'build'} 21:03:31 INFO - [mozharness: 2017-02-21 05:03:31.077175Z] Running clobber step. 21:03:31 INFO - Running pre-action listener: _resource_record_pre_action 21:03:31 INFO - Running main action method: clobber 21:03:31 INFO - rmtree: /builds/slave/test/build 21:03:31 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 21:03:32 INFO - Running post-action listener: _resource_record_post_action 21:03:32 INFO - [mozharness: 2017-02-21 05:03:32.009164Z] Finished clobber step (success) 21:03:32 INFO - [mozharness: 2017-02-21 05:03:32.009264Z] Running read-buildbot-config step. 21:03:32 INFO - Running pre-action listener: _resource_record_pre_action 21:03:32 INFO - Running main action method: read_buildbot_config 21:03:32 INFO - Using buildbot properties: 21:03:32 INFO - { 21:03:32 INFO - "project": "", 21:03:32 INFO - "product": "firefox", 21:03:32 INFO - "who": "philringnalda@gmail.com", 21:03:32 INFO - "installer_path": "public/build/target.tar.bz2", 21:03:32 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 21:03:32 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 21:03:32 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 21:03:32 INFO - "stage_platform": "linux64", 21:03:32 INFO - "basedir": "/builds/slave/test", 21:03:32 INFO - "buildnumber": 459, 21:03:32 INFO - "platform": "ubuntu64_hw", 21:03:32 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 21:03:32 INFO - "slavebuilddir": "test", 21:03:32 INFO - "taskId": "fkNumTgrRoG9oSwE7Qq10w", 21:03:32 INFO - "branch": "mozilla-central", 21:03:32 INFO - "script_repo_revision": "production", 21:03:32 INFO - "revision": "d84beb192e57e26846c82d3df3599381f4663792", 21:03:32 INFO - "slavename": "talos-linux64-ix-083", 21:03:32 INFO - "repo_path": "mozilla-central" 21:03:32 INFO - } 21:03:32 INFO - Finding installer, test and symbols from parent task. 21:03:32 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fkNumTgrRoG9oSwE7Qq10w'}, attempt #1 21:03:32 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fkNumTgrRoG9oSwE7Qq10w'}, attempt #1 21:03:32 INFO - Task dependencies: A7KAzjtDTZyOvV4GAQQfdA 21:03:32 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/A7KAzjtDTZyOvV4GAQQfdA'}, attempt #1 21:03:33 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/A7KAzjtDTZyOvV4GAQQfdA/artifacts/public/build/target.tar.bz2 21:03:33 INFO - Set test_url: https://queue.taskcluster.net/v1/task/A7KAzjtDTZyOvV4GAQQfdA/artifacts/public/build/test_packages.json 21:03:33 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/A7KAzjtDTZyOvV4GAQQfdA/artifacts/public/build/target.crashreporter-symbols.zip 21:03:33 INFO - Running post-action listener: _resource_record_post_action 21:03:33 INFO - [mozharness: 2017-02-21 05:03:33.371250Z] Finished read-buildbot-config step (success) 21:03:33 INFO - [mozharness: 2017-02-21 05:03:33.371466Z] Running download-and-extract step. 21:03:33 INFO - Running pre-action listener: _resource_record_pre_action 21:03:33 INFO - Running main action method: download_and_extract 21:03:33 INFO - mkdir: /builds/slave/test/build/tests 21:03:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:03:33 INFO - trying https://queue.taskcluster.net/v1/task/A7KAzjtDTZyOvV4GAQQfdA/artifacts/public/build/target.test_packages.json 21:03:33 INFO - Downloading https://queue.taskcluster.net/v1/task/A7KAzjtDTZyOvV4GAQQfdA/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 21:03:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/A7KAzjtDTZyOvV4GAQQfdA/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 21:03:33 INFO - Downloaded 896 bytes. 21:03:33 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 21:03:33 INFO - Using the following test package requirements: 21:03:33 INFO - {u'common': [u'target.common.tests.zip'], 21:03:33 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 21:03:33 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 21:03:33 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 21:03:33 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 21:03:33 INFO - u'mozbase': [u'target.common.tests.zip'], 21:03:33 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 21:03:33 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 21:03:33 INFO - u'web-platform': [u'target.common.tests.zip', 21:03:33 INFO - u'target.web-platform.tests.zip'], 21:03:33 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 21:03:33 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 21:03:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/A7KAzjtDTZyOvV4GAQQfdA/artifacts/public/build/target.common.tests.zip 21:03:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/A7KAzjtDTZyOvV4GAQQfdA/artifacts/public/build/target.common.tests.zip'}, attempt #1 21:03:33 INFO - Fetch https://queue.taskcluster.net/v1/task/A7KAzjtDTZyOvV4GAQQfdA/artifacts/public/build/target.common.tests.zip into memory 21:03:35 INFO - Expected file size: 57165814 21:03:35 INFO - Obtained file size: 57165814 21:03:44 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 21:03:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/A7KAzjtDTZyOvV4GAQQfdA/artifacts/public/build/target.common.tests.zip 21:03:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/A7KAzjtDTZyOvV4GAQQfdA/artifacts/public/build/target.common.tests.zip'}, attempt #1 21:03:44 INFO - Fetch https://queue.taskcluster.net/v1/task/A7KAzjtDTZyOvV4GAQQfdA/artifacts/public/build/target.common.tests.zip into memory 21:03:46 INFO - Expected file size: 57165814 21:03:46 INFO - Obtained file size: 57165814 21:03:56 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/A7KAzjtDTZyOvV4GAQQfdA/artifacts/public/build/target.talos.tests.zip 21:03:56 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/A7KAzjtDTZyOvV4GAQQfdA/artifacts/public/build/target.talos.tests.zip'}, attempt #1 21:03:56 INFO - Fetch https://queue.taskcluster.net/v1/task/A7KAzjtDTZyOvV4GAQQfdA/artifacts/public/build/target.talos.tests.zip into memory 21:03:57 INFO - Expected file size: 14404319 21:03:57 INFO - Obtained file size: 14404319 21:03:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:03:57 INFO - trying https://queue.taskcluster.net/v1/task/A7KAzjtDTZyOvV4GAQQfdA/artifacts/public/build/target.tar.bz2 21:03:57 INFO - Downloading https://queue.taskcluster.net/v1/task/A7KAzjtDTZyOvV4GAQQfdA/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 21:03:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/A7KAzjtDTZyOvV4GAQQfdA/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 21:03:58 INFO - Downloaded 61428911 bytes. 21:03:58 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/A7KAzjtDTZyOvV4GAQQfdA/artifacts/public/build/target.tar.bz2 21:03:58 INFO - mkdir: /builds/slave/test/properties 21:03:58 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 21:03:58 INFO - Writing to file /builds/slave/test/properties/build_url 21:03:58 INFO - Contents: 21:03:58 INFO - build_url:https://queue.taskcluster.net/v1/task/A7KAzjtDTZyOvV4GAQQfdA/artifacts/public/build/target.tar.bz2 21:03:58 INFO - Running post-action listener: _resource_record_post_action 21:03:58 INFO - Running post-action listener: set_extra_try_arguments 21:03:58 INFO - [mozharness: 2017-02-21 05:03:58.807471Z] Finished download-and-extract step (success) 21:03:58 INFO - [mozharness: 2017-02-21 05:03:58.807588Z] Running populate-webroot step. 21:03:58 INFO - Running pre-action listener: _resource_record_pre_action 21:03:58 INFO - Running main action method: populate_webroot 21:03:58 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 21:03:58 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 21:03:58 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 21:03:58 INFO - '--tppagecycles', 21:03:58 INFO - '7'], 21:03:58 INFO - 'tests': ['tsvgm']}}, 21:03:58 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 21:03:58 INFO - 'tests': ['tresize', 'tcanvasmark']}, 21:03:58 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 21:03:58 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 21:03:58 INFO - 'tests': ['dromaeo_css', 'kraken']}, 21:03:58 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 21:03:58 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 21:03:58 INFO - 'talos_options': ['--disable-e10s'], 21:03:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:03:58 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 21:03:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:03:58 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 21:03:58 INFO - 'talos_options': ['--disable-e10s'], 21:03:58 INFO - 'tests': ['damp', 'tps']}, 21:03:58 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 21:03:58 INFO - 'tests': ['damp', 'tps']}, 21:03:58 INFO - 'g3': {'talos_options': ['--disable-e10s'], 21:03:58 INFO - 'tests': ['dromaeo_dom']}, 21:03:58 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:03:58 INFO - 'g4': {'talos_options': ['--disable-e10s'], 21:03:58 INFO - 'tests': ['basic_compositor_video']}, 21:03:58 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 21:03:58 INFO - 'other': {'talos_options': ['--disable-e10s'], 21:03:58 INFO - 'tests': ['a11yr', 21:03:58 INFO - 'ts_paint', 21:03:58 INFO - 'tpaint', 21:03:58 INFO - 'sessionrestore', 21:03:58 INFO - 'sessionrestore_no_auto_restore', 21:03:58 INFO - 'tabpaint']}, 21:03:58 INFO - 'other-e10s': {'tests': ['a11yr', 21:03:58 INFO - 'ts_paint', 21:03:58 INFO - 'tpaint', 21:03:58 INFO - 'sessionrestore', 21:03:58 INFO - 'sessionrestore_no_auto_restore', 21:03:58 INFO - 'tabpaint']}, 21:03:58 INFO - 'other-e10s_l64': {'tests': ['a11yr', 21:03:58 INFO - 'ts_paint', 21:03:58 INFO - 'tpaint', 21:03:58 INFO - 'sessionrestore', 21:03:58 INFO - 'sessionrestore_no_auto_restore', 21:03:58 INFO - 'tabpaint']}, 21:03:58 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 21:03:58 INFO - 'ts_paint', 21:03:58 INFO - 'tpaint', 21:03:58 INFO - 'sessionrestore', 21:03:58 INFO - 'sessionrestore_no_auto_restore', 21:03:58 INFO - 'tabpaint']}, 21:03:58 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 21:03:58 INFO - 'tests': ['a11yr', 21:03:58 INFO - 'ts_paint', 21:03:58 INFO - 'tpaint', 21:03:58 INFO - 'sessionrestore', 21:03:58 INFO - 'sessionrestore_no_auto_restore', 21:03:58 INFO - 'tabpaint']}, 21:03:58 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 21:03:58 INFO - 'tests': ['a11yr', 21:03:58 INFO - 'ts_paint', 21:03:58 INFO - 'tpaint', 21:03:58 INFO - 'sessionrestore', 21:03:58 INFO - 'sessionrestore_no_auto_restore', 21:03:58 INFO - 'tabpaint']}, 21:03:58 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 21:03:58 INFO - 'tests': ['tsvgx', 21:03:58 INFO - 'tsvgr_opacity', 21:03:58 INFO - 'tart', 21:03:58 INFO - 'tscrollx', 21:03:58 INFO - 'cart', 21:03:58 INFO - 'tsvg_static']}, 21:03:58 INFO - 'svgr-e10s': {'tests': ['tsvgx', 21:03:58 INFO - 'tsvgr_opacity', 21:03:58 INFO - 'tart', 21:03:58 INFO - 'tscrollx', 21:03:58 INFO - 'cart', 21:03:58 INFO - 'tsvg_static']}, 21:03:58 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 21:03:58 INFO - 'talos_options': ['--disable-e10s'], 21:03:58 INFO - 'tests': ['tp5o']}, 21:03:58 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 21:03:58 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 21:03:58 INFO - 'talos_options': ['--disable-e10s', 21:03:58 INFO - '--xperf_path', 21:03:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:03:58 INFO - 'tests': ['tp5n']}, 21:03:58 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 21:03:58 INFO - 'talos_options': ['--xperf_path', 21:03:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:03:58 INFO - 'tests': ['tp5n']}}, 21:03:58 INFO - 'talos.zip': {'path': '', 21:03:58 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:03:58 INFO - Running post-action listener: _resource_record_post_action 21:03:58 INFO - [mozharness: 2017-02-21 05:03:58.823334Z] Finished populate-webroot step (success) 21:03:58 INFO - [mozharness: 2017-02-21 05:03:58.823424Z] Running create-virtualenv step. 21:03:58 INFO - Running pre-action listener: _resource_record_pre_action 21:03:58 INFO - Running main action method: create_virtualenv 21:03:58 INFO - Creating virtualenv /builds/slave/test/build/venv 21:03:58 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 21:03:58 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 21:03:58 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:03:58 INFO - Using real prefix '/usr' 21:03:58 INFO - New python executable in /builds/slave/test/build/venv/bin/python 21:03:59 INFO - Installing distribute.............................................................................................................................................................................................done. 21:04:01 INFO - Installing pip.................done. 21:04:01 INFO - Return code: 0 21:04:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 21:04:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 21:04:01 INFO - Reading from file tmpfile_stdout 21:04:01 INFO - Output received: 21:04:01 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) 21:04:01 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 21:04:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:04:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:04:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:04:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:04:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:04:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:04:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d521f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e4aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fefb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fed290>, '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 0x1fedb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fee040>, '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': '3297cc2eebb5444a7688c33f000001b5-1487647778.468706-1683341729', '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 21:04:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 21:04:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 21:04:01 INFO - Using env: {'DISPLAY': ':0', 21:04:01 INFO - 'HOME': '/home/cltbld', 21:04:01 INFO - 'LANG': 'en_US.UTF-8', 21:04:01 INFO - 'LANGUAGE': 'en_US:en', 21:04:01 INFO - 'LOGNAME': 'cltbld', 21:04:01 INFO - 'MAIL': '/var/mail/cltbld', 21:04:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:04:01 INFO - 'MOZ_NO_REMOTE': '1', 21:04:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:04:01 INFO - 'NO_EM_RESTART': '1', 21:04:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:04:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:04:01 INFO - 'PWD': '/builds/slave/test', 21:04:01 INFO - 'SHELL': '/bin/bash', 21:04:01 INFO - 'SHLVL': '1', 21:04:01 INFO - 'TERM': 'linux', 21:04:01 INFO - 'TMOUT': '86400', 21:04:01 INFO - 'USER': 'cltbld', 21:04:01 INFO - 'XDG_SESSION_COOKIE': '3297cc2eebb5444a7688c33f000001b5-1487647778.468706-1683341729', 21:04:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:04:01 INFO - '_': '/tools/buildbot/bin/python'} 21:04:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:04:01 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 21:04:01 INFO - Cleaning up... 21:04:01 INFO - Return code: 0 21:04:01 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 21:04:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:04:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:04:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:04:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:04:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:04:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:04:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d521f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e4aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fefb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fed290>, '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 0x1fedb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fee040>, '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': '3297cc2eebb5444a7688c33f000001b5-1487647778.468706-1683341729', '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 21:04:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 21:04:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 21:04:01 INFO - Using env: {'DISPLAY': ':0', 21:04:01 INFO - 'HOME': '/home/cltbld', 21:04:01 INFO - 'LANG': 'en_US.UTF-8', 21:04:01 INFO - 'LANGUAGE': 'en_US:en', 21:04:01 INFO - 'LOGNAME': 'cltbld', 21:04:01 INFO - 'MAIL': '/var/mail/cltbld', 21:04:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:04:01 INFO - 'MOZ_NO_REMOTE': '1', 21:04:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:04:01 INFO - 'NO_EM_RESTART': '1', 21:04:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:04:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:04:01 INFO - 'PWD': '/builds/slave/test', 21:04:01 INFO - 'SHELL': '/bin/bash', 21:04:01 INFO - 'SHLVL': '1', 21:04:01 INFO - 'TERM': 'linux', 21:04:01 INFO - 'TMOUT': '86400', 21:04:01 INFO - 'USER': 'cltbld', 21:04:01 INFO - 'XDG_SESSION_COOKIE': '3297cc2eebb5444a7688c33f000001b5-1487647778.468706-1683341729', 21:04:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:04:01 INFO - '_': '/tools/buildbot/bin/python'} 21:04:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:04:02 INFO - Downloading/unpacking psutil>=3.1.1 21:04:02 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 21:04:02 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 21:04:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:04:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:04:02 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 21:04:02 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 21:04:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 21:04:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:04:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:04:04 INFO - Installing collected packages: psutil 21:04:04 INFO - Running setup.py install for psutil 21:04:04 INFO - building 'psutil._psutil_linux' extension 21:04:04 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 21:04:04 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 21:04:04 INFO - building 'psutil._psutil_posix' extension 21:04:04 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 21:04:04 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 21:04:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:04:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:04:04 INFO - Successfully installed psutil 21:04:04 INFO - Cleaning up... 21:04:04 INFO - Return code: 0 21:04:04 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 21:04:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:04:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:04:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:04:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:04:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:04:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:04:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d521f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e4aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fefb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fed290>, '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 0x1fedb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fee040>, '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': '3297cc2eebb5444a7688c33f000001b5-1487647778.468706-1683341729', '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 21:04:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 21:04:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 21:04:04 INFO - Using env: {'DISPLAY': ':0', 21:04:04 INFO - 'HOME': '/home/cltbld', 21:04:04 INFO - 'LANG': 'en_US.UTF-8', 21:04:04 INFO - 'LANGUAGE': 'en_US:en', 21:04:04 INFO - 'LOGNAME': 'cltbld', 21:04:04 INFO - 'MAIL': '/var/mail/cltbld', 21:04:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:04:04 INFO - 'MOZ_NO_REMOTE': '1', 21:04:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:04:04 INFO - 'NO_EM_RESTART': '1', 21:04:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:04:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:04:04 INFO - 'PWD': '/builds/slave/test', 21:04:04 INFO - 'SHELL': '/bin/bash', 21:04:04 INFO - 'SHLVL': '1', 21:04:04 INFO - 'TERM': 'linux', 21:04:04 INFO - 'TMOUT': '86400', 21:04:04 INFO - 'USER': 'cltbld', 21:04:04 INFO - 'XDG_SESSION_COOKIE': '3297cc2eebb5444a7688c33f000001b5-1487647778.468706-1683341729', 21:04:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:04:04 INFO - '_': '/tools/buildbot/bin/python'} 21:04:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:04:04 INFO - Downloading/unpacking mozsystemmonitor==0.3 21:04:04 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 21:04:04 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 21:04:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:04:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:04:04 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 21:04:04 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 21:04:06 INFO - Downloading mozsystemmonitor-0.3.tar.gz 21:04:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:04:06 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 21:04:06 INFO - Installing collected packages: mozsystemmonitor 21:04:06 INFO - Running setup.py install for mozsystemmonitor 21:04:07 INFO - Successfully installed mozsystemmonitor 21:04:07 INFO - Cleaning up... 21:04:07 INFO - Return code: 0 21:04:07 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 21:04:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:04:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:04:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:04:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:04:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:04:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:04:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d521f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e4aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fefb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fed290>, '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 0x1fedb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fee040>, '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': '3297cc2eebb5444a7688c33f000001b5-1487647778.468706-1683341729', '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 21:04:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 21:04:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 21:04:07 INFO - Using env: {'DISPLAY': ':0', 21:04:07 INFO - 'HOME': '/home/cltbld', 21:04:07 INFO - 'LANG': 'en_US.UTF-8', 21:04:07 INFO - 'LANGUAGE': 'en_US:en', 21:04:07 INFO - 'LOGNAME': 'cltbld', 21:04:07 INFO - 'MAIL': '/var/mail/cltbld', 21:04:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:04:07 INFO - 'MOZ_NO_REMOTE': '1', 21:04:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:04:07 INFO - 'NO_EM_RESTART': '1', 21:04:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:04:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:04:07 INFO - 'PWD': '/builds/slave/test', 21:04:07 INFO - 'SHELL': '/bin/bash', 21:04:07 INFO - 'SHLVL': '1', 21:04:07 INFO - 'TERM': 'linux', 21:04:07 INFO - 'TMOUT': '86400', 21:04:07 INFO - 'USER': 'cltbld', 21:04:07 INFO - 'XDG_SESSION_COOKIE': '3297cc2eebb5444a7688c33f000001b5-1487647778.468706-1683341729', 21:04:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:04:07 INFO - '_': '/tools/buildbot/bin/python'} 21:04:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:04:07 INFO - Downloading/unpacking jsonschema==2.5.1 21:04:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:04:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:04:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:04:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:04:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:04:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:04:09 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:04:09 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 21:04:09 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 21:04:09 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 21:04:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:04:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:04:09 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 21:04:09 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 21:04:09 INFO - Downloading functools32-3.2.3-2.tar.gz 21:04:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 21:04:09 INFO - warning: no files found matching '*.txt' 21:04:09 INFO - no previously-included directories found matching 'build' 21:04:09 INFO - no previously-included directories found matching 'dist' 21:04:09 INFO - no previously-included directories found matching '.git*' 21:04:09 INFO - Installing collected packages: jsonschema, functools32 21:04:09 INFO - Running setup.py install for functools32 21:04:09 INFO - warning: no files found matching '*.txt' 21:04:09 INFO - no previously-included directories found matching 'build' 21:04:09 INFO - no previously-included directories found matching 'dist' 21:04:09 INFO - no previously-included directories found matching '.git*' 21:04:09 INFO - Successfully installed jsonschema functools32 21:04:09 INFO - Cleaning up... 21:04:09 INFO - Return code: 0 21:04:09 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 21:04:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:04:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:04:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:04:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:04:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:04:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:04:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d521f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e4aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fefb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fed290>, '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 0x1fedb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fee040>, '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': '3297cc2eebb5444a7688c33f000001b5-1487647778.468706-1683341729', '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 21:04:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 21:04:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 21:04:09 INFO - Using env: {'DISPLAY': ':0', 21:04:09 INFO - 'HOME': '/home/cltbld', 21:04:09 INFO - 'LANG': 'en_US.UTF-8', 21:04:09 INFO - 'LANGUAGE': 'en_US:en', 21:04:09 INFO - 'LOGNAME': 'cltbld', 21:04:09 INFO - 'MAIL': '/var/mail/cltbld', 21:04:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:04:09 INFO - 'MOZ_NO_REMOTE': '1', 21:04:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:04:10 INFO - 'NO_EM_RESTART': '1', 21:04:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:04:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:04:10 INFO - 'PWD': '/builds/slave/test', 21:04:10 INFO - 'SHELL': '/bin/bash', 21:04:10 INFO - 'SHLVL': '1', 21:04:10 INFO - 'TERM': 'linux', 21:04:10 INFO - 'TMOUT': '86400', 21:04:10 INFO - 'USER': 'cltbld', 21:04:10 INFO - 'XDG_SESSION_COOKIE': '3297cc2eebb5444a7688c33f000001b5-1487647778.468706-1683341729', 21:04:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:04:10 INFO - '_': '/tools/buildbot/bin/python'} 21:04:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:04:10 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 21:04:10 INFO - Cleaning up... 21:04:10 INFO - Return code: 0 21:04:10 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 21:04:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:04:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:04:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:04:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:04:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:04:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:04: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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d521f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e4aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fefb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fed290>, '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 0x1fedb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fee040>, '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': '3297cc2eebb5444a7688c33f000001b5-1487647778.468706-1683341729', '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 21:04:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 21:04:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 21:04:10 INFO - Using env: {'DISPLAY': ':0', 21:04:10 INFO - 'HOME': '/home/cltbld', 21:04:10 INFO - 'LANG': 'en_US.UTF-8', 21:04:10 INFO - 'LANGUAGE': 'en_US:en', 21:04:10 INFO - 'LOGNAME': 'cltbld', 21:04:10 INFO - 'MAIL': '/var/mail/cltbld', 21:04:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:04:10 INFO - 'MOZ_NO_REMOTE': '1', 21:04:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:04:10 INFO - 'NO_EM_RESTART': '1', 21:04:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:04:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:04:10 INFO - 'PWD': '/builds/slave/test', 21:04:10 INFO - 'SHELL': '/bin/bash', 21:04:10 INFO - 'SHLVL': '1', 21:04:10 INFO - 'TERM': 'linux', 21:04:10 INFO - 'TMOUT': '86400', 21:04:10 INFO - 'USER': 'cltbld', 21:04:10 INFO - 'XDG_SESSION_COOKIE': '3297cc2eebb5444a7688c33f000001b5-1487647778.468706-1683341729', 21:04:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:04:10 INFO - '_': '/tools/buildbot/bin/python'} 21:04:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:04:10 INFO - Downloading/unpacking blobuploader==1.2.4 21:04:10 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 21:04:10 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 21:04:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:04:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:04:10 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 21:04:10 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 21:04:12 INFO - Downloading blobuploader-1.2.4.tar.gz 21:04:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:04:12 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:04:12 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 21:04:12 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 21:04:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:04:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:04:12 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 21:04:12 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 21:04:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 21:04:12 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:04:12 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 21:04:12 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 21:04:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:04:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:04:12 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 21:04:12 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 21:04:13 INFO - Downloading docopt-0.6.1.tar.gz 21:04:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 21:04:13 INFO - Installing collected packages: blobuploader, requests, docopt 21:04:13 INFO - Running setup.py install for blobuploader 21:04:13 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:04:13 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 21:04:13 INFO - Running setup.py install for requests 21:04:13 INFO - Running setup.py install for docopt 21:04:13 INFO - Successfully installed blobuploader requests docopt 21:04:13 INFO - Cleaning up... 21:04:13 INFO - Return code: 0 21:04:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:04:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:04:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:04:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:04:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:04:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:04:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:04:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d521f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e4aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fefb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fed290>, '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 0x1fedb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fee040>, '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': '3297cc2eebb5444a7688c33f000001b5-1487647778.468706-1683341729', '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 21:04:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 21:04:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 21:04:13 INFO - Using env: {'DISPLAY': ':0', 21:04:13 INFO - 'HOME': '/home/cltbld', 21:04:13 INFO - 'LANG': 'en_US.UTF-8', 21:04:13 INFO - 'LANGUAGE': 'en_US:en', 21:04:13 INFO - 'LOGNAME': 'cltbld', 21:04:13 INFO - 'MAIL': '/var/mail/cltbld', 21:04:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:04:13 INFO - 'MOZ_NO_REMOTE': '1', 21:04:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:04:13 INFO - 'NO_EM_RESTART': '1', 21:04:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:04:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:04:13 INFO - 'PWD': '/builds/slave/test', 21:04:13 INFO - 'SHELL': '/bin/bash', 21:04:13 INFO - 'SHLVL': '1', 21:04:13 INFO - 'TERM': 'linux', 21:04:13 INFO - 'TMOUT': '86400', 21:04:13 INFO - 'USER': 'cltbld', 21:04:13 INFO - 'XDG_SESSION_COOKIE': '3297cc2eebb5444a7688c33f000001b5-1487647778.468706-1683341729', 21:04:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:04:13 INFO - '_': '/tools/buildbot/bin/python'} 21:04:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:04:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:04:14 INFO - Running setup.py (path:/tmp/pip-bOkBnp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:04:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:04:14 INFO - Running setup.py (path:/tmp/pip-MGliLK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:04:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:04:14 INFO - Running setup.py (path:/tmp/pip-U2IyfG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:04:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:04:14 INFO - Running setup.py (path:/tmp/pip-02yAS_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:04:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:04:14 INFO - Running setup.py (path:/tmp/pip-msU7BZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:04:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:04:14 INFO - Running setup.py (path:/tmp/pip-j0zdYf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:04:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:04:14 INFO - Running setup.py (path:/tmp/pip-tA0HdL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:04:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:04:14 INFO - Running setup.py (path:/tmp/pip-_nVjcn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:04:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:04:14 INFO - Running setup.py (path:/tmp/pip-a2a5Dr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:04:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:04:14 INFO - Running setup.py (path:/tmp/pip-tsqTI4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:04:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:04:14 INFO - Running setup.py (path:/tmp/pip-HkJ4qD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:04:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:04:14 INFO - Running setup.py (path:/tmp/pip-Eh8zfP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:04:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:04:14 INFO - Running setup.py (path:/tmp/pip-MIF1pO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:04:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:04:14 INFO - Running setup.py (path:/tmp/pip-SdLBqO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:04:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:04:15 INFO - Running setup.py (path:/tmp/pip-VFcthc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:04:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:04:15 INFO - Running setup.py (path:/tmp/pip-98yRts-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:04:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:04:15 INFO - Running setup.py (path:/tmp/pip-GVlb1V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:04:15 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:04:15 INFO - Running setup.py install for manifestparser 21:04:15 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 21:04:15 INFO - Running setup.py install for mozcrash 21:04:15 INFO - Running setup.py install for mozdebug 21:04:15 INFO - Running setup.py install for mozdevice 21:04:15 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 21:04:15 INFO - Installing dm script to /builds/slave/test/build/venv/bin 21:04:15 INFO - Running setup.py install for mozfile 21:04:15 INFO - Running setup.py install for mozhttpd 21:04:15 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 21:04:15 INFO - Running setup.py install for mozinfo 21:04:15 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 21:04:15 INFO - Running setup.py install for mozInstall 21:04:16 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 21:04:16 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 21:04:16 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 21:04:16 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 21:04:16 INFO - Running setup.py install for mozleak 21:04:16 INFO - Running setup.py install for mozlog 21:04:16 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 21:04:16 INFO - Running setup.py install for moznetwork 21:04:16 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 21:04:16 INFO - Running setup.py install for mozprocess 21:04:16 INFO - Running setup.py install for mozprofile 21:04:16 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 21:04:16 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 21:04:16 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 21:04:16 INFO - Running setup.py install for mozrunner 21:04:16 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 21:04:16 INFO - Running setup.py install for mozscreenshot 21:04:16 INFO - Running setup.py install for moztest 21:04:16 INFO - Running setup.py install for mozversion 21:04:17 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 21:04:17 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:04:17 INFO - Cleaning up... 21:04:17 INFO - Return code: 0 21:04:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:04:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:04:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:04:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:04:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:04:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:04:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:04:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d521f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e4aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fefb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fed290>, '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 0x1fedb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fee040>, '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': '3297cc2eebb5444a7688c33f000001b5-1487647778.468706-1683341729', '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 21:04:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 21:04:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 21:04:17 INFO - Using env: {'DISPLAY': ':0', 21:04:17 INFO - 'HOME': '/home/cltbld', 21:04:17 INFO - 'LANG': 'en_US.UTF-8', 21:04:17 INFO - 'LANGUAGE': 'en_US:en', 21:04:17 INFO - 'LOGNAME': 'cltbld', 21:04:17 INFO - 'MAIL': '/var/mail/cltbld', 21:04:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:04:17 INFO - 'MOZ_NO_REMOTE': '1', 21:04:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:04:17 INFO - 'NO_EM_RESTART': '1', 21:04:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:04:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:04:17 INFO - 'PWD': '/builds/slave/test', 21:04:17 INFO - 'SHELL': '/bin/bash', 21:04:17 INFO - 'SHLVL': '1', 21:04:17 INFO - 'TERM': 'linux', 21:04:17 INFO - 'TMOUT': '86400', 21:04:17 INFO - 'USER': 'cltbld', 21:04:17 INFO - 'XDG_SESSION_COOKIE': '3297cc2eebb5444a7688c33f000001b5-1487647778.468706-1683341729', 21:04:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:04:17 INFO - '_': '/tools/buildbot/bin/python'} 21:04:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:04:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:04:17 INFO - Running setup.py (path:/tmp/pip-J0IHF1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:04:17 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)) 21:04:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:04:17 INFO - Running setup.py (path:/tmp/pip-_B_epi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:04:17 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)) 21:04:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:04:17 INFO - Running setup.py (path:/tmp/pip-29se9U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:04:17 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)) 21:04:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:04:17 INFO - Running setup.py (path:/tmp/pip-bbd7Kt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:04:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 21:04:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:04:17 INFO - Running setup.py (path:/tmp/pip-PWU6Oi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:04:17 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)) 21:04:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:04:17 INFO - Running setup.py (path:/tmp/pip-eLCzxf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:04:17 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)) 21:04:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:04:17 INFO - Running setup.py (path:/tmp/pip-ptDUwg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:04:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 21:04:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:04:17 INFO - Running setup.py (path:/tmp/pip-AN2xur-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:04:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 21:04:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:04:17 INFO - Running setup.py (path:/tmp/pip-5V8rAv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:04:17 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)) 21:04:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:04:17 INFO - Running setup.py (path:/tmp/pip-AAndjN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:04:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:04:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:04:18 INFO - Running setup.py (path:/tmp/pip-xLxuiF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:04:18 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)) 21:04:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:04:18 INFO - Running setup.py (path:/tmp/pip-VNDk6D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:04:18 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)) 21:04:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:04:18 INFO - Running setup.py (path:/tmp/pip-2JoRG6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:04:18 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)) 21:04:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:04:18 INFO - Running setup.py (path:/tmp/pip-ErqrB_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:04:18 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)) 21:04:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:04:18 INFO - Running setup.py (path:/tmp/pip-LcKiNV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:04:18 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)) 21:04:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:04:18 INFO - Running setup.py (path:/tmp/pip-Zxs9cS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:04:18 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)) 21:04:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:04:18 INFO - Running setup.py (path:/tmp/pip-qF7kZr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:04:18 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)) 21:04:18 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)) 21:04:18 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)) 21:04:18 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)) 21:04:18 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 21:04:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 21:04:18 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:04:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:04:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:04:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:04:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:04:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:04:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:04:20 INFO - Downloading blessings-1.6.tar.gz 21:04:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 21:04:20 INFO - Installing collected packages: blessings 21:04:20 INFO - Running setup.py install for blessings 21:04:20 INFO - Successfully installed blessings 21:04:20 INFO - Cleaning up... 21:04:20 INFO - Return code: 0 21:04:20 INFO - Done creating virtualenv /builds/slave/test/build/venv. 21:04:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:04:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:04:21 INFO - Reading from file tmpfile_stdout 21:04:21 INFO - Current package versions: 21:04:21 INFO - argparse == 1.2.1 21:04:21 INFO - blessings == 1.6 21:04:21 INFO - blobuploader == 1.2.4 21:04:21 INFO - docopt == 0.6.1 21:04:21 INFO - functools32 == 3.2.3-2 21:04:21 INFO - jsonschema == 2.5.1 21:04:21 INFO - manifestparser == 1.1 21:04:21 INFO - mozInstall == 1.12 21:04:21 INFO - mozcrash == 1.0 21:04:21 INFO - mozdebug == 0.1 21:04:21 INFO - mozdevice == 0.49 21:04:21 INFO - mozfile == 1.2 21:04:21 INFO - mozhttpd == 0.7 21:04:21 INFO - mozinfo == 0.9 21:04:21 INFO - mozleak == 0.1 21:04:21 INFO - mozlog == 3.4 21:04:21 INFO - moznetwork == 0.27 21:04:21 INFO - mozprocess == 0.25 21:04:21 INFO - mozprofile == 0.28 21:04:21 INFO - mozrunner == 6.13 21:04:21 INFO - mozscreenshot == 0.1 21:04:21 INFO - mozsystemmonitor == 0.3 21:04:21 INFO - moztest == 0.8 21:04:21 INFO - mozversion == 1.4 21:04:21 INFO - psutil == 3.1.1 21:04:21 INFO - requests == 1.2.3 21:04:21 INFO - wsgiref == 0.1.2 21:04:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:04:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:04:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:04:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:04:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:04:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:04:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:04:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d521f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e4aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fefb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fed290>, '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 0x1fedb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fee040>, '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': '3297cc2eebb5444a7688c33f000001b5-1487647778.468706-1683341729', '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 21:04:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 21:04:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 21:04:21 INFO - Using env: {'DISPLAY': ':0', 21:04:21 INFO - 'HOME': '/home/cltbld', 21:04:21 INFO - 'LANG': 'en_US.UTF-8', 21:04:21 INFO - 'LANGUAGE': 'en_US:en', 21:04:21 INFO - 'LOGNAME': 'cltbld', 21:04:21 INFO - 'MAIL': '/var/mail/cltbld', 21:04:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:04:21 INFO - 'MOZ_NO_REMOTE': '1', 21:04:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:04:21 INFO - 'NO_EM_RESTART': '1', 21:04:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:04:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:04:21 INFO - 'PWD': '/builds/slave/test', 21:04:21 INFO - 'SHELL': '/bin/bash', 21:04:21 INFO - 'SHLVL': '1', 21:04:21 INFO - 'TERM': 'linux', 21:04:21 INFO - 'TMOUT': '86400', 21:04:21 INFO - 'USER': 'cltbld', 21:04:21 INFO - 'XDG_SESSION_COOKIE': '3297cc2eebb5444a7688c33f000001b5-1487647778.468706-1683341729', 21:04:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:04:21 INFO - '_': '/tools/buildbot/bin/python'} 21:04:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:04:21 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)) 21:04:21 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)) 21:04:21 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)) 21:04:21 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)) 21:04:21 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)) 21:04:21 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)) 21:04:21 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)) 21:04:21 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)) 21:04:21 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)) 21:04:21 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)) 21:04:21 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)) 21:04:21 INFO - Cleaning up... 21:04:21 INFO - Return code: 0 21:04:21 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 21:04:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:04:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:04:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:04:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:04:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:04:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:04:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d521f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e4aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fefb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fed290>, '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 0x1fedb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fee040>, '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': '3297cc2eebb5444a7688c33f000001b5-1487647778.468706-1683341729', '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 21:04:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 21:04:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 21:04:21 INFO - Using env: {'DISPLAY': ':0', 21:04:21 INFO - 'HOME': '/home/cltbld', 21:04:21 INFO - 'LANG': 'en_US.UTF-8', 21:04:21 INFO - 'LANGUAGE': 'en_US:en', 21:04:21 INFO - 'LOGNAME': 'cltbld', 21:04:21 INFO - 'MAIL': '/var/mail/cltbld', 21:04:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:04:21 INFO - 'MOZ_NO_REMOTE': '1', 21:04:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:04:21 INFO - 'NO_EM_RESTART': '1', 21:04:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:04:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:04:21 INFO - 'PWD': '/builds/slave/test', 21:04:21 INFO - 'SHELL': '/bin/bash', 21:04:21 INFO - 'SHLVL': '1', 21:04:21 INFO - 'TERM': 'linux', 21:04:21 INFO - 'TMOUT': '86400', 21:04:21 INFO - 'USER': 'cltbld', 21:04:21 INFO - 'XDG_SESSION_COOKIE': '3297cc2eebb5444a7688c33f000001b5-1487647778.468706-1683341729', 21:04:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:04:21 INFO - '_': '/tools/buildbot/bin/python'} 21:04:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:04:21 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 21:04:21 INFO - Cleaning up... 21:04:21 INFO - Return code: 0 21:04:21 INFO - Running post-action listener: _resource_record_post_action 21:04:21 INFO - Running post-action listener: _start_resource_monitoring 21:04:21 INFO - Starting resource monitoring. 21:04:21 INFO - [mozharness: 2017-02-21 05:04:21.518591Z] Finished create-virtualenv step (success) 21:04:21 INFO - [mozharness: 2017-02-21 05:04:21.519071Z] Running install step. 21:04:21 INFO - Running pre-action listener: _resource_record_pre_action 21:04:21 INFO - Running main action method: install 21:04:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:04:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:04:21 INFO - Reading from file tmpfile_stdout 21:04:21 INFO - Detecting whether we're running mozinstall >=1.0... 21:04:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 21:04:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 21:04:21 INFO - Reading from file tmpfile_stdout 21:04:21 INFO - Output received: 21:04:21 INFO - Usage: mozinstall [options] installer 21:04:21 INFO - Options: 21:04:21 INFO - -h, --help show this help message and exit 21:04:21 INFO - -d DEST, --destination=DEST 21:04:21 INFO - Directory to install application into. [default: 21:04:21 INFO - "/builds/slave/test"] 21:04:21 INFO - --app=APP Application being installed. [default: firefox] 21:04:21 INFO - mkdir: /builds/slave/test/build/application 21:04:21 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'] 21:04:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 21:04:37 INFO - Reading from file tmpfile_stdout 21:04:37 INFO - Output received: 21:04:37 INFO - /builds/slave/test/build/application/firefox/firefox 21:04:37 INFO - Running post-action listener: _resource_record_post_action 21:04:37 INFO - [mozharness: 2017-02-21 05:04:37.208557Z] Finished install step (success) 21:04:37 INFO - [mozharness: 2017-02-21 05:04:37.208832Z] Running run-tests step. 21:04:37 INFO - Running pre-action listener: _resource_record_pre_action 21:04:37 INFO - Running main action method: run_tests 21:04:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 21:04:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 21:04:37 INFO - Python 2.7.3 21:04:37 INFO - Return code: 0 21:04:37 INFO - grabbing minidump binary from tooltool 21:04:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:04:37 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 0x1fed290>, '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 0x1fedb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fee040>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 21:04:37 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 21:04:37 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 21:04:37 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 21:04:37 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:04:37 INFO - Return code: 0 21:04:37 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 21:04:37 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 21:04:37 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 21:04:37 INFO - ENV: RUST_BACKTRACE is now 1 21:04:37 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 21:04:37 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 21:04:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/A7KAzjtDTZyOvV4GAQQfdA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-083', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 21:04:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/A7KAzjtDTZyOvV4GAQQfdA/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-083 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 21:04:37 INFO - Using env: {'DISPLAY': ':0', 21:04:37 INFO - 'HOME': '/home/cltbld', 21:04:37 INFO - 'LANG': 'en_US.UTF-8', 21:04:37 INFO - 'LANGUAGE': 'en_US:en', 21:04:37 INFO - 'LOGNAME': 'cltbld', 21:04:37 INFO - 'MAIL': '/var/mail/cltbld', 21:04:37 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:04:37 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 21:04:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:04:37 INFO - 'MOZ_NO_REMOTE': '1', 21:04:37 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:04:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:04:37 INFO - 'NO_EM_RESTART': '1', 21:04:37 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:04:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:04:37 INFO - 'PWD': '/builds/slave/test', 21:04:37 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:04:37 INFO - 'RUST_BACKTRACE': '1', 21:04:37 INFO - 'SHELL': '/bin/bash', 21:04:37 INFO - 'SHLVL': '1', 21:04:37 INFO - 'TERM': 'linux', 21:04:37 INFO - 'TMOUT': '86400', 21:04:37 INFO - 'USER': 'cltbld', 21:04:37 INFO - 'XDG_SESSION_COOKIE': '3297cc2eebb5444a7688c33f000001b5-1487647778.468706-1683341729', 21:04:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:04:37 INFO - '_': '/tools/buildbot/bin/python'} 21:04:37 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/A7KAzjtDTZyOvV4GAQQfdA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-083', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 21:04:37 INFO - mozversion application_buildid: 20170221042909 21:04:37 INFO - mozversion application_changeset: d84beb192e57e26846c82d3df3599381f4663792 21:04:37 INFO - mozversion application_display_name: Nightly 21:04:37 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:04:37 INFO - mozversion application_name: Firefox 21:04:37 INFO - mozversion application_remotingname: firefox 21:04:37 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 21:04:37 INFO - mozversion application_vendor: Mozilla 21:04:37 INFO - mozversion application_version: 54.0a1 21:04:37 INFO - mozversion platform_buildid: 20170221042909 21:04:37 INFO - mozversion platform_changeset: d84beb192e57e26846c82d3df3599381f4663792 21:04:37 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 21:04:37 INFO - mozversion platform_version: 54.0a1 21:04:37 INFO - using testdate: 1487653477 21:04:37 INFO - actual date: 1487653477 21:04:37 INFO - starting webserver on 'localhost:44912' 21:04:37 INFO - SUITE-START | Running 2 tests 21:04:37 INFO - TEST-START | dromaeo_css 21:04:37 INFO - operating with platform_type : linux_ 21:04:37 INFO - Initialising browser for dromaeo_css test... 21:04:37 INFO - TEST-INFO | started process 28814 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpC2mTqW/profile http://localhost:44912/getInfo.html) 21:04:40 INFO - PROCESS | 28814 | Iterating over profile /tmp/tmpC2mTqW/profile 21:04:41 INFO - PROCESS | 28814 | __metrics Screen width/height:1600/1200 21:04:41 INFO - PROCESS | 28814 | colorDepth:24 21:04:41 INFO - PROCESS | 28814 | Browser inner width/height: 1024/697 21:04:41 INFO - PROCESS | 28814 | __metrics 21:04:41 INFO - PROCESS | 28814 | No birth date. Using mtime. 21:04:41 INFO - PROCESS | 28814 | Using date: /tmp/tmpC2mTqW/profile/extensions.json = Mon Feb 20 2017 21:04:39 GMT-0800 (PST) 21:04:41 INFO - PROCESS | 28814 | No birth date. Using mtime. 21:04:41 INFO - PROCESS | 28814 | Using date: /tmp/tmpC2mTqW/profile/extensions = Mon Feb 20 2017 21:04:37 GMT-0800 (PST) 21:04:41 INFO - PROCESS | 28814 | No birth date. Using mtime. 21:04:41 INFO - PROCESS | 28814 | Using date: /tmp/tmpC2mTqW/profile/secmod.db = Mon Feb 20 2017 21:04:37 GMT-0800 (PST) 21:04:41 INFO - PROCESS | 28814 | No birth date. Using mtime. 21:04:41 INFO - PROCESS | 28814 | Using date: /tmp/tmpC2mTqW/profile/blocklist.xml = Mon Feb 20 2017 21:04:37 GMT-0800 (PST) 21:04:41 INFO - PROCESS | 28814 | No birth date. Using mtime. 21:04:41 INFO - PROCESS | 28814 | Using date: /tmp/tmpC2mTqW/profile/frequencyCap.json = Mon Feb 20 2017 21:04:40 GMT-0800 (PST) 21:04:41 INFO - PROCESS | 28814 | No birth date. Using mtime. 21:04:41 INFO - PROCESS | 28814 | Using date: /tmp/tmpC2mTqW/profile/places.sqlite-shm = Mon Feb 20 2017 21:04:39 GMT-0800 (PST) 21:04:41 INFO - PROCESS | 28814 | No birth date. Using mtime. 21:04:41 INFO - PROCESS | 28814 | Using date: /tmp/tmpC2mTqW/profile/gmp = Mon Feb 20 2017 21:04:38 GMT-0800 (PST) 21:04:41 INFO - PROCESS | 28814 | No birth date. Using mtime. 21:04:41 INFO - PROCESS | 28814 | Using date: /tmp/tmpC2mTqW/profile/sessionCheckpoints.json = Mon Feb 20 2017 21:04:41 GMT-0800 (PST) 21:04:41 INFO - PROCESS | 28814 | No birth date. Using mtime. 21:04:41 INFO - PROCESS | 28814 | Using date: /tmp/tmpC2mTqW/profile/crashes = Mon Feb 20 2017 21:04:37 GMT-0800 (PST) 21:04:41 INFO - PROCESS | 28814 | No birth date. Using mtime. 21:04:41 INFO - PROCESS | 28814 | Using date: /tmp/tmpC2mTqW/profile/places.sqlite-wal = Mon Feb 20 2017 21:04:41 GMT-0800 (PST) 21:04:41 INFO - PROCESS | 28814 | No birth date. Using mtime. 21:04:41 INFO - PROCESS | 28814 | Using date: /tmp/tmpC2mTqW/profile/user.js = Mon Feb 20 2017 21:04:37 GMT-0800 (PST) 21:04:41 INFO - PROCESS | 28814 | No birth date. Using mtime. 21:04:41 INFO - PROCESS | 28814 | Using date: /tmp/tmpC2mTqW/profile/minidumps = Mon Feb 20 2017 21:04:37 GMT-0800 (PST) 21:04:41 INFO - PROCESS | 28814 | No birth date. Using mtime. 21:04:41 INFO - PROCESS | 28814 | Using date: /tmp/tmpC2mTqW/profile/safebrowsing = Mon Feb 20 2017 21:04:41 GMT-0800 (PST) 21:04:41 INFO - PROCESS | 28814 | No birth date. Using mtime. 21:04:41 INFO - PROCESS | 28814 | Using date: /tmp/tmpC2mTqW/profile/startupCache = Mon Feb 20 2017 21:04:37 GMT-0800 (PST) 21:04:41 INFO - PROCESS | 28814 | No birth date. Using mtime. 21:04:41 INFO - PROCESS | 28814 | Using date: /tmp/tmpC2mTqW/profile/prefs.js = Mon Feb 20 2017 21:03:57 GMT-0800 (PST) 21:04:41 INFO - PROCESS | 28814 | No birth date. Using mtime. 21:04:41 INFO - PROCESS | 28814 | Using date: /tmp/tmpC2mTqW/profile/cache2 = Mon Feb 20 2017 21:04:37 GMT-0800 (PST) 21:04:41 INFO - PROCESS | 28814 | No birth date. Using mtime. 21:04:41 INFO - PROCESS | 28814 | Using date: /tmp/tmpC2mTqW/profile/bookmarkbackups = Mon Feb 20 2017 21:04:39 GMT-0800 (PST) 21:04:41 INFO - PROCESS | 28814 | No birth date. Using mtime. 21:04:41 INFO - PROCESS | 28814 | Using date: /tmp/tmpC2mTqW/profile/compatibility.ini = Mon Feb 20 2017 21:04:38 GMT-0800 (PST) 21:04:41 INFO - PROCESS | 28814 | No birth date. Using mtime. 21:04:41 INFO - PROCESS | 28814 | Using date: /tmp/tmpC2mTqW/profile/pluginreg.dat = Mon Feb 20 2017 21:04:39 GMT-0800 (PST) 21:04:41 INFO - PROCESS | 28814 | No birth date. Using mtime. 21:04:41 INFO - PROCESS | 28814 | Using date: /tmp/tmpC2mTqW/profile/mimeTypes.rdf = Mon Feb 20 2017 21:04:41 GMT-0800 (PST) 21:04:41 INFO - PROCESS | 28814 | Stat failure 21:04:41 INFO - PROCESS | 28814 | No birth date. Using mtime. 21:04:41 INFO - PROCESS | 28814 | Using date: /tmp/tmpC2mTqW/profile/directoryLinks.json = Mon Feb 20 2017 21:04:40 GMT-0800 (PST) 21:04:41 INFO - PROCESS | 28814 | No birth date. Using mtime. 21:04:41 INFO - PROCESS | 28814 | Using date: /tmp/tmpC2mTqW/profile/localstore.rdf = Mon Feb 20 2017 21:03:57 GMT-0800 (PST) 21:04:41 INFO - PROCESS | 28814 | No birth date. Using mtime. 21:04:41 INFO - PROCESS | 28814 | Using date: /tmp/tmpC2mTqW/profile/content-prefs.sqlite = Mon Feb 20 2017 21:04:39 GMT-0800 (PST) 21:04:41 INFO - PROCESS | 28814 | No birth date. Using mtime. 21:04:41 INFO - PROCESS | 28814 | Using date: /tmp/tmpC2mTqW/profile/thumbnails = Mon Feb 20 2017 21:04:39 GMT-0800 (PST) 21:04:41 INFO - PROCESS | 28814 | No birth date. Using mtime. 21:04:41 INFO - PROCESS | 28814 | Using date: /tmp/tmpC2mTqW/profile/extensions.ini = Mon Feb 20 2017 21:04:38 GMT-0800 (PST) 21:04:41 INFO - PROCESS | 28814 | No birth date. Using mtime. 21:04:41 INFO - PROCESS | 28814 | Using date: /tmp/tmpC2mTqW/profile/permissions.sqlite = Mon Feb 20 2017 21:04:38 GMT-0800 (PST) 21:04:41 INFO - PROCESS | 28814 | No birth date. Using mtime. 21:04:41 INFO - PROCESS | 28814 | Using date: /tmp/tmpC2mTqW/profile/addons.json = Mon Feb 20 2017 21:04:40 GMT-0800 (PST) 21:04:41 INFO - PROCESS | 28814 | No birth date. Using mtime. 21:04:41 INFO - PROCESS | 28814 | Using date: /tmp/tmpC2mTqW/profile/places.sqlite = Mon Feb 20 2017 21:04:41 GMT-0800 (PST) 21:04:41 INFO - PROCESS | 28814 | No birth date. Using mtime. 21:04:41 INFO - PROCESS | 28814 | Using date: /tmp/tmpC2mTqW/profile/cert8.db = Mon Feb 20 2017 21:04:37 GMT-0800 (PST) 21:04:41 INFO - PROCESS | 28814 | No birth date. Using mtime. 21:04:41 INFO - PROCESS | 28814 | Using date: /tmp/tmpC2mTqW/profile/.parentlock = Mon Feb 20 2017 21:04:37 GMT-0800 (PST) 21:04:41 INFO - PROCESS | 28814 | No birth date. Using mtime. 21:04:41 INFO - PROCESS | 28814 | Using date: /tmp/tmpC2mTqW/profile/key3.db = Mon Feb 20 2017 21:04:37 GMT-0800 (PST) 21:04:41 INFO - PROCESS | 28814 | No birth date. Using mtime. 21:04:41 INFO - PROCESS | 28814 | Using date: /tmp/tmpC2mTqW/profile/cookies.sqlite = Mon Feb 20 2017 21:04:41 GMT-0800 (PST) 21:04:42 INFO - PROCESS | 28814 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 21:04:42 INFO - PROCESS | 28814 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 21:04:43 INFO - TEST-INFO | 28814: exit 0 21:04:43 INFO - Browser initialized. 21:04:43 INFO - Running cycle 1/1 for dromaeo_css test... 21:04:43 INFO - TEST-INFO | started process 28906 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpC2mTqW/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:04:55 INFO - PROCESS | 28906 | RSS: Main: 147636224 21:04:55 INFO - PROCESS | 28906 | 21:08:00 INFO - PROCESS | 28906 | Cycle 1(1): loaded http://localhost:44912/tests/dromaeo/cssquery-dojo.html (next: http://localhost:44912/tests/dromaeo/cssquery-ext.html) 21:08:03 INFO - PROCESS | 28906 | RSS: Main: 351563776 21:08:03 INFO - PROCESS | 28906 | 21:09:45 INFO - PROCESS | 28906 | ************************* 21:09:45 INFO - PROCESS | 28906 | A coding exception was thrown and uncaught in a Task. 21:09:45 INFO - PROCESS | 28906 | 21:09:45 ERROR - PROCESS | 28906 | Full message: TypeError: NetworkError when attempting to fetch resource. 21:09:45 INFO - PROCESS | 28906 | Full stack: 21:09:45 INFO - PROCESS | 28906 | ************************* 21:11:06 INFO - PROCESS | 28906 | Cycle 1(1): loaded http://localhost:44912/tests/dromaeo/cssquery-ext.html (next: http://localhost:44912/tests/dromaeo/cssquery-jquery.html) 21:11:07 INFO - PROCESS | 28906 | RSS: Main: 245309440 21:11:07 INFO - PROCESS | 28906 | 21:14:09 INFO - PROCESS | 28906 | Cycle 1(1): loaded http://localhost:44912/tests/dromaeo/cssquery-jquery.html (next: http://localhost:44912/tests/dromaeo/cssquery-mootools.html) 21:14:09 INFO - PROCESS | 28906 | RSS: Main: 183652352 21:14:09 INFO - PROCESS | 28906 | 21:17:12 INFO - PROCESS | 28906 | Cycle 1(1): loaded http://localhost:44912/tests/dromaeo/cssquery-mootools.html (next: http://localhost:44912/tests/dromaeo/cssquery-prototype.html) 21:17:12 INFO - PROCESS | 28906 | RSS: Main: 216317952 21:17:12 INFO - PROCESS | 28906 | 21:20:15 INFO - PROCESS | 28906 | Cycle 1(1): loaded http://localhost:44912/tests/dromaeo/cssquery-prototype.html (next: http://localhost:44912/tests/dromaeo/cssquery-yui.html) 21:20:16 INFO - PROCESS | 28906 | RSS: Main: 189095936 21:20:16 INFO - PROCESS | 28906 | 21:23:19 INFO - PROCESS | 28906 | Cycle 1(1): loaded http://localhost:44912/tests/dromaeo/cssquery-yui.html (next: http://localhost:44912/tests/dromaeo/cssquery-dojo.html) 21:23:19 INFO - PROCESS | 28906 | RSS: Main: 280854528 21:23:19 INFO - PROCESS | 28906 | 21:23:19 INFO - PROCESS | 28906 | __start_tp_report 21:23:19 INFO - PROCESS | 28906 | _x_x_mozilla_page_load 21:23:19 INFO - PROCESS | 28906 | _x_x_mozilla_page_load_details 21:23:19 INFO - PROCESS | 28906 | |i|pagename|runs| 21:23:19 INFO - PROCESS | 28906 | |0;dojo.html;2583.9095631652895;2613.098481024046;2619.593962935745;2643.510950474162;2652.8275662081974;4592.793324300406;4629.745364004978;4728.716277023376;4771.355866957964;4775.49857264987;4677.181493238678;4691.483936766953;4720.976395118028;4724.669273150872;4748.192807222772;4363.759993200372;4374.846880359188;4429.33559966006;4490.7081039732475;4514.052049069699;4332.740035597874;4408.889777755566;4415.293553031515;4472.53038430965;4496.078303947683;4209.873703788891;4234.449521562208;4234.830606775756;4433.113377324516;4439.134368798085;4286.464191976003;4289.335153051282;4315.438992930899;4356.085222103362;4358.869233922987;4321.330193819963;4331.090471001094;4337.696361254713;4394.384786129945;4397.296432570805;4682.1338052460405;4718;4736.100140973239;4748.026654535846;4779.904401911977;4900.950990490085;4933.901321973576;4938.555530002282;5070.543651071422;5080.13637681595;5013.498650134994;5024.824131155392;5026.346574945271;5027.019731152427;5027.271045698353;4710.952890471086;4723.102610503994;4728.550787675165;4729.62163026957;4735.715857048588;5086.364204474441;5125.923111153335;5127.46161653021;5135.075686376488;5136.614753893473;4923;4932.580730637864;4937.259411088366;4975.228839529878;5046.545810877039;5081.669691470042;5083.034223497523;5083.237514372874;5086.847394578169;5133.101707201225;5043.117454445457;5048.7728052237;5051.646384753032;5051.848444546669;5059.342285502861;4870.683405578626;4902.436219834764;4913.091078150482;4975.104481193348;4984.950150498521;4335.241332766753;4346.1090476452255;4362.040351122748;4362.956370436255;4379.277419225792;3775.339315619756;3778.1499162688174;3779.093017675738;3811.8475260989803;3835.079580900564;4491.8427855025575;4493.146302202571;4502.527234640315;4527.094581083797;4536.841210557614;4919.188333924933;4947.307376967227;4954.256861470736;5016.899662006813;5149.897002059938;4396.252637051709;4447.976965297999;4449.243628583149;4489;4537.659675524349;4683.250679891201;4689.390379250675;4694;4714.245720684674;4727.054589082198;4860.659753817199;4864.221724524059;4866;4872.074305881871;4900.191468407744;4846.442659094108;4927.187014142626;4942.258661200849;5169.508896654812;5213.087709650872;4689.99165179488;4714.622830173509;4714.669973101987;4718.221493453541;4725.10223057618;4914.606831453547;4916.557509824133;4916.926246106383;4917.729524876167;4924.064427758714;4987.426445958616;4987.551120399182;4997.525235102659;4999.150144475375;5000.2499625056535;4814.518548145157;4861.611071114232;4869.756512174306;4870.634702397264;4872.926906096341;4875.87810304731;4901.3138160658245;4902.166631672696;4904.386951631046;4904.435989861069;4811.663183577256;4827.155247831685;4828.7102773833685;4832.830850920201;4843.297721830385;4989.401271847401;4990.8253211137435;4994.525520075587;4999.675021123615;5046.066477701637;4691.038337140946;4729.983053643483;4830.492798256273;4835.854924352275;4879.853604391874 21:23:19 INFO - PROCESS | 28906 | |1;ext.html;558.3913534247672;566.2921348314607;577.1746402644217;584.5557376393939;602.5179856115107;25914.093006744766;39009.80495097524;40865.182696346055;40975.59024409755;41063.17873642525;36761.08097297569;37337.626623733755;37350.252994940085;37400.25199496008;37441.438378424275;6725.630090345029;7413.443991700617;7589.924100759004;7655.08139023318;7657.66441235423;6706.932930670704;9431.717048488556;9609.327347085708;9615.086566776145;9829.262805289596;9589.66436174734;9657.275704322168;9659.855102173438;9665.130138287554;9673.516324183798;9599.856002159973;9638.614455421775;9645.614175432975;9651.903480965171;9656.903430965705;9415.811683766355;9474.573644186028;9481.620735170585;9485.90514094864;9487.383320084173;33819;34015.14962125942;34076;34095.488567671506;34124.14689632754;3304.7851889627095;3406.9488957665653;3421.640727723578;3456.4642480415578;3466.60134084581;26206.606900896502;26388.340291492674;26427.075052373075;26561.468770624673;27012.864935675392;3314.7182489488423;3499.91250218744;3500.492428597839;3502.474628805674;3641;220714;221553.89223053944;221735;221776;222039;46013;46821.5317846824;48799.512004880205;48838.26742598864;49357.25964110541;51509.48490515122;52962.47037529652;52997.47002530003;57312;57364.139537906965;11752.294862308288;11867.525298988117;11881.762364752656;11890.405479726152;11895.643130706092;8789.516576588221;8980.191782739454;8982.416142950819;8982.910170898338;8984.146506081912;14226.146431213952;14344.282785860874;14368.784468232985;14393.136411815116;14527.87650655093;5991.880162396727;6015.879682406327;6113.41922517369;6164.784232551929;6169.290531588925;776.9805754856174;785.5679376342988;787.5786454246951;793.6904607203195;800.4796882026612;24160.395990100038;24838.627420588706;25185;25215.243542693748;25256.242312730647;895.6551727584927;938.1369140390857;938.7653086728318;953.3469573342283;962.225408546113;13129.146605470614;13428.597142085753;13510.594682159552;13771.311434428238;13779.311034448237;253728;254273;256137;256163;256360;42028.78985605053;42032;42114;42153.156936861094;42175.57824421778;1094.9178811589163;1159.2641366375515;1163.2555164694602;1173.178774857603;1195.4082729049176;22730.43173920665;22892.198773042863;24113.51772964566;24116;24197;36005;36506;36525.63474365223;36550;36554.903352899455;213749;213879;214203;214658;215028;29518.557221641262;30502.542461863533;30608.693913060586;30633.693663063084;30673.386532269677;34001.82999084989;34052.65947340495;34754;34859.128521786144;34874;29033.85483072571;29940.251493712833;30028.699713002592;30342;31292.374152516368;29256.853715731286;29558.852205738833;30292.84853575718;30317.090487285415;30410.087697369116;29199.006442133046;30127.397452051275;30256.243593910327;30301.696983029888;30332.393352133276;23099.99700038984;23508.119350452263;26219.73780262173;26757.464850703265;27121.372717637005;21174.258900938392;21802.781972180073;21949.561008780056;22068.558628827013;22109 21:23:19 INFO - PROCESS | 28906 | |2;jquery.html;360.5547149270652;384.23728898137176;389.1419420178507;396.4825902197632;417.7410005796407;11834.526618935242;12082.275063496185;12727.61817145486;16715.91642041788;17559.91220043898;15416.460423885166;15574.221288935565;15651.45219917303;15708.21458927052;15760.763588546153;4281.7216880902715;4454.042380888109;4478.440194975628;4560.954390456086;4563.977180114095;3280.0979730574163;4482.394876691654;4517.41273634427;4518.864434066983;4524.140413321475;3391.7456190785665;3419.042668052949;3826.9284600311817;5906.173135760997;6000.639961602296;5972.343042265347;5977.2229610150835;5978.282606087254;5978.9701051494685;5984.910226346565;5253.395859476171;5378.435264297271;5401.351837779453;5413.242146099549;5415.593830462731;11946.283223006647;12002.51989920402;12034.27794332334;12104.878951210463;12118.091143164298;1989.8806071635747;2060.0832629479887;2105.6841473778904;2112.57748450309;2155.7844215578475;8223.876641850376;8582.382373376118;8872.334574906909;8926.642934282621;9060.184583387461;1652.2977734462804;1697.6010637500178;1703.6337187504746;1712.922918468672;1733.298014304209;71205.64397178033;73495;73737.63131184364;74559.62720186364;76850;10465.47672616366;10640.308379955279;10824.512896919658;10828.566857325619;10865.728356791144;15143.167125808186;15151.924240378728;15166.848331516765;15173.317200725995;15181.468648597245;6587.604743715392;6740;6911.723531058802;6964.93035069653;6973.651317434108;6007.399260074045;6071;6167.599106058092;6194.783182588588;6248.281447633581;4008.2785098682516;4094.6928980326593;4421.7346959181905;4518.751468669189;4783.04339132168;1914.7510823592843;1937.350987419202;1963.5385684364162;1967.9016049197483;2008.9095990680162;765.2500549461558;792.6036981509245;797.2864286463582;814.9429539932266;824.9092599814015;16889.577760556083;17312;17422;17470.563235919202;17753.11234438273;764.2930289482207;794.3287921706149;805.8630032894422;809.3161278719473;811.9797005074802;9110.453372797654;9667.613295468243;9765.853512197322;9773.21814254858;9861.40831550095;459292;467095;469756;476289;477086;56505.43494565002;84071.5796421014;84317.57841210754;86237.5688121568;87184;908.4140729229609;925.4817302310728;931.6552875435997;932.5896605493425;932.7947851472666;14552.17192136836;14786.408543658134;15080.773788393402;15262.313195905988;15277.465288714842;35990.8200458996;36290.552397522195;36334;36679.633203667625;37478.25043499169;49179.508204917496;49762;49814.25278620896;50093.99812003812;50096.74951625219;6812.182538095461;7042.577445353295;7120;7160.498765086394;7541.2458754124145;8734.344924130843;11751.529938802694;12088.15382923187;12145.939270303592;12508.061895358067;9805.55874985613;9953.502324883582;9975.950120249352;10212.744681382728;10561.894381056398;9636.951815240878;9794.902050979399;9883.901160988586;9984.251181161297;10080.596776128872;10222.693319200436;10277.897221027693;10315.587376505156;10406;10509.369437833755;6902.551334163263;6915.343042410963;6954.130733658293;7012.859742805217;7088.503804733619;8063.83872322562;9993.700188994051;10071.143952764098;10372.688819335432;10408.323458975143 21:23:19 INFO - PROCESS | 28906 | |3;mootools.html;1334.8531661517231;1341.7651910915588;1351.5404762380788;1352.770029095054;1362.8092067110613;7424.814379640505;7848.018997625297;7877.842443151134;7881.054273487182;7895.763127106189;7387.667554960026;7695.422843286762;7749.031371078615;7903.8419231615335;7920.881186782203;2015.556577552936;2050.917963281467;2060.268604645344;2063.814256716895;2064.762552306489;1800.4598620413824;2127.6595744680853;2173.0655817998254;2174.4672555223915;2179.967300490494;1829.8902065876023;2021.6967454881737;2063.8039386258283;2066.3284432559403;2102.1906565972135;1976.318170231268;1978.940631781049;1981.1679094780227;1989.1446677928484;1991.3030439346257;1767.5139336682387;1999.2502811445709;2083.958320833575;2084.8436367272366;2085.35354040248;6732;7372.336489715952;7641.694332226704;7660.080790305144;7856.25365590268;4560.156371071363;4701.95298047022;4706.129366067288;4707.788149533245;4709.458412282597;2667.6532050833266;2773.55623100303;2828.476731804623;2831.0940499040316;2842.6304580404417;1090.514720949178;1090.7545802194443;1130.4226363890343;1150.3270586706703;1151.2229245259416;10357.171426285955;10423.5830566778;10447.634332798392;10463.215258855616;10492.527836247311;3816.3321418751607;3955.248502784519;4023.959760402417;4025.5370632377067;4046.433499310099;8341.83316333682;8350.874736878952;8358.247757701836;8363.080061193265;8372.120927302667;4564.42944631921;4580.97709511457;4581.037982023746;4581.037982023812;4583.243764778841;4091.97954010234;4122.299209134454;4126.3397856343445;4132.710710250253;4134.545200027995;3303.7191838693498;3350.9664903350654;3419.777714448553;3456.9654303456646;3480.7215422766626;1057.5029736024057;1069.9572017119228;1085.7122862441458;1087.206339372247;1090.8200146975819;780.9804754881173;790.5375355417145;795.129333379948;798.6964953317702;802.4864086984335;4587.541245875453;4826.348442960226;4833.782479788418;4878.170710979142;5116.590672746172;423.9364095385717;433.91755566442276;457.259240031145;464.9767511624405;472.83687127940806;2950.3804201118005;3007.6691563927952;3192.74458043356;3222.8388580570877;3335.4329763940186;8131.512109273344;8305.418620696493;9312.394694344846;9327.906720932839;9535.189508891823;9624.51877406141;9639.084286992724;9645.08371704687;9645.662401815904;9647.276454265817;7020.9648951755935;7398.297161769731;7424.220456852061;7425.85148297031;7462.664180111908;4837.516248375135;4906.509349065065;4992.375953005874;5010.899782004267;5042.873928151826;8297.25324720778;8539.487630742175;8634.352423568133;8645.697400591202;8766.912330876865;8656.177663121993;8823.117688231125;8883.73348799537;8885.600147993486;8898.644054237759;6518.902216466662;6652.73389064432;6659.900101498578;6665.666716664245;6667.633280169637;7279.417646588367;7288.744893928899;7318.231585683639;7382.5570465772225;7408.259174082548;6517.576357536745;6546.8363290918105;6558.967205163944;6606.966965165336;6758.966205168943;6583.604983700993;6617.834554136185;6651.43462805667;6652.7006284716335;6657.73369065232;5491.36849262324;5596.1605759136455;5610.6072574921;5623.578231632726;5627.803026894039;4259.42497762798;4277.615014648696;4297;4351.456067991501;4419.933700994423;4193.329067349209;4197.433346498306;4258.61672449481;4329.653627709713;4413.095315460387 21:23:19 INFO - PROCESS | 28906 | |4;prototype.html;344.3973047167457;365.5083912138175;371.25933762144524;372.43761919501554;372.75957007730034;4917.434495033072;4917.975410122949;4957.851264462063;4970.801167953282;4985.875353116174;4649.232876575369;4799.112164249607;4819.590334821544;4839.491853355391;4919.778609962551;3825.2158307546956;3826.1773718650484;3839.6544311012144;3855.5373355197416;3873.5158105236846;3846.9807650961707;3852.3451013327804;3853.1715681128553;3857.32496813058;3857.864974725885;3622.5834029086727;3820.5033345665024;3823.885283441501;3824.521934758155;3843.3466310727104;3791.241751649681;3797.9555622204116;3802.144517483558;3828.55971563271;3834.060655139481;3345.8494367753265;3355.328934213167;3355.748318876094;3363.7813542119684;3367.107716455141;4724.362211101492;4725.4565724941385;4764.2615394613895;4768.856934291976;4774.283857421415;4172;4269.295566231568;4315.654747620183;4349.565043495657;4357.281048626973;5029.2958985742025;5054.418741844662;5067.807281412724;5084.567811736007;5085.330201644167;3626.546681664792;3634.0551456621206;3657.762245454037;3661.9816900915325;3671.494687178271;4667.506397952657;5017.84946451607;5193.506616871391;5315.441878602768;5392.865178370533;3694.131879008431;3697.371446854041;3697.7781333120097;3700.1119731264303;3713.962860371416;3739.6634302912325;3752.962470375261;3760.8495660173835;3761.4733937248807;3762.8682996095004;4280.336547835091;4287.721298115613;4290.442242508517;4297.419848320436;4298.720583162084;4942.800453798025;5067.721275329893;5116.283720279164;5165.940982098661;5176.948230517722;1258;1259.8488181418288;1270.3775150176532;1270.4092596942282;1273.2933222061845;815.4740192575755;860.5998210831868;861.638111992952;873.8514452543083;875.6191056890273;4377.737335759801;4390.341448782644;4441.444819397575;4472.664550158751;4477.910441791146;5158.896822063538;5166.819161329336;5171.715555644476;5173.40505841833;5188.521271437622;4231.942014496376;4416.690831641754;4422.911541769211;4475.6643251755595;4479.843205487858;2659.6143559184197;2690.367763575558;2708.5260079486407;2715.592661100851;2720.5511090669816;4754.809807607726;4772.5943294819635;4792.185328494164;4803.327534145223;4806.182948898626;4797.808087676523;5070.61970352225;5185.403678576937;5224.738763061831;5231.921521177185;4901.2893130496595;4929.278394811894;4932.470934010468;4947.431045429749;4951.282064100613;5246.350592950535;5249.238860365149;5251.842444726664;5255.81744107588;5259.973700131628;4954.761309672582;5176.4305926347315;5200.425789234353;5236.528712415901;5261.3686357637325;5003.324551185543;5013.674111182761;5014.548690617862;5019.974900125476;5025.3718285214345;3737.3459644562636;3739;3749.7562658427114;3753.343164946069;3757;5013.6490445669915;5161.793528258828;5225.733916302311;5309.415964244007;5310.362756469094;5057.31726216956;5065.353760027932;5102.387713474407;5109.284700141909;5131.87170320745;5127.615428842777;5129.692218466904;5131.384233891957;5146.408163061295;5149.021685879671;4902.3872015998;5037.798488060436;5043.697378157322;5083.466236045162;5086.923696144635;5102.566281866083;5122.948770512247;5149.922751158811;5171.560417364416;5172.71550064735;5050.823221187241;5060.267455573949;5063.974680126576;5078.365204349457;5083.364579427572 21:23:19 INFO - PROCESS | 28906 | |5;yui.html;644.8774732800771;663.9037339585757;705.6189657584904;705.8729428702835;722.4473277942375;11742.29546227227;12894.032947528925;12936.805947910789;12940.676483087927;14451.710965780678;14215.786763198561;15119.924400377982;15172.54482365528;15176.165310907896;15192.16443095632;799.6281728996016;812.155358427235;840.4285086141422;845.1041895590694;845.9788505287386;809.9109097999216;832.4672209785742;833.2251006563893;833.3374966901328;833.4374297349295;862.0603542139067;862.4307956748547;862.7239283429305;862.7325529086004;862.8403745307138;855.6620135046621;857.6869442653418;857.8498762716499;857.8541647919868;857.9828403431959;633.6229943183845;634.2610858350017;634.3054355480741;634.3783092569262;634.933332000138;13056.151350162208;13092.410841512155;13376.665583360396;13474.932625336909;13543.45826166952;12248.142630015902;12288.385580720927;12352.44414001372;12392.62822115338;13147.14543554666;10535.683929482127;10753.03222709952;10762;10809.567617295299;11225.158113141466;3150.763692723055;3180.141361832292;3217.806931584089;3251.64231934487;3318.3529211803707;60360;60959.695201524155;63039.36960630381;63244.36755632477;63673.68163159201;9745.610175592898;9842.753931151636;9900.900990990142;9900.950495247478;10293.948530257301;18311.08444577766;18429.631407372046;18432.63134737298;18433.90783046076;18441.1701473434;6034.426729460694;6337.081123237105;6343.651099189497;6382.968085159545;6394.8721025579225;3241.7406607471344;3299.488579270217;3412.129906873731;3467.358538670354;3492.8253587320532;2794.483020641188;2856.8000239983417;2884.5528943013865;2885.091196273167;2886.826790392583;498.7730582584915;548.958828087895;574.1903915479238;588.3175516400954;594.1711312718819;589.2604780999818;616.2327901762335;621.8476473263988;630.2144521216235;643.3277225299573;8005.599720013976;8222.13667564909;8328.541930193898;8512.446690965067;8861.689840855539;585.5725320516048;607.4108115128319;607.8601921558067;624.7969409941787;626.7680958045553;3652.214773823641;3773.6599331954894;3805.1438426353848;3864.787436691009;3924.744891582095;45726.77136614296;46065.76967115143;56870.43129568734;60964;61099;47763.76118119387;49584.75207623939;49640.75179624079;49776.75111624419;50584.49415505872;46810;47783.5221647786;48617.27074093891;48816.51183488191;50290.24564631606;8410.789730256793;8891.688790892287;8914.509701966454;8925.009823280305;8937.195652391316;32545;32939.83530082334;33033.66966330402;33094.2123434196;33441.163970900925;40045;40329.39505907355;41087.589124108374;41164.38253426261;42580.574194257664;10494.68515944523;10557.736056598647;10620.20348473852;10885.619003334845;11084.722881928017;11623.59317423886;11692.766144677229;11809.527618895492;11989.280643161439;12037.75924481523;10684.679459616224;10819.621313253998;10861.51099764144;10869.619563315246;11203.943980280046;10800.459977001276;10808.56765729362;10876.763996891405;10914.890851091388;10943.890561094287;10543.630972915911;10616.309939853885;10709.57161713554;10809.945950270197;11176.10591152722;9517.476538790434;9612.711618651452;9850.408975461496;9972.252081093802;10112.393256404344;9114.726558203265;9190.126937940884;9216.170544651013;9219.262459003397;9257.444553326823 21:23:19 INFO - PROCESS | 28906 | __end_tp_report 21:23:19 INFO - PROCESS | 28906 | __start_cc_report 21:23:19 INFO - PROCESS | 28906 | _x_x_mozilla_cycle_collect,2722 21:23:19 INFO - PROCESS | 28906 | __end_cc_report 21:23:19 INFO - PROCESS | 28906 | __startTimestamp1487654599867__endTimestamp 21:23:19 INFO - PROCESS | 28906 | 21:23:19 INFO - PROCESS | 28906 | ------- Summary: start ------- 21:23:19 INFO - PROCESS | 28906 | Number of tests: 6 21:23:19 INFO - PROCESS | 28906 | 21:23:19 INFO - PROCESS | 28906 | [#0] dojo.html Cycles:165 Average:4673.75 Median:4811.66 stddev:470.38 (9.8%) stddev-sans-first:442.34 21:23:19 INFO - PROCESS | 28906 | Values: 2583.9 2613.1 2619.6 2643.5 2652.8 4592.8 4629.7 4728.7 4771.4 4775.5 4677.2 4691.5 4721.0 4724.7 4748.2 4363.8 4374.8 4429.3 4490.7 4514.1 4332.7 4408.9 4415.3 4472.5 4496.1 4209.9 4234.4 4234.8 4433.1 4439.1 4286.5 4289.3 4315.4 4356.1 4358.9 4321.3 4331.1 4337.7 4394.4 4397.3 4682.1 4718.0 4736.1 4748.0 4779.9 4901.0 4933.9 4938.6 5070.5 5080.1 5013.5 5024.8 5026.3 5027.0 5027.3 4711.0 4723.1 4728.6 4729.6 4735.7 5086.4 5125.9 5127.5 5135.1 5136.6 4923.0 4932.6 4937.3 4975.2 5046.5 5081.7 5083.0 5083.2 5086.8 5133.1 5043.1 5048.8 5051.6 5051.8 5059.3 4870.7 4902.4 4913.1 4975.1 4985.0 4335.2 4346.1 4362.0 4363.0 4379.3 3775.3 3778.1 3779.1 3811.8 3835.1 4491.8 4493.1 4502.5 4527.1 4536.8 4919.2 4947.3 4954.3 5016.9 5149.9 4396.3 4448.0 4449.2 4489.0 4537.7 4683.3 4689.4 4694.0 4714.2 4727.1 4860.7 4864.2 4866.0 4872.1 4900.2 4846.4 4927.2 4942.3 5169.5 5213.1 4690.0 4714.6 4714.7 4718.2 4725.1 4914.6 4916.6 4916.9 4917.7 4924.1 4987.4 4987.6 4997.5 4999.2 5000.2 4814.5 4861.6 4869.8 4870.6 4872.9 4875.9 4901.3 4902.2 4904.4 4904.4 4811.7 4827.2 4828.7 4832.8 4843.3 4989.4 4990.8 4994.5 4999.7 5046.1 4691.0 4730.0 4830.5 4835.9 4879.9 21:23:19 INFO - PROCESS | 28906 | 21:23:19 INFO - PROCESS | 28906 | [#1] ext.html Cycles:180 Average:38016.25 Median:18772.89 stddev:60135.37 (320.3%) stddev-sans-first:60238.30 21:23:19 INFO - PROCESS | 28906 | Values: 558.4 566.3 577.2 584.6 602.5 25914.1 39009.8 40865.2 40975.6 41063.2 36761.1 37337.6 37350.3 37400.3 37441.4 6725.6 7413.4 7589.9 7655.1 7657.7 6706.9 9431.7 9609.3 9615.1 9829.3 9589.7 9657.3 9659.9 9665.1 9673.5 9599.9 9638.6 9645.6 9651.9 9656.9 9415.8 9474.6 9481.6 9485.9 9487.4 33819.0 34015.1 34076.0 34095.5 34124.1 3304.8 3406.9 3421.6 3456.5 3466.6 26206.6 26388.3 26427.1 26561.5 27012.9 3314.7 3499.9 3500.5 3502.5 3641.0 220714.0 221553.9 221735.0 221776.0 222039.0 46013.0 46821.5 48799.5 48838.3 49357.3 51509.5 52962.5 52997.5 57312.0 57364.1 11752.3 11867.5 11881.8 11890.4 11895.6 8789.5 8980.2 8982.4 8982.9 8984.1 14226.1 14344.3 14368.8 14393.1 14527.9 5991.9 6015.9 6113.4 6164.8 6169.3 777.0 785.6 787.6 793.7 800.5 24160.4 24838.6 25185.0 25215.2 25256.2 895.7 938.1 938.8 953.3 962.2 13129.1 13428.6 13510.6 13771.3 13779.3 253728.0 254273.0 256137.0 256163.0 256360.0 42028.8 42032.0 42114.0 42153.2 42175.6 1094.9 1159.3 1163.3 1173.2 1195.4 22730.4 22892.2 24113.5 24116.0 24197.0 36005.0 36506.0 36525.6 36550.0 36554.9 213749.0 213879.0 214203.0 214658.0 215028.0 29518.6 30502.5 30608.7 30633.7 30673.4 34001.8 34052.7 34754.0 34859.1 34874.0 29033.9 29940.3 30028.7 30342.0 31292.4 29256.9 29558.9 30292.8 30317.1 30410.1 29199.0 30127.4 30256.2 30301.7 30332.4 23100.0 23508.1 26219.7 26757.5 27121.4 21174.3 21802.8 21949.6 22068.6 22109.0 21:23:19 INFO - PROCESS | 28906 | 21:23:19 INFO - PROCESS | 28906 | [#2] jquery.html Cycles:180 Average:26238.72 Median:4562.47 stddev:77379.13 (1696.0%) stddev-sans-first:77571.80 21:23:19 INFO - PROCESS | 28906 | Values: 360.6 384.2 389.1 396.5 417.7 11834.5 12082.3 12727.6 16715.9 17559.9 15416.5 15574.2 15651.5 15708.2 15760.8 4281.7 4454.0 4478.4 4561.0 4564.0 3280.1 4482.4 4517.4 4518.9 4524.1 3391.7 3419.0 3826.9 5906.2 6000.6 5972.3 5977.2 5978.3 5979.0 5984.9 5253.4 5378.4 5401.4 5413.2 5415.6 11946.3 12002.5 12034.3 12104.9 12118.1 1989.9 2060.1 2105.7 2112.6 2155.8 8223.9 8582.4 8872.3 8926.6 9060.2 1652.3 1697.6 1703.6 1712.9 1733.3 71205.6 73495.0 73737.6 74559.6 76850.0 10465.5 10640.3 10824.5 10828.6 10865.7 15143.2 15151.9 15166.8 15173.3 15181.5 6587.6 6740.0 6911.7 6964.9 6973.7 6007.4 6071.0 6167.6 6194.8 6248.3 4008.3 4094.7 4421.7 4518.8 4783.0 1914.8 1937.4 1963.5 1967.9 2008.9 765.3 792.6 797.3 814.9 824.9 16889.6 17312.0 17422.0 17470.6 17753.1 764.3 794.3 805.9 809.3 812.0 9110.5 9667.6 9765.9 9773.2 9861.4 459292.0 467095.0 469756.0 476289.0 477086.0 56505.4 84071.6 84317.6 86237.6 87184.0 908.4 925.5 931.7 932.6 932.8 14552.2 14786.4 15080.8 15262.3 15277.5 35990.8 36290.6 36334.0 36679.6 37478.3 49179.5 49762.0 49814.3 50094.0 50096.7 6812.2 7042.6 7120.0 7160.5 7541.2 8734.3 11751.5 12088.2 12145.9 12508.1 9805.6 9953.5 9976.0 10212.7 10561.9 9637.0 9794.9 9883.9 9984.3 10080.6 10222.7 10277.9 10315.6 10406.0 10509.4 6902.6 6915.3 6954.1 7012.9 7088.5 8063.8 9993.7 10071.1 10372.7 10408.3 21:23:19 INFO - PROCESS | 28906 | 21:23:19 INFO - PROCESS | 28906 | [#3] mootools.html Cycles:180 Average:4941.59 Median:2583.46 stddev:2829.23 (109.5%) stddev-sans-first:2824.18 21:23:19 INFO - PROCESS | 28906 | Values: 1334.9 1341.8 1351.5 1352.8 1362.8 7424.8 7848.0 7877.8 7881.1 7895.8 7387.7 7695.4 7749.0 7903.8 7920.9 2015.6 2050.9 2060.3 2063.8 2064.8 1800.5 2127.7 2173.1 2174.5 2180.0 1829.9 2021.7 2063.8 2066.3 2102.2 1976.3 1978.9 1981.2 1989.1 1991.3 1767.5 1999.3 2084.0 2084.8 2085.4 6732.0 7372.3 7641.7 7660.1 7856.3 4560.2 4702.0 4706.1 4707.8 4709.5 2667.7 2773.6 2828.5 2831.1 2842.6 1090.5 1090.8 1130.4 1150.3 1151.2 10357.2 10423.6 10447.6 10463.2 10492.5 3816.3 3955.2 4024.0 4025.5 4046.4 8341.8 8350.9 8358.2 8363.1 8372.1 4564.4 4581.0 4581.0 4581.0 4583.2 4092.0 4122.3 4126.3 4132.7 4134.5 3303.7 3351.0 3419.8 3457.0 3480.7 1057.5 1070.0 1085.7 1087.2 1090.8 781.0 790.5 795.1 798.7 802.5 4587.5 4826.3 4833.8 4878.2 5116.6 423.9 433.9 457.3 465.0 472.8 2950.4 3007.7 3192.7 3222.8 3335.4 8131.5 8305.4 9312.4 9327.9 9535.2 9624.5 9639.1 9645.1 9645.7 9647.3 7021.0 7398.3 7424.2 7425.9 7462.7 4837.5 4906.5 4992.4 5010.9 5042.9 8297.3 8539.5 8634.4 8645.7 8766.9 8656.2 8823.1 8883.7 8885.6 8898.6 6518.9 6652.7 6659.9 6665.7 6667.6 7279.4 7288.7 7318.2 7382.6 7408.3 6517.6 6546.8 6559.0 6607.0 6759.0 6583.6 6617.8 6651.4 6652.7 6657.7 5491.4 5596.2 5610.6 5623.6 5627.8 4259.4 4277.6 4297.0 4351.5 4419.9 4193.3 4197.4 4258.6 4329.7 4413.1 21:23:19 INFO - PROCESS | 28906 | 21:23:19 INFO - PROCESS | 28906 | [#4] prototype.html Cycles:180 Average:4222.25 Median:4798.46 stddev:1219.16 (25.4%) stddev-sans-first:1187.33 21:23:19 INFO - PROCESS | 28906 | Values: 344.4 365.5 371.3 372.4 372.8 4917.4 4918.0 4957.9 4970.8 4985.9 4649.2 4799.1 4819.6 4839.5 4919.8 3825.2 3826.2 3839.7 3855.5 3873.5 3847.0 3852.3 3853.2 3857.3 3857.9 3622.6 3820.5 3823.9 3824.5 3843.3 3791.2 3798.0 3802.1 3828.6 3834.1 3345.8 3355.3 3355.7 3363.8 3367.1 4724.4 4725.5 4764.3 4768.9 4774.3 4172.0 4269.3 4315.7 4349.6 4357.3 5029.3 5054.4 5067.8 5084.6 5085.3 3626.5 3634.1 3657.8 3662.0 3671.5 4667.5 5017.8 5193.5 5315.4 5392.9 3694.1 3697.4 3697.8 3700.1 3714.0 3739.7 3753.0 3760.8 3761.5 3762.9 4280.3 4287.7 4290.4 4297.4 4298.7 4942.8 5067.7 5116.3 5165.9 5176.9 1258.0 1259.8 1270.4 1270.4 1273.3 815.5 860.6 861.6 873.9 875.6 4377.7 4390.3 4441.4 4472.7 4477.9 5158.9 5166.8 5171.7 5173.4 5188.5 4231.9 4416.7 4422.9 4475.7 4479.8 2659.6 2690.4 2708.5 2715.6 2720.6 4754.8 4772.6 4792.2 4803.3 4806.2 4797.8 5070.6 5185.4 5224.7 5231.9 4901.3 4929.3 4932.5 4947.4 4951.3 5246.4 5249.2 5251.8 5255.8 5260.0 4954.8 5176.4 5200.4 5236.5 5261.4 5003.3 5013.7 5014.5 5020.0 5025.4 3737.3 3739.0 3749.8 3753.3 3757.0 5013.6 5161.8 5225.7 5309.4 5310.4 5057.3 5065.4 5102.4 5109.3 5131.9 5127.6 5129.7 5131.4 5146.4 5149.0 4902.4 5037.8 5043.7 5083.5 5086.9 5102.6 5122.9 5149.9 5171.6 5172.7 5050.8 5060.3 5064.0 5078.4 5083.4 21:23:19 INFO - PROCESS | 28906 | 21:23:19 INFO - PROCESS | 28906 | [#5] yui.html Cycles:180 Average:13852.13 Median:47773.64 stddev:16570.74 (34.7%) stddev-sans-first:16587.54 21:23:19 INFO - PROCESS | 28906 | Values: 644.9 663.9 705.6 705.9 722.4 11742.3 12894.0 12936.8 12940.7 14451.7 14215.8 15119.9 15172.5 15176.2 15192.2 799.6 812.2 840.4 845.1 846.0 809.9 832.5 833.2 833.3 833.4 862.1 862.4 862.7 862.7 862.8 855.7 857.7 857.8 857.9 858.0 633.6 634.3 634.3 634.4 634.9 13056.2 13092.4 13376.7 13474.9 13543.5 12248.1 12288.4 12352.4 12392.6 13147.1 10535.7 10753.0 10762.0 10809.6 11225.2 3150.8 3180.1 3217.8 3251.6 3318.4 60360.0 60959.7 63039.4 63244.4 63673.7 9745.6 9842.8 9900.9 9901.0 10293.9 18311.1 18429.6 18432.6 18433.9 18441.2 6034.4 6337.1 6343.7 6383.0 6394.9 3241.7 3299.5 3412.1 3467.4 3492.8 2794.5 2856.8 2884.6 2885.1 2886.8 498.8 549.0 574.2 588.3 594.2 589.3 616.2 621.8 630.2 643.3 8005.6 8222.1 8328.5 8512.4 8861.7 585.6 607.4 607.9 624.8 626.8 3652.2 3773.7 3805.1 3864.8 3924.7 45726.8 46065.8 56870.4 60964.0 61099.0 47763.8 49584.8 49640.8 49776.8 50584.5 46810.0 47783.5 48617.3 48816.5 50290.2 8410.8 8891.7 8914.5 8925.0 8937.2 32545.0 32939.8 33033.7 33094.2 33441.2 40045.0 40329.4 41087.6 41164.4 42580.6 10494.7 10557.7 10620.2 10885.6 11084.7 11623.6 11692.8 11809.5 11989.3 12037.8 10684.7 10819.6 10861.5 10869.6 11203.9 10800.5 10808.6 10876.8 10914.9 10943.9 10543.6 10616.3 10709.6 10809.9 11176.1 9517.5 9612.7 9850.4 9972.3 10112.4 9114.7 9190.1 9216.2 9219.3 9257.4 21:23:19 INFO - PROCESS | 28906 | -------- Summary: end -------- 21:23:19 INFO - PROCESS | 28906 | 21:23:20 INFO - TEST-INFO | 28906: exit 0 21:23:20 INFO - TEST-OK | dromaeo_css | took 1123300ms 21:23:20 INFO - TEST-START | kraken 21:23:20 INFO - operating with platform_type : linux_ 21:23:20 INFO - Initialising browser for kraken test... 21:23:20 INFO - TEST-INFO | started process 29233 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp42KXxE/profile http://localhost:44912/getInfo.html) 21:23:23 INFO - PROCESS | 29233 | Iterating over profile /tmp/tmp42KXxE/profile 21:23:24 INFO - PROCESS | 29233 | __metrics Screen width/height:1600/1200 21:23:24 INFO - PROCESS | 29233 | colorDepth:24 21:23:24 INFO - PROCESS | 29233 | Browser inner width/height: 1024/697 21:23:24 INFO - PROCESS | 29233 | __metrics 21:23:24 INFO - PROCESS | 29233 | No birth date. Using mtime. 21:23:24 INFO - PROCESS | 29233 | Using date: /tmp/tmp42KXxE/profile/extensions.json = Mon Feb 20 2017 21:23:23 GMT-0800 (PST) 21:23:24 INFO - PROCESS | 29233 | No birth date. Using mtime. 21:23:24 INFO - PROCESS | 29233 | Using date: /tmp/tmp42KXxE/profile/webappsstore.sqlite-wal = Mon Feb 20 2017 21:23:24 GMT-0800 (PST) 21:23:24 INFO - PROCESS | 29233 | No birth date. Using mtime. 21:23:24 INFO - PROCESS | 29233 | Using date: /tmp/tmp42KXxE/profile/extensions = Mon Feb 20 2017 21:23:21 GMT-0800 (PST) 21:23:24 INFO - PROCESS | 29233 | No birth date. Using mtime. 21:23:24 INFO - PROCESS | 29233 | Using date: /tmp/tmp42KXxE/profile/secmod.db = Mon Feb 20 2017 21:23:21 GMT-0800 (PST) 21:23:24 INFO - PROCESS | 29233 | No birth date. Using mtime. 21:23:24 INFO - PROCESS | 29233 | Using date: /tmp/tmp42KXxE/profile/storage.sqlite-journal = Mon Feb 20 2017 21:23:24 GMT-0800 (PST) 21:23:25 INFO - PROCESS | 29233 | No birth date. Using mtime. 21:23:25 INFO - PROCESS | 29233 | Using date: /tmp/tmp42KXxE/profile/webappsstore.sqlite = Mon Feb 20 2017 21:23:24 GMT-0800 (PST) 21:23:25 INFO - PROCESS | 29233 | No birth date. Using mtime. 21:23:25 INFO - PROCESS | 29233 | Using date: /tmp/tmp42KXxE/profile/storage.sqlite = Mon Feb 20 2017 21:23:24 GMT-0800 (PST) 21:23:25 INFO - PROCESS | 29233 | No birth date. Using mtime. 21:23:25 INFO - PROCESS | 29233 | Using date: /tmp/tmp42KXxE/profile/blocklist.xml = Mon Feb 20 2017 21:23:21 GMT-0800 (PST) 21:23:25 INFO - PROCESS | 29233 | No birth date. Using mtime. 21:23:25 INFO - PROCESS | 29233 | Using date: /tmp/tmp42KXxE/profile/frequencyCap.json = Mon Feb 20 2017 21:23:23 GMT-0800 (PST) 21:23:25 INFO - PROCESS | 29233 | No birth date. Using mtime. 21:23:25 INFO - PROCESS | 29233 | Using date: /tmp/tmp42KXxE/profile/places.sqlite-shm = Mon Feb 20 2017 21:23:22 GMT-0800 (PST) 21:23:25 INFO - PROCESS | 29233 | No birth date. Using mtime. 21:23:25 INFO - PROCESS | 29233 | Using date: /tmp/tmp42KXxE/profile/gmp = Mon Feb 20 2017 21:23:21 GMT-0800 (PST) 21:23:25 INFO - PROCESS | 29233 | No birth date. Using mtime. 21:23:25 INFO - PROCESS | 29233 | Using date: /tmp/tmp42KXxE/profile/sessionCheckpoints.json = Mon Feb 20 2017 21:23:24 GMT-0800 (PST) 21:23:25 INFO - PROCESS | 29233 | No birth date. Using mtime. 21:23:25 INFO - PROCESS | 29233 | Using date: /tmp/tmp42KXxE/profile/crashes = Mon Feb 20 2017 21:23:20 GMT-0800 (PST) 21:23:25 INFO - PROCESS | 29233 | No birth date. Using mtime. 21:23:25 INFO - PROCESS | 29233 | Using date: /tmp/tmp42KXxE/profile/places.sqlite-wal = Mon Feb 20 2017 21:23:24 GMT-0800 (PST) 21:23:25 INFO - PROCESS | 29233 | No birth date. Using mtime. 21:23:25 INFO - PROCESS | 29233 | Using date: /tmp/tmp42KXxE/profile/user.js = Mon Feb 20 2017 21:23:20 GMT-0800 (PST) 21:23:25 INFO - PROCESS | 29233 | No birth date. Using mtime. 21:23:25 INFO - PROCESS | 29233 | Using date: /tmp/tmp42KXxE/profile/minidumps = Mon Feb 20 2017 21:23:20 GMT-0800 (PST) 21:23:25 INFO - PROCESS | 29233 | No birth date. Using mtime. 21:23:25 INFO - PROCESS | 29233 | Using date: /tmp/tmp42KXxE/profile/safebrowsing = Mon Feb 20 2017 21:23:24 GMT-0800 (PST) 21:23:25 INFO - PROCESS | 29233 | No birth date. Using mtime. 21:23:25 INFO - PROCESS | 29233 | Using date: /tmp/tmp42KXxE/profile/webappsstore.sqlite-shm = Mon Feb 20 2017 21:23:24 GMT-0800 (PST) 21:23:25 INFO - PROCESS | 29233 | No birth date. Using mtime. 21:23:25 INFO - PROCESS | 29233 | Using date: /tmp/tmp42KXxE/profile/startupCache = Mon Feb 20 2017 21:23:20 GMT-0800 (PST) 21:23:25 INFO - PROCESS | 29233 | No birth date. Using mtime. 21:23:25 INFO - PROCESS | 29233 | Using date: /tmp/tmp42KXxE/profile/prefs.js = Mon Feb 20 2017 21:03:57 GMT-0800 (PST) 21:23:25 INFO - PROCESS | 29233 | No birth date. Using mtime. 21:23:25 INFO - PROCESS | 29233 | Using date: /tmp/tmp42KXxE/profile/cache2 = Mon Feb 20 2017 21:23:21 GMT-0800 (PST) 21:23:25 INFO - PROCESS | 29233 | No birth date. Using mtime. 21:23:25 INFO - PROCESS | 29233 | Using date: /tmp/tmp42KXxE/profile/bookmarkbackups = Mon Feb 20 2017 21:23:23 GMT-0800 (PST) 21:23:25 INFO - PROCESS | 29233 | No birth date. Using mtime. 21:23:25 INFO - PROCESS | 29233 | Using date: /tmp/tmp42KXxE/profile/compatibility.ini = Mon Feb 20 2017 21:23:21 GMT-0800 (PST) 21:23:25 INFO - PROCESS | 29233 | No birth date. Using mtime. 21:23:25 INFO - PROCESS | 29233 | Using date: /tmp/tmp42KXxE/profile/pluginreg.dat = Mon Feb 20 2017 21:23:23 GMT-0800 (PST) 21:23:25 INFO - PROCESS | 29233 | No birth date. Using mtime. 21:23:25 INFO - PROCESS | 29233 | Using date: /tmp/tmp42KXxE/profile/mimeTypes.rdf = Mon Feb 20 2017 21:23:24 GMT-0800 (PST) 21:23:25 INFO - PROCESS | 29233 | Stat failure 21:23:25 INFO - PROCESS | 29233 | No birth date. Using mtime. 21:23:25 INFO - PROCESS | 29233 | Using date: /tmp/tmp42KXxE/profile/directoryLinks.json = Mon Feb 20 2017 21:23:23 GMT-0800 (PST) 21:23:25 INFO - PROCESS | 29233 | No birth date. Using mtime. 21:23:25 INFO - PROCESS | 29233 | Using date: /tmp/tmp42KXxE/profile/localstore.rdf = Mon Feb 20 2017 21:03:57 GMT-0800 (PST) 21:23:25 INFO - PROCESS | 29233 | No birth date. Using mtime. 21:23:25 INFO - PROCESS | 29233 | Using date: /tmp/tmp42KXxE/profile/content-prefs.sqlite = Mon Feb 20 2017 21:23:23 GMT-0800 (PST) 21:23:25 INFO - PROCESS | 29233 | No birth date. Using mtime. 21:23:25 INFO - PROCESS | 29233 | Using date: /tmp/tmp42KXxE/profile/thumbnails = Mon Feb 20 2017 21:23:23 GMT-0800 (PST) 21:23:25 INFO - PROCESS | 29233 | No birth date. Using mtime. 21:23:25 INFO - PROCESS | 29233 | Using date: /tmp/tmp42KXxE/profile/extensions.ini = Mon Feb 20 2017 21:23:21 GMT-0800 (PST) 21:23:25 INFO - PROCESS | 29233 | No birth date. Using mtime. 21:23:25 INFO - PROCESS | 29233 | Using date: /tmp/tmp42KXxE/profile/permissions.sqlite = Mon Feb 20 2017 21:23:22 GMT-0800 (PST) 21:23:25 INFO - PROCESS | 29233 | No birth date. Using mtime. 21:23:25 INFO - PROCESS | 29233 | Using date: /tmp/tmp42KXxE/profile/addons.json = Mon Feb 20 2017 21:23:23 GMT-0800 (PST) 21:23:25 INFO - PROCESS | 29233 | No birth date. Using mtime. 21:23:25 INFO - PROCESS | 29233 | Using date: /tmp/tmp42KXxE/profile/places.sqlite = Mon Feb 20 2017 21:23:25 GMT-0800 (PST) 21:23:25 INFO - PROCESS | 29233 | No birth date. Using mtime. 21:23:25 INFO - PROCESS | 29233 | Using date: /tmp/tmp42KXxE/profile/cert8.db = Mon Feb 20 2017 21:23:21 GMT-0800 (PST) 21:23:25 INFO - PROCESS | 29233 | No birth date. Using mtime. 21:23:25 INFO - PROCESS | 29233 | Using date: /tmp/tmp42KXxE/profile/.parentlock = Mon Feb 20 2017 21:23:20 GMT-0800 (PST) 21:23:25 INFO - PROCESS | 29233 | No birth date. Using mtime. 21:23:25 INFO - PROCESS | 29233 | Using date: /tmp/tmp42KXxE/profile/key3.db = Mon Feb 20 2017 21:23:21 GMT-0800 (PST) 21:23:25 INFO - PROCESS | 29233 | No birth date. Using mtime. 21:23:25 INFO - PROCESS | 29233 | Using date: /tmp/tmp42KXxE/profile/safebrowsing-backup = Mon Feb 20 2017 21:23:23 GMT-0800 (PST) 21:23:25 INFO - PROCESS | 29233 | No birth date. Using mtime. 21:23:25 INFO - PROCESS | 29233 | Using date: /tmp/tmp42KXxE/profile/cookies.sqlite = Mon Feb 20 2017 21:23:24 GMT-0800 (PST) 21:23:25 INFO - PROCESS | 29233 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 21:23:25 INFO - PROCESS | 29233 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 21:23:26 INFO - TEST-INFO | 29233: exit 0 21:23:26 INFO - Browser initialized. 21:23:26 INFO - Running cycle 1/1 for kraken test... 21:23:26 INFO - TEST-INFO | started process 29324 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp42KXxE/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:23:38 INFO - PROCESS | 29324 | RSS: Main: 147378176 21:23:38 INFO - PROCESS | 29324 | 21:23:41 INFO - PROCESS | 29324 | Cycle 1(1): loaded http://localhost:44912/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:44912/tests/kraken/driver.html?testName=audio-beat-detection) 21:23:42 INFO - PROCESS | 29324 | RSS: Main: 202293248 21:23:42 INFO - PROCESS | 29324 | 21:23:45 INFO - PROCESS | 29324 | Cycle 1(1): loaded http://localhost:44912/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:44912/tests/kraken/driver.html?testName=audio-dft) 21:23:46 INFO - PROCESS | 29324 | RSS: Main: 181358592 21:23:46 INFO - PROCESS | 29324 | 21:23:49 INFO - PROCESS | 29324 | Cycle 1(1): loaded http://localhost:44912/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:44912/tests/kraken/driver.html?testName=audio-fft) 21:23:49 INFO - PROCESS | 29324 | RSS: Main: 263217152 21:23:49 INFO - PROCESS | 29324 | 21:23:51 INFO - PROCESS | 29324 | Cycle 1(1): loaded http://localhost:44912/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:44912/tests/kraken/driver.html?testName=audio-oscillator) 21:23:52 INFO - PROCESS | 29324 | RSS: Main: 174628864 21:23:52 INFO - PROCESS | 29324 | 21:23:54 INFO - PROCESS | 29324 | Cycle 1(1): loaded http://localhost:44912/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:44912/tests/kraken/driver.html?testName=imaging-gaussian-blur) 21:23:55 INFO - PROCESS | 29324 | RSS: Main: 177741824 21:23:55 INFO - PROCESS | 29324 | 21:23:58 INFO - PROCESS | 29324 | Cycle 1(1): loaded http://localhost:44912/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:44912/tests/kraken/driver.html?testName=imaging-darkroom) 21:23:59 INFO - PROCESS | 29324 | RSS: Main: 216756224 21:23:59 INFO - PROCESS | 29324 | 21:24:02 INFO - PROCESS | 29324 | Cycle 1(1): loaded http://localhost:44912/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:44912/tests/kraken/driver.html?testName=imaging-desaturate) 21:24:03 INFO - PROCESS | 29324 | RSS: Main: 211058688 21:24:03 INFO - PROCESS | 29324 | 21:24:06 INFO - PROCESS | 29324 | Cycle 1(1): loaded http://localhost:44912/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:44912/tests/kraken/driver.html?testName=json-parse-financial) 21:24:06 INFO - PROCESS | 29324 | RSS: Main: 213463040 21:24:06 INFO - PROCESS | 29324 | 21:24:08 INFO - PROCESS | 29324 | Cycle 1(1): loaded http://localhost:44912/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:44912/tests/kraken/driver.html?testName=json-stringify-tinderbox) 21:24:09 INFO - PROCESS | 29324 | RSS: Main: 177319936 21:24:09 INFO - PROCESS | 29324 | 21:24:10 INFO - PROCESS | 29324 | Cycle 1(1): loaded http://localhost:44912/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:44912/tests/kraken/driver.html?testName=stanford-crypto-aes) 21:24:11 INFO - PROCESS | 29324 | RSS: Main: 184795136 21:24:11 INFO - PROCESS | 29324 | 21:24:13 INFO - PROCESS | 29324 | Cycle 1(1): loaded http://localhost:44912/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:44912/tests/kraken/driver.html?testName=stanford-crypto-ccm) 21:24:14 INFO - PROCESS | 29324 | RSS: Main: 192819200 21:24:14 INFO - PROCESS | 29324 | 21:24:17 INFO - PROCESS | 29324 | Cycle 1(1): loaded http://localhost:44912/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:44912/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 21:24:18 INFO - PROCESS | 29324 | RSS: Main: 202145792 21:24:18 INFO - PROCESS | 29324 | 21:24:21 INFO - PROCESS | 29324 | Cycle 1(1): loaded http://localhost:44912/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:44912/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 21:24:21 INFO - PROCESS | 29324 | RSS: Main: 198238208 21:24:21 INFO - PROCESS | 29324 | 21:24:23 INFO - PROCESS | 29324 | Cycle 1(1): loaded http://localhost:44912/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:44912/tests/kraken/driver.html?testName=ai-astar) 21:24:23 INFO - PROCESS | 29324 | RSS: Main: 250847232 21:24:23 INFO - PROCESS | 29324 | 21:24:23 INFO - PROCESS | 29324 | __start_tp_report 21:24:23 INFO - PROCESS | 29324 | _x_x_mozilla_page_load 21:24:23 INFO - PROCESS | 29324 | _x_x_mozilla_page_load_details 21:24:23 INFO - PROCESS | 29324 | |i|pagename|runs| 21:24:23 INFO - PROCESS | 29324 | |0;ai-astar;108;116;114;116;115;115;116;115;114;114 21:24:23 INFO - PROCESS | 29324 | |1;audio-beat-detection;156;133;127;171;133;127;132;126;151;133 21:24:23 INFO - PROCESS | 29324 | |2;audio-dft;162;163;161;164;163;162;162;164;161;161 21:24:23 INFO - PROCESS | 29324 | |3;audio-fft;77;77;77;113;77;77;77;77;112;77 21:24:23 INFO - PROCESS | 29324 | |4;audio-oscillator;84;85;116;85;84;84;106;84;85;107 21:24:23 INFO - PROCESS | 29324 | |5;imaging-gaussian-blur;113;114;114;114;113;113;113;113;113;113 21:24:23 INFO - PROCESS | 29324 | |6;imaging-darkroom;149;149;149;150;150;149;150;148;152;149 21:24:23 INFO - PROCESS | 29324 | |7;imaging-desaturate;96;96;95;95;95;95;95;95;96;95 21:24:23 INFO - PROCESS | 29324 | |8;json-parse-financial;61;62;63;62;62;63;61;62;63;63 21:24:23 INFO - PROCESS | 29324 | |9;json-stringify-tinderbox;66;65;66;66;66;66;65;65;68;65 21:24:23 INFO - PROCESS | 29324 | |10;stanford-crypto-aes;88;88;87;87;88;88;96;88;88;88 21:24:23 INFO - PROCESS | 29324 | |11;stanford-crypto-ccm;142;137;138;142;139;138;145;143;185;136 21:24:23 INFO - PROCESS | 29324 | |12;stanford-crypto-pbkdf2;182;180;190;185;183;182;185;180;193;184 21:24:23 INFO - PROCESS | 29324 | |13;stanford-crypto-sha256-iterative;75;69;70;69;70;68;69;71;69;68 21:24:23 INFO - PROCESS | 29324 | __end_tp_report 21:24:23 INFO - PROCESS | 29324 | __start_cc_report 21:24:23 INFO - PROCESS | 29324 | _x_x_mozilla_cycle_collect,2006 21:24:23 INFO - PROCESS | 29324 | __end_cc_report 21:24:23 INFO - PROCESS | 29324 | __startTimestamp1487654663779__endTimestamp 21:24:23 INFO - PROCESS | 29324 | 21:24:23 INFO - PROCESS | 29324 | ------- Summary: start ------- 21:24:23 INFO - PROCESS | 29324 | Number of tests: 14 21:24:23 INFO - PROCESS | 29324 | 21:24:23 INFO - PROCESS | 29324 | [#0] ai-astar Cycles:10 Average:114.30 Median:115.00 stddev:2.36 (2.1%) stddev-sans-first:0.87 21:24:23 INFO - PROCESS | 29324 | Values: 108.0 116.0 114.0 116.0 115.0 115.0 116.0 115.0 114.0 114.0 21:24:23 INFO - PROCESS | 29324 | 21:24:23 INFO - PROCESS | 29324 | [#1] audio-beat-detection Cycles:10 Average:138.90 Median:133.00 stddev:15.17 (11.4%) stddev-sans-first:14.77 21:24:23 INFO - PROCESS | 29324 | Values: 156.0 133.0 127.0 171.0 133.0 127.0 132.0 126.0 151.0 133.0 21:24:23 INFO - PROCESS | 29324 | 21:24:23 INFO - PROCESS | 29324 | [#2] audio-dft Cycles:10 Average:162.30 Median:162.50 stddev:1.16 (0.7%) stddev-sans-first:1.22 21:24:23 INFO - PROCESS | 29324 | Values: 162.0 163.0 161.0 164.0 163.0 162.0 162.0 164.0 161.0 161.0 21:24:23 INFO - PROCESS | 29324 | 21:24:23 INFO - PROCESS | 29324 | [#3] audio-fft Cycles:10 Average:84.10 Median:77.00 stddev:14.97 (19.4%) stddev-sans-first:15.66 21:24:23 INFO - PROCESS | 29324 | Values: 77.0 77.0 77.0 113.0 77.0 77.0 77.0 77.0 112.0 77.0 21:24:23 INFO - PROCESS | 29324 | 21:24:23 INFO - PROCESS | 29324 | [#4] audio-oscillator Cycles:10 Average:92.00 Median:84.00 stddev:12.47 (14.8%) stddev-sans-first:12.89 21:24:23 INFO - PROCESS | 29324 | Values: 84.0 85.0 116.0 85.0 84.0 84.0 106.0 84.0 85.0 107.0 21:24:23 INFO - PROCESS | 29324 | 21:24:23 INFO - PROCESS | 29324 | [#5] imaging-gaussian-blur Cycles:10 Average:113.30 Median:113.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 21:24:23 INFO - PROCESS | 29324 | Values: 113.0 114.0 114.0 114.0 113.0 113.0 113.0 113.0 113.0 113.0 21:24:23 INFO - PROCESS | 29324 | 21:24:23 INFO - PROCESS | 29324 | [#6] imaging-darkroom Cycles:10 Average:149.50 Median:149.50 stddev:1.08 (0.7%) stddev-sans-first:1.13 21:24:23 INFO - PROCESS | 29324 | Values: 149.0 149.0 149.0 150.0 150.0 149.0 150.0 148.0 152.0 149.0 21:24:23 INFO - PROCESS | 29324 | 21:24:23 INFO - PROCESS | 29324 | [#7] imaging-desaturate Cycles:10 Average:95.30 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.44 21:24:23 INFO - PROCESS | 29324 | Values: 96.0 96.0 95.0 95.0 95.0 95.0 95.0 95.0 96.0 95.0 21:24:23 INFO - PROCESS | 29324 | 21:24:23 INFO - PROCESS | 29324 | [#8] json-parse-financial Cycles:10 Average:62.20 Median:62.50 stddev:0.79 (1.3%) stddev-sans-first:0.71 21:24:23 INFO - PROCESS | 29324 | Values: 61.0 62.0 63.0 62.0 62.0 63.0 61.0 62.0 63.0 63.0 21:24:23 INFO - PROCESS | 29324 | 21:24:23 INFO - PROCESS | 29324 | [#9] json-stringify-tinderbox Cycles:10 Average:65.80 Median:66.00 stddev:0.92 (1.4%) stddev-sans-first:0.97 21:24:23 INFO - PROCESS | 29324 | Values: 66.0 65.0 66.0 66.0 66.0 66.0 65.0 65.0 68.0 65.0 21:24:23 INFO - PROCESS | 29324 | 21:24:23 INFO - PROCESS | 29324 | [#10] stanford-crypto-aes Cycles:10 Average:88.60 Median:88.00 stddev:2.63 (3.0%) stddev-sans-first:2.78 21:24:23 INFO - PROCESS | 29324 | Values: 88.0 88.0 87.0 87.0 88.0 88.0 96.0 88.0 88.0 88.0 21:24:23 INFO - PROCESS | 29324 | 21:24:23 INFO - PROCESS | 29324 | [#11] stanford-crypto-ccm Cycles:10 Average:144.50 Median:142.00 stddev:14.52 (10.2%) stddev-sans-first:15.38 21:24:23 INFO - PROCESS | 29324 | Values: 142.0 137.0 138.0 142.0 139.0 138.0 145.0 143.0 185.0 136.0 21:24:23 INFO - PROCESS | 29324 | 21:24:23 INFO - PROCESS | 29324 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:184.40 Median:184.50 stddev:4.20 (2.3%) stddev-sans-first:4.36 21:24:23 INFO - PROCESS | 29324 | Values: 182.0 180.0 190.0 185.0 183.0 182.0 185.0 180.0 193.0 184.0 21:24:23 INFO - PROCESS | 29324 | 21:24:23 INFO - PROCESS | 29324 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.80 Median:69.50 stddev:2.04 (2.9%) stddev-sans-first:0.97 21:24:23 INFO - PROCESS | 29324 | Values: 75.0 69.0 70.0 69.0 70.0 68.0 69.0 71.0 69.0 68.0 21:24:23 INFO - PROCESS | 29324 | -------- Summary: end -------- 21:24:23 INFO - PROCESS | 29324 | 21:24:24 INFO - TEST-INFO | 29324: exit 0 21:24:24 INFO - TEST-OK | kraken | took 64174ms 21:24:24 INFO - SUITE-END | took 1187s 21:24:25 INFO - Completed test suite (00:19:47) 21:24:25 INFO - javascript benchmark 21:24:25 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6162.0470335485, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2583.9095631652895, 2613.098481024046, 2619.593962935745, 2643.510950474162, 2652.8275662081974, 4592.793324300406, 4629.745364004978, 4728.716277023376, 4771.355866957964, 4775.49857264987, 4677.181493238678, 4691.483936766953, 4720.976395118028, 4724.669273150872, 4748.192807222772, 4363.759993200372, 4374.846880359188, 4429.33559966006, 4490.7081039732475, 4514.052049069699, 4332.740035597874, 4408.889777755566, 4415.293553031515, 4472.53038430965, 4496.078303947683, 4209.873703788891, 4234.449521562208, 4234.830606775756, 4433.113377324516, 4439.134368798085, 4286.464191976003, 4289.335153051282, 4315.438992930899, 4356.085222103362, 4358.869233922987, 4321.330193819963, 4331.090471001094, 4337.696361254713, 4394.384786129945, 4397.296432570805, 4682.1338052460405, 4718.0, 4736.100140973239, 4748.026654535846, 4779.904401911977, 4900.950990490085, 4933.901321973576, 4938.555530002282, 5070.543651071422, 5080.13637681595, 5013.498650134994, 5024.824131155392, 5026.346574945271, 5027.019731152427, 5027.271045698353, 4710.952890471086, 4723.102610503994, 4728.550787675165, 4729.62163026957, 4735.715857048588, 5086.364204474441, 5125.923111153335, 5127.46161653021, 5135.075686376488, 5136.614753893473, 4923.0, 4932.580730637864, 4937.259411088366, 4975.228839529878, 5046.545810877039, 5081.669691470042, 5083.034223497523, 5083.237514372874, 5086.847394578169, 5133.101707201225, 5043.117454445457, 5048.7728052237, 5051.646384753032, 5051.848444546669, 5059.342285502861, 4870.683405578626, 4902.436219834764, 4913.091078150482, 4975.104481193348, 4984.950150498521, 4335.241332766753, 4346.1090476452255, 4362.040351122748, 4362.956370436255, 4379.277419225792, 3775.339315619756, 3778.1499162688174, 3779.093017675738, 3811.8475260989803, 3835.079580900564, 4491.8427855025575, 4493.146302202571, 4502.527234640315, 4527.094581083797, 4536.841210557614, 4919.188333924933, 4947.307376967227, 4954.256861470736, 5016.899662006813, 5149.897002059938, 4396.252637051709, 4447.976965297999, 4449.243628583149, 4489.0, 4537.659675524349, 4683.250679891201, 4689.390379250675, 4694.0, 4714.245720684674, 4727.054589082198, 4860.659753817199, 4864.221724524059, 4866.0, 4872.074305881871, 4900.191468407744, 4846.442659094108, 4927.187014142626, 4942.258661200849, 5169.508896654812, 5213.087709650872, 4689.99165179488, 4714.622830173509, 4714.669973101987, 4718.221493453541, 4725.10223057618, 4914.606831453547, 4916.557509824133, 4916.926246106383, 4917.729524876167, 4924.064427758714, 4987.426445958616, 4987.551120399182, 4997.525235102659, 4999.150144475375, 5000.2499625056535, 4814.518548145157, 4861.611071114232, 4869.756512174306, 4870.634702397264, 4872.926906096341, 4875.87810304731, 4901.3138160658245, 4902.166631672696, 4904.386951631046, 4904.435989861069, 4811.663183577256, 4827.155247831685, 4828.7102773833685, 4832.830850920201, 4843.297721830385, 4989.401271847401, 4990.8253211137435, 4994.525520075587, 4999.675021123615, 5046.066477701637, 4691.038337140946, 4729.983053643483, 4830.492798256273, 4835.854924352275, 4879.853604391874], "value": 4644.298412500325, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [558.3913534247672, 566.2921348314607, 577.1746402644217, 584.5557376393939, 602.5179856115107, 25914.093006744766, 39009.80495097524, 40865.182696346055, 40975.59024409755, 41063.17873642525, 36761.08097297569, 37337.626623733755, 37350.252994940085, 37400.25199496008, 37441.438378424275, 6725.630090345029, 7413.443991700617, 7589.924100759004, 7655.08139023318, 7657.66441235423, 6706.932930670704, 9431.717048488556, 9609.327347085708, 9615.086566776145, 9829.262805289596, 9589.66436174734, 9657.275704322168, 9659.855102173438, 9665.130138287554, 9673.516324183798, 9599.856002159973, 9638.614455421775, 9645.614175432975, 9651.903480965171, 9656.903430965705, 9415.811683766355, 9474.573644186028, 9481.620735170585, 9485.90514094864, 9487.383320084173, 33819.0, 34015.14962125942, 34076.0, 34095.488567671506, 34124.14689632754, 3304.7851889627095, 3406.9488957665653, 3421.640727723578, 3456.4642480415578, 3466.60134084581, 26206.606900896502, 26388.340291492674, 26427.075052373075, 26561.468770624673, 27012.864935675392, 3314.7182489488423, 3499.91250218744, 3500.492428597839, 3502.474628805674, 3641.0, 220714.0, 221553.89223053944, 221735.0, 221776.0, 222039.0, 46013.0, 46821.5317846824, 48799.512004880205, 48838.26742598864, 49357.25964110541, 51509.48490515122, 52962.47037529652, 52997.47002530003, 57312.0, 57364.139537906965, 11752.294862308288, 11867.525298988117, 11881.762364752656, 11890.405479726152, 11895.643130706092, 8789.516576588221, 8980.191782739454, 8982.416142950819, 8982.910170898338, 8984.146506081912, 14226.146431213952, 14344.282785860874, 14368.784468232985, 14393.136411815116, 14527.87650655093, 5991.880162396727, 6015.879682406327, 6113.41922517369, 6164.784232551929, 6169.290531588925, 776.9805754856174, 785.5679376342988, 787.5786454246951, 793.6904607203195, 800.4796882026612, 24160.395990100038, 24838.627420588706, 25185.0, 25215.243542693748, 25256.242312730647, 895.6551727584927, 938.1369140390857, 938.7653086728318, 953.3469573342283, 962.225408546113, 13129.146605470614, 13428.597142085753, 13510.594682159552, 13771.311434428238, 13779.311034448237, 253728.0, 254273.0, 256137.0, 256163.0, 256360.0, 42028.78985605053, 42032.0, 42114.0, 42153.156936861094, 42175.57824421778, 1094.9178811589163, 1159.2641366375515, 1163.2555164694602, 1173.178774857603, 1195.4082729049176, 22730.43173920665, 22892.198773042863, 24113.51772964566, 24116.0, 24197.0, 36005.0, 36506.0, 36525.63474365223, 36550.0, 36554.903352899455, 213749.0, 213879.0, 214203.0, 214658.0, 215028.0, 29518.557221641262, 30502.542461863533, 30608.693913060586, 30633.693663063084, 30673.386532269677, 34001.82999084989, 34052.65947340495, 34754.0, 34859.128521786144, 34874.0, 29033.85483072571, 29940.251493712833, 30028.699713002592, 30342.0, 31292.374152516368, 29256.853715731286, 29558.852205738833, 30292.84853575718, 30317.090487285415, 30410.087697369116, 29199.006442133046, 30127.397452051275, 30256.243593910327, 30301.696983029888, 30332.393352133276, 23099.99700038984, 23508.119350452263, 26219.73780262173, 26757.464850703265, 27121.372717637005, 21174.258900938392, 21802.781972180073, 21949.561008780056, 22068.558628827013, 22109.0], "value": 16048.611788900125, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [360.5547149270652, 384.23728898137176, 389.1419420178507, 396.4825902197632, 417.7410005796407, 11834.526618935242, 12082.275063496185, 12727.61817145486, 16715.91642041788, 17559.91220043898, 15416.460423885166, 15574.221288935565, 15651.45219917303, 15708.21458927052, 15760.763588546153, 4281.7216880902715, 4454.042380888109, 4478.440194975628, 4560.954390456086, 4563.977180114095, 3280.0979730574163, 4482.394876691654, 4517.41273634427, 4518.864434066983, 4524.140413321475, 3391.7456190785665, 3419.042668052949, 3826.9284600311817, 5906.173135760997, 6000.639961602296, 5972.343042265347, 5977.2229610150835, 5978.282606087254, 5978.9701051494685, 5984.910226346565, 5253.395859476171, 5378.435264297271, 5401.351837779453, 5413.242146099549, 5415.593830462731, 11946.283223006647, 12002.51989920402, 12034.27794332334, 12104.878951210463, 12118.091143164298, 1989.8806071635747, 2060.0832629479887, 2105.6841473778904, 2112.57748450309, 2155.7844215578475, 8223.876641850376, 8582.382373376118, 8872.334574906909, 8926.642934282621, 9060.184583387461, 1652.2977734462804, 1697.6010637500178, 1703.6337187504746, 1712.922918468672, 1733.298014304209, 71205.64397178033, 73495.0, 73737.63131184364, 74559.62720186364, 76850.0, 10465.47672616366, 10640.308379955279, 10824.512896919658, 10828.566857325619, 10865.728356791144, 15143.167125808186, 15151.924240378728, 15166.848331516765, 15173.317200725995, 15181.468648597245, 6587.604743715392, 6740.0, 6911.723531058802, 6964.93035069653, 6973.651317434108, 6007.399260074045, 6071.0, 6167.599106058092, 6194.783182588588, 6248.281447633581, 4008.2785098682516, 4094.6928980326593, 4421.7346959181905, 4518.751468669189, 4783.04339132168, 1914.7510823592843, 1937.350987419202, 1963.5385684364162, 1967.9016049197483, 2008.9095990680162, 765.2500549461558, 792.6036981509245, 797.2864286463582, 814.9429539932266, 824.9092599814015, 16889.577760556083, 17312.0, 17422.0, 17470.563235919202, 17753.11234438273, 764.2930289482207, 794.3287921706149, 805.8630032894422, 809.3161278719473, 811.9797005074802, 9110.453372797654, 9667.613295468243, 9765.853512197322, 9773.21814254858, 9861.40831550095, 459292.0, 467095.0, 469756.0, 476289.0, 477086.0, 56505.43494565002, 84071.5796421014, 84317.57841210754, 86237.5688121568, 87184.0, 908.4140729229609, 925.4817302310728, 931.6552875435997, 932.5896605493425, 932.7947851472666, 14552.17192136836, 14786.408543658134, 15080.773788393402, 15262.313195905988, 15277.465288714842, 35990.8200458996, 36290.552397522195, 36334.0, 36679.633203667625, 37478.25043499169, 49179.508204917496, 49762.0, 49814.25278620896, 50093.99812003812, 50096.74951625219, 6812.182538095461, 7042.577445353295, 7120.0, 7160.498765086394, 7541.2458754124145, 8734.344924130843, 11751.529938802694, 12088.15382923187, 12145.939270303592, 12508.061895358067, 9805.55874985613, 9953.502324883582, 9975.950120249352, 10212.744681382728, 10561.894381056398, 9636.951815240878, 9794.902050979399, 9883.901160988586, 9984.251181161297, 10080.596776128872, 10222.693319200436, 10277.897221027693, 10315.587376505156, 10406.0, 10509.369437833755, 6902.551334163263, 6915.343042410963, 6954.130733658293, 7012.859742805217, 7088.503804733619, 8063.83872322562, 9993.700188994051, 10071.143952764098, 10372.688819335432, 10408.323458975143], "value": 7917.653300592737, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1334.8531661517231, 1341.7651910915588, 1351.5404762380788, 1352.770029095054, 1362.8092067110613, 7424.814379640505, 7848.018997625297, 7877.842443151134, 7881.054273487182, 7895.763127106189, 7387.667554960026, 7695.422843286762, 7749.031371078615, 7903.8419231615335, 7920.881186782203, 2015.556577552936, 2050.917963281467, 2060.268604645344, 2063.814256716895, 2064.762552306489, 1800.4598620413824, 2127.6595744680853, 2173.0655817998254, 2174.4672555223915, 2179.967300490494, 1829.8902065876023, 2021.6967454881737, 2063.8039386258283, 2066.3284432559403, 2102.1906565972135, 1976.318170231268, 1978.940631781049, 1981.1679094780227, 1989.1446677928484, 1991.3030439346257, 1767.5139336682387, 1999.2502811445709, 2083.958320833575, 2084.8436367272366, 2085.35354040248, 6732.0, 7372.336489715952, 7641.694332226704, 7660.080790305144, 7856.25365590268, 4560.156371071363, 4701.95298047022, 4706.129366067288, 4707.788149533245, 4709.458412282597, 2667.6532050833266, 2773.55623100303, 2828.476731804623, 2831.0940499040316, 2842.6304580404417, 1090.514720949178, 1090.7545802194443, 1130.4226363890343, 1150.3270586706703, 1151.2229245259416, 10357.171426285955, 10423.5830566778, 10447.634332798392, 10463.215258855616, 10492.527836247311, 3816.3321418751607, 3955.248502784519, 4023.959760402417, 4025.5370632377067, 4046.433499310099, 8341.83316333682, 8350.874736878952, 8358.247757701836, 8363.080061193265, 8372.120927302667, 4564.42944631921, 4580.97709511457, 4581.037982023746, 4581.037982023812, 4583.243764778841, 4091.97954010234, 4122.299209134454, 4126.3397856343445, 4132.710710250253, 4134.545200027995, 3303.7191838693498, 3350.9664903350654, 3419.777714448553, 3456.9654303456646, 3480.7215422766626, 1057.5029736024057, 1069.9572017119228, 1085.7122862441458, 1087.206339372247, 1090.8200146975819, 780.9804754881173, 790.5375355417145, 795.129333379948, 798.6964953317702, 802.4864086984335, 4587.541245875453, 4826.348442960226, 4833.782479788418, 4878.170710979142, 5116.590672746172, 423.9364095385717, 433.91755566442276, 457.259240031145, 464.9767511624405, 472.83687127940806, 2950.3804201118005, 3007.6691563927952, 3192.74458043356, 3222.8388580570877, 3335.4329763940186, 8131.512109273344, 8305.418620696493, 9312.394694344846, 9327.906720932839, 9535.189508891823, 9624.51877406141, 9639.084286992724, 9645.08371704687, 9645.662401815904, 9647.276454265817, 7020.9648951755935, 7398.297161769731, 7424.220456852061, 7425.85148297031, 7462.664180111908, 4837.516248375135, 4906.509349065065, 4992.375953005874, 5010.899782004267, 5042.873928151826, 8297.25324720778, 8539.487630742175, 8634.352423568133, 8645.697400591202, 8766.912330876865, 8656.177663121993, 8823.117688231125, 8883.73348799537, 8885.600147993486, 8898.644054237759, 6518.902216466662, 6652.73389064432, 6659.900101498578, 6665.666716664245, 6667.633280169637, 7279.417646588367, 7288.744893928899, 7318.231585683639, 7382.5570465772225, 7408.259174082548, 6517.576357536745, 6546.8363290918105, 6558.967205163944, 6606.966965165336, 6758.966205168943, 6583.604983700993, 6617.834554136185, 6651.43462805667, 6652.7006284716335, 6657.73369065232, 5491.36849262324, 5596.1605759136455, 5610.6072574921, 5623.578231632726, 5627.803026894039, 4259.42497762798, 4277.615014648696, 4297.0, 4351.456067991501, 4419.933700994423, 4193.329067349209, 4197.433346498306, 4258.61672449481, 4329.653627709713, 4413.095315460387], "value": 3910.267364882813, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [344.3973047167457, 365.5083912138175, 371.25933762144524, 372.43761919501554, 372.75957007730034, 4917.434495033072, 4917.975410122949, 4957.851264462063, 4970.801167953282, 4985.875353116174, 4649.232876575369, 4799.112164249607, 4819.590334821544, 4839.491853355391, 4919.778609962551, 3825.2158307546956, 3826.1773718650484, 3839.6544311012144, 3855.5373355197416, 3873.5158105236846, 3846.9807650961707, 3852.3451013327804, 3853.1715681128553, 3857.32496813058, 3857.864974725885, 3622.5834029086727, 3820.5033345665024, 3823.885283441501, 3824.521934758155, 3843.3466310727104, 3791.241751649681, 3797.9555622204116, 3802.144517483558, 3828.55971563271, 3834.060655139481, 3345.8494367753265, 3355.328934213167, 3355.748318876094, 3363.7813542119684, 3367.107716455141, 4724.362211101492, 4725.4565724941385, 4764.2615394613895, 4768.856934291976, 4774.283857421415, 4172.0, 4269.295566231568, 4315.654747620183, 4349.565043495657, 4357.281048626973, 5029.2958985742025, 5054.418741844662, 5067.807281412724, 5084.567811736007, 5085.330201644167, 3626.546681664792, 3634.0551456621206, 3657.762245454037, 3661.9816900915325, 3671.494687178271, 4667.506397952657, 5017.84946451607, 5193.506616871391, 5315.441878602768, 5392.865178370533, 3694.131879008431, 3697.371446854041, 3697.7781333120097, 3700.1119731264303, 3713.962860371416, 3739.6634302912325, 3752.962470375261, 3760.8495660173835, 3761.4733937248807, 3762.8682996095004, 4280.336547835091, 4287.721298115613, 4290.442242508517, 4297.419848320436, 4298.720583162084, 4942.800453798025, 5067.721275329893, 5116.283720279164, 5165.940982098661, 5176.948230517722, 1258.0, 1259.8488181418288, 1270.3775150176532, 1270.4092596942282, 1273.2933222061845, 815.4740192575755, 860.5998210831868, 861.638111992952, 873.8514452543083, 875.6191056890273, 4377.737335759801, 4390.341448782644, 4441.444819397575, 4472.664550158751, 4477.910441791146, 5158.896822063538, 5166.819161329336, 5171.715555644476, 5173.40505841833, 5188.521271437622, 4231.942014496376, 4416.690831641754, 4422.911541769211, 4475.6643251755595, 4479.843205487858, 2659.6143559184197, 2690.367763575558, 2708.5260079486407, 2715.592661100851, 2720.5511090669816, 4754.809807607726, 4772.5943294819635, 4792.185328494164, 4803.327534145223, 4806.182948898626, 4797.808087676523, 5070.61970352225, 5185.403678576937, 5224.738763061831, 5231.921521177185, 4901.2893130496595, 4929.278394811894, 4932.470934010468, 4947.431045429749, 4951.282064100613, 5246.350592950535, 5249.238860365149, 5251.842444726664, 5255.81744107588, 5259.973700131628, 4954.761309672582, 5176.4305926347315, 5200.425789234353, 5236.528712415901, 5261.3686357637325, 5003.324551185543, 5013.674111182761, 5014.548690617862, 5019.974900125476, 5025.3718285214345, 3737.3459644562636, 3739.0, 3749.7562658427114, 3753.343164946069, 3757.0, 5013.6490445669915, 5161.793528258828, 5225.733916302311, 5309.415964244007, 5310.362756469094, 5057.31726216956, 5065.353760027932, 5102.387713474407, 5109.284700141909, 5131.87170320745, 5127.615428842777, 5129.692218466904, 5131.384233891957, 5146.408163061295, 5149.021685879671, 4902.3872015998, 5037.798488060436, 5043.697378157322, 5083.466236045162, 5086.923696144635, 5102.566281866083, 5122.948770512247, 5149.922751158811, 5171.560417364416, 5172.71550064735, 5050.823221187241, 5060.267455573949, 5063.974680126576, 5078.365204349457, 5083.364579427572], "value": 3849.718992026913, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [644.8774732800771, 663.9037339585757, 705.6189657584904, 705.8729428702835, 722.4473277942375, 11742.29546227227, 12894.032947528925, 12936.805947910789, 12940.676483087927, 14451.710965780678, 14215.786763198561, 15119.924400377982, 15172.54482365528, 15176.165310907896, 15192.16443095632, 799.6281728996016, 812.155358427235, 840.4285086141422, 845.1041895590694, 845.9788505287386, 809.9109097999216, 832.4672209785742, 833.2251006563893, 833.3374966901328, 833.4374297349295, 862.0603542139067, 862.4307956748547, 862.7239283429305, 862.7325529086004, 862.8403745307138, 855.6620135046621, 857.6869442653418, 857.8498762716499, 857.8541647919868, 857.9828403431959, 633.6229943183845, 634.2610858350017, 634.3054355480741, 634.3783092569262, 634.933332000138, 13056.151350162208, 13092.410841512155, 13376.665583360396, 13474.932625336909, 13543.45826166952, 12248.142630015902, 12288.385580720927, 12352.44414001372, 12392.62822115338, 13147.14543554666, 10535.683929482127, 10753.03222709952, 10762.0, 10809.567617295299, 11225.158113141466, 3150.763692723055, 3180.141361832292, 3217.806931584089, 3251.64231934487, 3318.3529211803707, 60360.0, 60959.695201524155, 63039.36960630381, 63244.36755632477, 63673.68163159201, 9745.610175592898, 9842.753931151636, 9900.900990990142, 9900.950495247478, 10293.948530257301, 18311.08444577766, 18429.631407372046, 18432.63134737298, 18433.90783046076, 18441.1701473434, 6034.426729460694, 6337.081123237105, 6343.651099189497, 6382.968085159545, 6394.8721025579225, 3241.7406607471344, 3299.488579270217, 3412.129906873731, 3467.358538670354, 3492.8253587320532, 2794.483020641188, 2856.8000239983417, 2884.5528943013865, 2885.091196273167, 2886.826790392583, 498.7730582584915, 548.958828087895, 574.1903915479238, 588.3175516400954, 594.1711312718819, 589.2604780999818, 616.2327901762335, 621.8476473263988, 630.2144521216235, 643.3277225299573, 8005.599720013976, 8222.13667564909, 8328.541930193898, 8512.446690965067, 8861.689840855539, 585.5725320516048, 607.4108115128319, 607.8601921558067, 624.7969409941787, 626.7680958045553, 3652.214773823641, 3773.6599331954894, 3805.1438426353848, 3864.787436691009, 3924.744891582095, 45726.77136614296, 46065.76967115143, 56870.43129568734, 60964.0, 61099.0, 47763.76118119387, 49584.75207623939, 49640.75179624079, 49776.75111624419, 50584.49415505872, 46810.0, 47783.5221647786, 48617.27074093891, 48816.51183488191, 50290.24564631606, 8410.789730256793, 8891.688790892287, 8914.509701966454, 8925.009823280305, 8937.195652391316, 32545.0, 32939.83530082334, 33033.66966330402, 33094.2123434196, 33441.163970900925, 40045.0, 40329.39505907355, 41087.589124108374, 41164.38253426261, 42580.574194257664, 10494.68515944523, 10557.736056598647, 10620.20348473852, 10885.619003334845, 11084.722881928017, 11623.59317423886, 11692.766144677229, 11809.527618895492, 11989.280643161439, 12037.75924481523, 10684.679459616224, 10819.621313253998, 10861.51099764144, 10869.619563315246, 11203.943980280046, 10800.459977001276, 10808.56765729362, 10876.763996891405, 10914.890851091388, 10943.890561094287, 10543.630972915911, 10616.309939853885, 10709.57161713554, 10809.945950270197, 11176.10591152722, 9517.476538790434, 9612.711618651452, 9850.408975461496, 9972.252081093802, 10112.393256404344, 9114.726558203265, 9190.126937940884, 9216.170544651013, 9219.262459003397, 9257.444553326823], "value": 6161.885260259443, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1565.0, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 116.0, 114.0, 116.0, 115.0, 115.0, 116.0, 115.0, 114.0, 114.0], "value": 114.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 133.0, 127.0, 171.0, 133.0, 127.0, 132.0, 126.0, 151.0, 133.0], "value": 138.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 163.0, 161.0, 164.0, 163.0, 162.0, 162.0, 164.0, 161.0, 161.0], "value": 162.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 77.0, 77.0, 113.0, 77.0, 77.0, 77.0, 77.0, 112.0, 77.0], "value": 84.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 116.0, 85.0, 84.0, 84.0, 106.0, 84.0, 85.0, 107.0], "value": 92.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 114.0, 114.0, 113.0, 113.0, 113.0, 113.0, 113.0, 113.0], "value": 113.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 149.0, 149.0, 150.0, 150.0, 149.0, 150.0, 148.0, 152.0, 149.0], "value": 149.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 96.0, 95.0], "value": 95.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 62.0, 63.0, 62.0, 62.0, 63.0, 61.0, 62.0, 63.0, 63.0], "value": 62.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 66.0, 66.0, 66.0, 66.0, 65.0, 65.0, 68.0, 65.0], "value": 65.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 88.0, 87.0, 87.0, 88.0, 88.0, 96.0, 88.0, 88.0, 88.0], "value": 88.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 137.0, 138.0, 142.0, 139.0, 138.0, 145.0, 143.0, 185.0, 136.0], "value": 144.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 180.0, 190.0, 185.0, 183.0, 182.0, 185.0, 180.0, 193.0, 184.0], "value": 184.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 69.0, 70.0, 69.0, 70.0, 68.0, 69.0, 71.0, 69.0, 68.0], "value": 69.8, "unit": "score"}]}]} 21:24:25 INFO - Return code: 0 21:24:25 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 21:24:25 ERROR - # TBPL SUCCESS # 21:24:25 INFO - Running post-action listener: _resource_record_post_action 21:24:25 INFO - [mozharness: 2017-02-21 05:24:25.575443Z] Finished run-tests step (success) 21:24:25 INFO - Running post-run listener: _resource_record_post_run 21:24:25 INFO - Total resource usage - Wall time: 1203s; CPU: 13.0%; Read bytes: 4096; Write bytes: 348819456; Read time: 132; Write time: 187856 21:24:25 INFO - TinderboxPrint: CPU usage
13.1% 21:24:25 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 132 21:24:25 INFO - TinderboxPrint: I/O write bytes / time
348,819,456 / 187,856 21:24:25 INFO - TinderboxPrint: CPU idle
8,340.6 (86.9%) 21:24:25 INFO - TinderboxPrint: CPU user
1,217.0 (12.7%) 21:24:25 INFO - TinderboxPrint: Swap in / out
0 / 0 21:24:25 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 105586688; Read time: 0; Write time: 81412 21:24:25 INFO - run-tests - Wall time: 1188s; CPU: 13.0%; Read bytes: 4096; Write bytes: 243232768; Read time: 132; Write time: 106444 21:24:26 INFO - Running post-run listener: _upload_blobber_files 21:24:26 INFO - Blob upload gear active. 21:24:26 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 21:24:26 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 21:24:26 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-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 21:24:26 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-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 21:24:26 INFO - (blobuploader) - INFO - Open directory for files ... 21:24:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 21:24:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:24:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:24:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 21:24:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:24:27 INFO - (blobuploader) - INFO - Done attempting. 21:24:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 21:24:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:24:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:24:28 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 21:24:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:24:28 INFO - (blobuploader) - INFO - Done attempting. 21:24:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 21:24:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:24:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:24:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 21:24:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:24:29 INFO - (blobuploader) - INFO - Done attempting. 21:24:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 21:24:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:24:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:24:32 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 21:24:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:24:32 INFO - (blobuploader) - INFO - Done attempting. 21:24:32 INFO - (blobuploader) - INFO - Iteration through files over. 21:24:32 INFO - Return code: 0 21:24:32 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 21:24:32 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 21:24:32 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a4cf64f3fad40600503918be8785ad15c6f61125de4b811a2b85c757985e1b68befe4072da19e5e8ac6e861d8c831d11b7f265d04dd646c5bfba79022b4ee563", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8a22ac31677e7047bf9a6761113bdc2f0192229d798af60f0655faab7a992b89e6e5291a64f3189d346dd7a06b93e093ed04177bffcaf536350e390215b39d7b", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1e9c00c538121665e045a546d5993cb3034dbc52da09293c7977a845be3475a04fc0b2b7f1ef0b800ef01cfb1fbc473349ca02355a929040dc59f795a5501e79", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/940f65d7bea57d171d2c72e325d0ba6814000fec619bfc0a0c47ea6f5ce28f2af1ac60ee78e5ccd60e9c890348e7de5282abf6feae568fb90a9dcd6c7f7b09e7"} 21:24:32 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 21:24:32 INFO - Writing to file /builds/slave/test/properties/blobber_files 21:24:32 INFO - Contents: 21:24:32 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a4cf64f3fad40600503918be8785ad15c6f61125de4b811a2b85c757985e1b68befe4072da19e5e8ac6e861d8c831d11b7f265d04dd646c5bfba79022b4ee563", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8a22ac31677e7047bf9a6761113bdc2f0192229d798af60f0655faab7a992b89e6e5291a64f3189d346dd7a06b93e093ed04177bffcaf536350e390215b39d7b", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1e9c00c538121665e045a546d5993cb3034dbc52da09293c7977a845be3475a04fc0b2b7f1ef0b800ef01cfb1fbc473349ca02355a929040dc59f795a5501e79", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/940f65d7bea57d171d2c72e325d0ba6814000fec619bfc0a0c47ea6f5ce28f2af1ac60ee78e5ccd60e9c890348e7de5282abf6feae568fb90a9dcd6c7f7b09e7"} 21:24:32 INFO - Running post-run listener: copy_logs_to_upload_dir 21:24:32 INFO - Copying logs to upload dir... 21:24:32 INFO - mkdir: /builds/slave/test/build/upload/logs 21:24:32 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1261.661109 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2017-02-20 21:24:31.868258) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-20 21:24:31.872271) ========= 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=3297cc2eebb5444a7688c33f000001b5-1487647778.468706-1683341729 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-Non-PGO/sha512/a4cf64f3fad40600503918be8785ad15c6f61125de4b811a2b85c757985e1b68befe4072da19e5e8ac6e861d8c831d11b7f265d04dd646c5bfba79022b4ee563", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8a22ac31677e7047bf9a6761113bdc2f0192229d798af60f0655faab7a992b89e6e5291a64f3189d346dd7a06b93e093ed04177bffcaf536350e390215b39d7b", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1e9c00c538121665e045a546d5993cb3034dbc52da09293c7977a845be3475a04fc0b2b7f1ef0b800ef01cfb1fbc473349ca02355a929040dc59f795a5501e79", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/940f65d7bea57d171d2c72e325d0ba6814000fec619bfc0a0c47ea6f5ce28f2af1ac60ee78e5ccd60e9c890348e7de5282abf6feae568fb90a9dcd6c7f7b09e7"} build_url:https://queue.taskcluster.net/v1/task/A7KAzjtDTZyOvV4GAQQfdA/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011206 build_url: 'https://queue.taskcluster.net/v1/task/A7KAzjtDTZyOvV4GAQQfdA/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a4cf64f3fad40600503918be8785ad15c6f61125de4b811a2b85c757985e1b68befe4072da19e5e8ac6e861d8c831d11b7f265d04dd646c5bfba79022b4ee563", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8a22ac31677e7047bf9a6761113bdc2f0192229d798af60f0655faab7a992b89e6e5291a64f3189d346dd7a06b93e093ed04177bffcaf536350e390215b39d7b", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1e9c00c538121665e045a546d5993cb3034dbc52da09293c7977a845be3475a04fc0b2b7f1ef0b800ef01cfb1fbc473349ca02355a929040dc59f795a5501e79", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/940f65d7bea57d171d2c72e325d0ba6814000fec619bfc0a0c47ea6f5ce28f2af1ac60ee78e5ccd60e9c890348e7de5282abf6feae568fb90a9dcd6c7f7b09e7"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-20 21:24:31.896952) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-20 21:24:31.897272) ========= 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=3297cc2eebb5444a7688c33f000001b5-1487647778.468706-1683341729 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004478 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-20 21:24:31.981673) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-20 21:24:31.981991) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-20 21:24:31.982274) ========= ========= Total master_lag: 0.21 =========